builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1464785920.84 results: success (0) buildid: 20160601030219 builduid: 84d422cee1bb4a57b3424d8bc677fa0d revision: 25321494921c824703a605127fb1f99b1faf5910 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.835990) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.836442) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.836736) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.852093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.852378) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1464785920.385426-1563640472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 05:58:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.7M=0s 2016-06-01 05:58:41 (70.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082034 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.949333) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.949665) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1464785920.385426-1563640472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016209 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:40.999357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 05:58:40.999746) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25321494921c824703a605127fb1f99b1faf5910 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 25321494921c824703a605127fb1f99b1faf5910 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1464785920.385426-1563640472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 05:58:41,216 truncating revision to first 12 chars 2016-06-01 05:58:41,216 Setting DEBUG logging. 2016-06-01 05:58:41,216 attempt 1/10 2016-06-01 05:58:41,216 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25321494921c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 05:58:42,274 unpacking tar archive at: mozilla-central-25321494921c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.408230 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 05:58:42.421289) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:42.421670) ========= script_repo_revision: 25321494921c824703a605127fb1f99b1faf5910 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:42.422076) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:42.422346) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 05:58:42.430604) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 47 secs) (at 2016-06-01 05:58:42.430914) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1464785920.385426-1563640472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:58:42 INFO - MultiFileLogger online at 20160601 05:58:42 in /builds/slave/test 05:58:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:58:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:58:42 INFO - {'append_to_log': False, 05:58:42 INFO - 'base_work_dir': '/builds/slave/test', 05:58:42 INFO - 'blob_upload_branch': 'Firefox', 05:58:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:58:42 INFO - 'branch': 'Firefox', 05:58:42 INFO - 'buildbot_json_path': 'buildprops.json', 05:58:42 INFO - 'config_files': ('talos/linux_config.py',), 05:58:42 INFO - 'default_actions': ('clobber', 05:58:42 INFO - 'read-buildbot-config', 05:58:42 INFO - 'download-and-extract', 05:58:42 INFO - 'populate-webroot', 05:58:42 INFO - 'create-virtualenv', 05:58:42 INFO - 'install', 05:58:42 INFO - 'run-tests'), 05:58:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:58:42 INFO - 'download_minidump_stackwalk': True, 05:58:42 INFO - 'download_symbols': 'ondemand', 05:58:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:58:42 INFO - 'tooltool.py': '/tools/tooltool.py', 05:58:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:58:42 INFO - '/tools/misc-python/virtualenv.py')}, 05:58:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:58:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:58:42 INFO - 'installer_path': 'installer.exe', 05:58:42 INFO - 'log_level': 'info', 05:58:42 INFO - 'log_name': 'talos', 05:58:42 INFO - 'log_to_console': True, 05:58:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:58:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:58:42 INFO - 'opt_config_files': (), 05:58:42 INFO - 'pip_index': False, 05:58:42 INFO - 'sps_profile': False, 05:58:42 INFO - 'sps_profile_interval': 0, 05:58:42 INFO - 'suite': 'dromaeojs', 05:58:42 INFO - 'system_bits': '32', 05:58:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:58:42 INFO - 'title': 'talos-linux64-ix-003', 05:58:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:58:42 INFO - 'use_talos_json': True, 05:58:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:58:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:58:42 INFO - 'work_dir': 'build'} 05:58:42 INFO - ##### 05:58:42 INFO - ##### Running clobber step. 05:58:42 INFO - ##### 05:58:42 INFO - Running pre-action listener: _resource_record_pre_action 05:58:42 INFO - Running main action method: clobber 05:58:42 INFO - rmtree: /builds/slave/test/build 05:58:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:58:43 INFO - Running post-action listener: _resource_record_post_action 05:58:43 INFO - ##### 05:58:43 INFO - ##### Finished clobber step (success) 05:58:43 INFO - ##### 05:58:43 INFO - ##### 05:58:43 INFO - ##### Running read-buildbot-config step. 05:58:43 INFO - ##### 05:58:43 INFO - Running pre-action listener: _resource_record_pre_action 05:58:43 INFO - Running main action method: read_buildbot_config 05:58:43 INFO - Using buildbot properties: 05:58:43 INFO - { 05:58:43 INFO - "project": "", 05:58:43 INFO - "product": "firefox", 05:58:43 INFO - "script_repo_revision": "production", 05:58:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:58:43 INFO - "repository": "", 05:58:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:58:43 INFO - "buildid": "20160601030219", 05:58:43 INFO - "stage_platform": "linux64-pgo", 05:58:43 INFO - "basedir": "/builds/slave/test", 05:58:43 INFO - "nightly_build": "True", 05:58:43 INFO - "buildnumber": 316, 05:58:43 INFO - "slavename": "talos-linux64-ix-003", 05:58:43 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:58:43 INFO - "platform": "ubuntu64_hw", 05:58:43 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:58:43 INFO - "branch": "mozilla-central", 05:58:43 INFO - "repo_path": "mozilla-central", 05:58:43 INFO - "revision": "25321494921c824703a605127fb1f99b1faf5910", 05:58:43 INFO - "pgo_build": "True", 05:58:43 INFO - "builduid": "84d422cee1bb4a57b3424d8bc677fa0d", 05:58:43 INFO - "slavebuilddir": "test" 05:58:43 INFO - } 05:58:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:58:43 INFO - Running post-action listener: _resource_record_post_action 05:58:43 INFO - ##### 05:58:43 INFO - ##### Finished read-buildbot-config step (success) 05:58:43 INFO - ##### 05:58:43 INFO - ##### 05:58:43 INFO - ##### Running download-and-extract step. 05:58:43 INFO - ##### 05:58:43 INFO - Running pre-action listener: _resource_record_pre_action 05:58:43 INFO - Running main action method: download_and_extract 05:58:43 INFO - mkdir: /builds/slave/test/build/tests 05:58:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:43 INFO - https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:58:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:58:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:58:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:58:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:58:43 INFO - Downloaded 1361 bytes. 05:58:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:58:43 INFO - Using the following test package requirements: 05:58:43 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:58:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:58:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:58:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'jsshell-linux-x86_64.zip'], 05:58:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:58:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:58:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:58:43 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:58:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:58:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:58:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:58:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:43 INFO - https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:58:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:58:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:58:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:58:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:58:45 INFO - Downloaded 24412883 bytes. 05:58:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:58:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:58:46 INFO - Return code: 0 05:58:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:46 INFO - https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:58:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:58:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:58:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:58:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:58:47 INFO - Downloaded 24412883 bytes. 05:58:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:58:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:58:48 INFO - Return code: 0 05:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:48 INFO - https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:58:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:58:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:58:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:58:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:58:49 INFO - Downloaded 10834055 bytes. 05:58:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:58:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:58:50 INFO - Return code: 0 05:58:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:50 INFO - https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:58:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:58:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:58:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:58:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:58:51 INFO - Downloaded 58987974 bytes. 05:58:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:58:51 INFO - mkdir: /builds/slave/test/properties 05:58:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:58:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:58:51 INFO - Contents: 05:58:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:58:52 INFO - Running post-action listener: _resource_record_post_action 05:58:52 INFO - Running post-action listener: set_extra_try_arguments 05:58:52 INFO - ##### 05:58:52 INFO - ##### Finished download-and-extract step (success) 05:58:52 INFO - ##### 05:58:52 INFO - ##### 05:58:52 INFO - ##### Running populate-webroot step. 05:58:52 INFO - ##### 05:58:52 INFO - Running pre-action listener: _resource_record_pre_action 05:58:52 INFO - Running main action method: populate_webroot 05:58:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:58:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:58:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:58:52 INFO - '--tppagecycles', 05:58:52 INFO - '7'], 05:58:52 INFO - 'tests': ['tsvgm']}}, 05:58:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:58:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:58:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:58:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:58:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:58:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:58:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['damp', 'tps']}, 05:58:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'tests': ['damp', 'tps']}, 05:58:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['dromaeo_dom']}, 05:58:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:58:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['a11yr', 05:58:52 INFO - 'ts_paint', 05:58:52 INFO - 'tpaint', 05:58:52 INFO - 'sessionrestore', 05:58:52 INFO - 'sessionrestore_no_auto_restore', 05:58:52 INFO - 'tabpaint']}, 05:58:52 INFO - 'other-e10s': {'tests': ['a11yr', 05:58:52 INFO - 'ts_paint', 05:58:52 INFO - 'tpaint', 05:58:52 INFO - 'sessionrestore', 05:58:52 INFO - 'sessionrestore_no_auto_restore', 05:58:52 INFO - 'tabpaint']}, 05:58:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:58:52 INFO - 'ts_paint', 05:58:52 INFO - 'tpaint', 05:58:52 INFO - 'sessionrestore', 05:58:52 INFO - 'sessionrestore_no_auto_restore', 05:58:52 INFO - 'tabpaint']}, 05:58:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:58:52 INFO - 'ts_paint', 05:58:52 INFO - 'tpaint', 05:58:52 INFO - 'sessionrestore', 05:58:52 INFO - 'sessionrestore_no_auto_restore', 05:58:52 INFO - 'tabpaint']}, 05:58:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['a11yr', 05:58:52 INFO - 'ts_paint', 05:58:52 INFO - 'tpaint', 05:58:52 INFO - 'sessionrestore', 05:58:52 INFO - 'sessionrestore_no_auto_restore', 05:58:52 INFO - 'tabpaint']}, 05:58:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['a11yr', 05:58:52 INFO - 'ts_paint', 05:58:52 INFO - 'tpaint', 05:58:52 INFO - 'sessionrestore', 05:58:52 INFO - 'sessionrestore_no_auto_restore', 05:58:52 INFO - 'tabpaint']}, 05:58:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['tsvgx', 05:58:52 INFO - 'tsvgr_opacity', 05:58:52 INFO - 'tart', 05:58:52 INFO - 'tscrollx', 05:58:52 INFO - 'cart']}, 05:58:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:58:52 INFO - 'tsvgr_opacity', 05:58:52 INFO - 'tart', 05:58:52 INFO - 'tscrollx', 05:58:52 INFO - 'cart']}, 05:58:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'talos_options': ['--disable-e10s'], 05:58:52 INFO - 'tests': ['tp5o']}, 05:58:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'tests': ['tp5o']}, 05:58:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'talos_options': ['--disable-e10s', 05:58:52 INFO - '--xperf_path', 05:58:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:58:52 INFO - 'tests': ['tp5n']}, 05:58:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:52 INFO - 'talos_options': ['--xperf_path', 05:58:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:58:52 INFO - 'tests': ['tp5n']}}, 05:58:52 INFO - 'talos.zip': {'path': '', 05:58:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:58:52 INFO - Running post-action listener: _resource_record_post_action 05:58:52 INFO - ##### 05:58:52 INFO - ##### Finished populate-webroot step (success) 05:58:52 INFO - ##### 05:58:52 INFO - ##### 05:58:52 INFO - ##### Running create-virtualenv step. 05:58:52 INFO - ##### 05:58:52 INFO - Running pre-action listener: _resource_record_pre_action 05:58:52 INFO - Running main action method: create_virtualenv 05:58:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:58:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:58:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:58:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:58:52 INFO - Using real prefix '/usr' 05:58:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:58:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:58:54 INFO - Installing pip.................done. 05:58:54 INFO - Return code: 0 05:58:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:58:54 INFO - Using env: {'DISPLAY': ':0', 05:58:54 INFO - 'HOME': '/home/cltbld', 05:58:54 INFO - 'LANG': 'en_US.UTF-8', 05:58:54 INFO - 'LANGUAGE': 'en_US:en', 05:58:54 INFO - 'LOGNAME': 'cltbld', 05:58:54 INFO - 'MAIL': '/var/mail/cltbld', 05:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:54 INFO - 'MOZ_NO_REMOTE': '1', 05:58:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:54 INFO - 'NO_EM_RESTART': '1', 05:58:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:54 INFO - 'PWD': '/builds/slave/test', 05:58:54 INFO - 'SHELL': '/bin/bash', 05:58:54 INFO - 'SHLVL': '1', 05:58:54 INFO - 'TERM': 'linux', 05:58:54 INFO - 'TMOUT': '86400', 05:58:54 INFO - 'USER': 'cltbld', 05:58:54 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:54 INFO - '_': '/tools/buildbot/bin/python'} 05:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:58:55 INFO - Cleaning up... 05:58:55 INFO - Return code: 0 05:58:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:58:55 INFO - Using env: {'DISPLAY': ':0', 05:58:55 INFO - 'HOME': '/home/cltbld', 05:58:55 INFO - 'LANG': 'en_US.UTF-8', 05:58:55 INFO - 'LANGUAGE': 'en_US:en', 05:58:55 INFO - 'LOGNAME': 'cltbld', 05:58:55 INFO - 'MAIL': '/var/mail/cltbld', 05:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:55 INFO - 'MOZ_NO_REMOTE': '1', 05:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:55 INFO - 'NO_EM_RESTART': '1', 05:58:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:55 INFO - 'PWD': '/builds/slave/test', 05:58:55 INFO - 'SHELL': '/bin/bash', 05:58:55 INFO - 'SHLVL': '1', 05:58:55 INFO - 'TERM': 'linux', 05:58:55 INFO - 'TMOUT': '86400', 05:58:55 INFO - 'USER': 'cltbld', 05:58:55 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:55 INFO - '_': '/tools/buildbot/bin/python'} 05:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:55 INFO - Downloading/unpacking psutil>=3.1.1 05:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:58:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:57 INFO - Installing collected packages: psutil 05:58:57 INFO - Running setup.py install for psutil 05:58:57 INFO - building 'psutil._psutil_linux' extension 05:58:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:58:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:58:57 INFO - building 'psutil._psutil_posix' extension 05:58:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:58:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:58:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:57 INFO - Successfully installed psutil 05:58:57 INFO - Cleaning up... 05:58:57 INFO - Return code: 0 05:58:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:58:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:58:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:58:57 INFO - Using env: {'DISPLAY': ':0', 05:58:57 INFO - 'HOME': '/home/cltbld', 05:58:57 INFO - 'LANG': 'en_US.UTF-8', 05:58:57 INFO - 'LANGUAGE': 'en_US:en', 05:58:57 INFO - 'LOGNAME': 'cltbld', 05:58:57 INFO - 'MAIL': '/var/mail/cltbld', 05:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:57 INFO - 'MOZ_NO_REMOTE': '1', 05:58:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:57 INFO - 'NO_EM_RESTART': '1', 05:58:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:57 INFO - 'PWD': '/builds/slave/test', 05:58:57 INFO - 'SHELL': '/bin/bash', 05:58:57 INFO - 'SHLVL': '1', 05:58:57 INFO - 'TERM': 'linux', 05:58:57 INFO - 'TMOUT': '86400', 05:58:57 INFO - 'USER': 'cltbld', 05:58:57 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:57 INFO - '_': '/tools/buildbot/bin/python'} 05:58:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:59:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:59:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:59:00 INFO - Installing collected packages: mozsystemmonitor 05:59:00 INFO - Running setup.py install for mozsystemmonitor 05:59:00 INFO - Successfully installed mozsystemmonitor 05:59:00 INFO - Cleaning up... 05:59:00 INFO - Return code: 0 05:59:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:59:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:59:00 INFO - Using env: {'DISPLAY': ':0', 05:59:00 INFO - 'HOME': '/home/cltbld', 05:59:00 INFO - 'LANG': 'en_US.UTF-8', 05:59:00 INFO - 'LANGUAGE': 'en_US:en', 05:59:00 INFO - 'LOGNAME': 'cltbld', 05:59:00 INFO - 'MAIL': '/var/mail/cltbld', 05:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:00 INFO - 'MOZ_NO_REMOTE': '1', 05:59:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:00 INFO - 'NO_EM_RESTART': '1', 05:59:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:00 INFO - 'PWD': '/builds/slave/test', 05:59:00 INFO - 'SHELL': '/bin/bash', 05:59:00 INFO - 'SHLVL': '1', 05:59:00 INFO - 'TERM': 'linux', 05:59:00 INFO - 'TMOUT': '86400', 05:59:00 INFO - 'USER': 'cltbld', 05:59:00 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:00 INFO - '_': '/tools/buildbot/bin/python'} 05:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:00 INFO - Downloading/unpacking blobuploader==1.2.4 05:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:02 INFO - Downloading blobuploader-1.2.4.tar.gz 05:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:59:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:59:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:03 INFO - Downloading docopt-0.6.1.tar.gz 05:59:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:59:03 INFO - Installing collected packages: blobuploader, requests, docopt 05:59:03 INFO - Running setup.py install for blobuploader 05:59:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:59:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:59:03 INFO - Running setup.py install for requests 05:59:03 INFO - Running setup.py install for docopt 05:59:03 INFO - Successfully installed blobuploader requests docopt 05:59:03 INFO - Cleaning up... 05:59:03 INFO - Return code: 0 05:59:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:59:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:03 INFO - Using env: {'DISPLAY': ':0', 05:59:03 INFO - 'HOME': '/home/cltbld', 05:59:03 INFO - 'LANG': 'en_US.UTF-8', 05:59:03 INFO - 'LANGUAGE': 'en_US:en', 05:59:03 INFO - 'LOGNAME': 'cltbld', 05:59:03 INFO - 'MAIL': '/var/mail/cltbld', 05:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:03 INFO - 'MOZ_NO_REMOTE': '1', 05:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:03 INFO - 'NO_EM_RESTART': '1', 05:59:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:03 INFO - 'PWD': '/builds/slave/test', 05:59:03 INFO - 'SHELL': '/bin/bash', 05:59:03 INFO - 'SHLVL': '1', 05:59:03 INFO - 'TERM': 'linux', 05:59:03 INFO - 'TMOUT': '86400', 05:59:03 INFO - 'USER': 'cltbld', 05:59:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:03 INFO - '_': '/tools/buildbot/bin/python'} 05:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:03 INFO - Running setup.py (path:/tmp/pip-piBW_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:04 INFO - Running setup.py (path:/tmp/pip-b2cP14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:04 INFO - Running setup.py (path:/tmp/pip-lVtGkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:04 INFO - Running setup.py (path:/tmp/pip-O4iw0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:04 INFO - Running setup.py (path:/tmp/pip-lDkWV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:04 INFO - Running setup.py (path:/tmp/pip-ZEGAQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:04 INFO - Running setup.py (path:/tmp/pip-QH089E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:04 INFO - Running setup.py (path:/tmp/pip-4eVhi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:04 INFO - Running setup.py (path:/tmp/pip-bChKG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:04 INFO - Running setup.py (path:/tmp/pip-v224M4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:04 INFO - Running setup.py (path:/tmp/pip-edW68_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:04 INFO - Running setup.py (path:/tmp/pip-uFkkHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:04 INFO - Running setup.py (path:/tmp/pip-mgycit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:04 INFO - Running setup.py (path:/tmp/pip-4LOQLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:05 INFO - Running setup.py (path:/tmp/pip-hBcd6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:05 INFO - Running setup.py (path:/tmp/pip-lYyHFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:05 INFO - Running setup.py (path:/tmp/pip-uJ_pTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:59:05 INFO - Running setup.py install for manifestparser 05:59:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:59:05 INFO - Running setup.py install for mozcrash 05:59:05 INFO - Running setup.py install for mozdebug 05:59:05 INFO - Running setup.py install for mozdevice 05:59:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:59:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:59:05 INFO - Running setup.py install for mozfile 05:59:05 INFO - Running setup.py install for mozhttpd 05:59:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:59:05 INFO - Running setup.py install for mozinfo 05:59:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:59:05 INFO - Running setup.py install for mozInstall 05:59:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Running setup.py install for mozleak 05:59:06 INFO - Running setup.py install for mozlog 05:59:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Running setup.py install for moznetwork 05:59:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Running setup.py install for mozprocess 05:59:06 INFO - Running setup.py install for mozprofile 05:59:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Running setup.py install for mozrunner 05:59:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:59:06 INFO - Running setup.py install for mozscreenshot 05:59:06 INFO - Running setup.py install for moztest 05:59:06 INFO - Running setup.py install for mozversion 05:59:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:59:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:59:07 INFO - Cleaning up... 05:59:07 INFO - Return code: 0 05:59:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:07 INFO - Using env: {'DISPLAY': ':0', 05:59:07 INFO - 'HOME': '/home/cltbld', 05:59:07 INFO - 'LANG': 'en_US.UTF-8', 05:59:07 INFO - 'LANGUAGE': 'en_US:en', 05:59:07 INFO - 'LOGNAME': 'cltbld', 05:59:07 INFO - 'MAIL': '/var/mail/cltbld', 05:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:07 INFO - 'MOZ_NO_REMOTE': '1', 05:59:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:07 INFO - 'NO_EM_RESTART': '1', 05:59:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:07 INFO - 'PWD': '/builds/slave/test', 05:59:07 INFO - 'SHELL': '/bin/bash', 05:59:07 INFO - 'SHLVL': '1', 05:59:07 INFO - 'TERM': 'linux', 05:59:07 INFO - 'TMOUT': '86400', 05:59:07 INFO - 'USER': 'cltbld', 05:59:07 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:07 INFO - '_': '/tools/buildbot/bin/python'} 05:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:07 INFO - Running setup.py (path:/tmp/pip-W6rAgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:07 INFO - Running setup.py (path:/tmp/pip-CO4012-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:07 INFO - Running setup.py (path:/tmp/pip-a1kcSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:07 INFO - Running setup.py (path:/tmp/pip-Mkx2mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:07 INFO - Running setup.py (path:/tmp/pip-ZPTJpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:07 INFO - Running setup.py (path:/tmp/pip-Vwx0Ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:07 INFO - Running setup.py (path:/tmp/pip-MO8b6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:07 INFO - Running setup.py (path:/tmp/pip-Soq09O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:07 INFO - Running setup.py (path:/tmp/pip-4ZVWk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:07 INFO - Running setup.py (path:/tmp/pip-DNR2c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:07 INFO - Running setup.py (path:/tmp/pip-MeqWmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:08 INFO - Running setup.py (path:/tmp/pip-7nXCy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:08 INFO - Running setup.py (path:/tmp/pip-Fu54oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:08 INFO - Running setup.py (path:/tmp/pip-5TU6sC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:08 INFO - Running setup.py (path:/tmp/pip-ywinj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:08 INFO - Running setup.py (path:/tmp/pip-7gIpEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:08 INFO - Running setup.py (path:/tmp/pip-GsjNEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:10 INFO - Downloading blessings-1.6.tar.gz 05:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:59:10 INFO - Installing collected packages: blessings 05:59:10 INFO - Running setup.py install for blessings 05:59:10 INFO - Successfully installed blessings 05:59:10 INFO - Cleaning up... 05:59:10 INFO - Return code: 0 05:59:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:59:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:10 INFO - Reading from file tmpfile_stdout 05:59:10 INFO - Current package versions: 05:59:10 INFO - argparse == 1.2.1 05:59:10 INFO - blessings == 1.6 05:59:10 INFO - blobuploader == 1.2.4 05:59:10 INFO - docopt == 0.6.1 05:59:10 INFO - manifestparser == 1.1 05:59:10 INFO - mozInstall == 1.12 05:59:10 INFO - mozcrash == 0.17 05:59:10 INFO - mozdebug == 0.1 05:59:10 INFO - mozdevice == 0.48 05:59:10 INFO - mozfile == 1.2 05:59:10 INFO - mozhttpd == 0.7 05:59:10 INFO - mozinfo == 0.9 05:59:10 INFO - mozleak == 0.1 05:59:10 INFO - mozlog == 3.2 05:59:10 INFO - moznetwork == 0.27 05:59:10 INFO - mozprocess == 0.22 05:59:10 INFO - mozprofile == 0.28 05:59:10 INFO - mozrunner == 6.11 05:59:10 INFO - mozscreenshot == 0.1 05:59:10 INFO - mozsystemmonitor == 0.3 05:59:10 INFO - moztest == 0.7 05:59:10 INFO - mozversion == 1.4 05:59:10 INFO - psutil == 3.1.1 05:59:10 INFO - requests == 1.2.3 05:59:10 INFO - wsgiref == 0.1.2 05:59:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:59:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:10 INFO - Using env: {'DISPLAY': ':0', 05:59:10 INFO - 'HOME': '/home/cltbld', 05:59:10 INFO - 'LANG': 'en_US.UTF-8', 05:59:10 INFO - 'LANGUAGE': 'en_US:en', 05:59:10 INFO - 'LOGNAME': 'cltbld', 05:59:10 INFO - 'MAIL': '/var/mail/cltbld', 05:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:10 INFO - 'MOZ_NO_REMOTE': '1', 05:59:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:10 INFO - 'NO_EM_RESTART': '1', 05:59:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:10 INFO - 'PWD': '/builds/slave/test', 05:59:10 INFO - 'SHELL': '/bin/bash', 05:59:10 INFO - 'SHLVL': '1', 05:59:10 INFO - 'TERM': 'linux', 05:59:10 INFO - 'TMOUT': '86400', 05:59:10 INFO - 'USER': 'cltbld', 05:59:10 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:10 INFO - '_': '/tools/buildbot/bin/python'} 05:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:59:10 INFO - Cleaning up... 05:59:10 INFO - Return code: 0 05:59:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:59:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:59:10 INFO - Using env: {'DISPLAY': ':0', 05:59:10 INFO - 'HOME': '/home/cltbld', 05:59:10 INFO - 'LANG': 'en_US.UTF-8', 05:59:10 INFO - 'LANGUAGE': 'en_US:en', 05:59:10 INFO - 'LOGNAME': 'cltbld', 05:59:10 INFO - 'MAIL': '/var/mail/cltbld', 05:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:10 INFO - 'MOZ_NO_REMOTE': '1', 05:59:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:10 INFO - 'NO_EM_RESTART': '1', 05:59:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:10 INFO - 'PWD': '/builds/slave/test', 05:59:10 INFO - 'SHELL': '/bin/bash', 05:59:10 INFO - 'SHLVL': '1', 05:59:10 INFO - 'TERM': 'linux', 05:59:10 INFO - 'TMOUT': '86400', 05:59:10 INFO - 'USER': 'cltbld', 05:59:10 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:10 INFO - '_': '/tools/buildbot/bin/python'} 05:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:11 INFO - Downloading/unpacking jsonschema 05:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:59:13 INFO - Downloading/unpacking functools32 (from jsonschema) 05:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:13 INFO - Downloading functools32-3.2.3-2.tar.gz 05:59:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:59:13 INFO - warning: no files found matching '*.txt' 05:59:13 INFO - no previously-included directories found matching 'build' 05:59:13 INFO - no previously-included directories found matching 'dist' 05:59:13 INFO - no previously-included directories found matching '.git*' 05:59:13 INFO - Installing collected packages: jsonschema, functools32 05:59:13 INFO - Running setup.py install for functools32 05:59:13 INFO - warning: no files found matching '*.txt' 05:59:13 INFO - no previously-included directories found matching 'build' 05:59:13 INFO - no previously-included directories found matching 'dist' 05:59:13 INFO - no previously-included directories found matching '.git*' 05:59:13 INFO - Successfully installed jsonschema functools32 05:59:13 INFO - Cleaning up... 05:59:13 INFO - Return code: 0 05:59:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa679bacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:59:13 INFO - Using env: {'DISPLAY': ':0', 05:59:13 INFO - 'HOME': '/home/cltbld', 05:59:13 INFO - 'LANG': 'en_US.UTF-8', 05:59:13 INFO - 'LANGUAGE': 'en_US:en', 05:59:13 INFO - 'LOGNAME': 'cltbld', 05:59:13 INFO - 'MAIL': '/var/mail/cltbld', 05:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:13 INFO - 'MOZ_NO_REMOTE': '1', 05:59:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:13 INFO - 'NO_EM_RESTART': '1', 05:59:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:13 INFO - 'PWD': '/builds/slave/test', 05:59:13 INFO - 'SHELL': '/bin/bash', 05:59:13 INFO - 'SHLVL': '1', 05:59:13 INFO - 'TERM': 'linux', 05:59:13 INFO - 'TMOUT': '86400', 05:59:13 INFO - 'USER': 'cltbld', 05:59:13 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:13 INFO - '_': '/tools/buildbot/bin/python'} 05:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:13 INFO - Downloading/unpacking flake8 05:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:59:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:59:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:59:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:59:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:59:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:59:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:59:16 INFO - no previously-included directories found matching 'docs/_build' 05:59:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:59:16 INFO - Running setup.py install for pep8 05:59:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:59:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:59:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:59:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:59:16 INFO - no previously-included directories found matching 'docs/_build' 05:59:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:59:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:59:16 INFO - Cleaning up... 05:59:16 INFO - Return code: 0 05:59:16 INFO - Running post-action listener: _resource_record_post_action 05:59:16 INFO - Running post-action listener: _start_resource_monitoring 05:59:16 INFO - Starting resource monitoring. 05:59:16 INFO - ##### 05:59:16 INFO - ##### Finished create-virtualenv step (success) 05:59:16 INFO - ##### 05:59:16 INFO - ##### 05:59:16 INFO - ##### Running install step. 05:59:16 INFO - ##### 05:59:16 INFO - Running pre-action listener: _resource_record_pre_action 05:59:16 INFO - Running main action method: install 05:59:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:16 INFO - Reading from file tmpfile_stdout 05:59:16 INFO - Detecting whether we're running mozinstall >=1.0... 05:59:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:59:16 INFO - Reading from file tmpfile_stdout 05:59:16 INFO - Output received: 05:59:16 INFO - Usage: mozinstall [options] installer 05:59:16 INFO - Options: 05:59:16 INFO - -h, --help show this help message and exit 05:59:16 INFO - -d DEST, --destination=DEST 05:59:16 INFO - Directory to install application into. [default: 05:59:16 INFO - "/builds/slave/test"] 05:59:16 INFO - --app=APP Application being installed. [default: firefox] 05:59:16 INFO - mkdir: /builds/slave/test/build/application 05:59:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:59:33 INFO - Reading from file tmpfile_stdout 05:59:33 INFO - Output received: 05:59:33 INFO - /builds/slave/test/build/application/firefox/firefox 05:59:33 INFO - Running post-action listener: _resource_record_post_action 05:59:33 INFO - ##### 05:59:33 INFO - ##### Finished install step (success) 05:59:33 INFO - ##### 05:59:33 INFO - ##### 05:59:33 INFO - ##### Running run-tests step. 05:59:33 INFO - ##### 05:59:33 INFO - Running pre-action listener: _resource_record_pre_action 05:59:33 INFO - Running main action method: run_tests 05:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:59:33 INFO - Python 2.7.3 05:59:33 INFO - Return code: 0 05:59:33 INFO - grabbing minidump binary from tooltool 05:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:33 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279f9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a0d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:59:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:59:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:59:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:59:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:59:33 INFO - Return code: 0 05:59:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:59:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:59:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:59:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:59:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:59:34 INFO - Return code: 0 05:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:59:34 INFO - Using env: {'DISPLAY': ':0', 05:59:34 INFO - 'HOME': '/home/cltbld', 05:59:34 INFO - 'LANG': 'en_US.UTF-8', 05:59:34 INFO - 'LANGUAGE': 'en_US:en', 05:59:34 INFO - 'LOGNAME': 'cltbld', 05:59:34 INFO - 'MAIL': '/var/mail/cltbld', 05:59:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:59:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:34 INFO - 'MOZ_NO_REMOTE': '1', 05:59:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:59:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:34 INFO - 'NO_EM_RESTART': '1', 05:59:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:34 INFO - 'PWD': '/builds/slave/test', 05:59:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:59:34 INFO - 'SHELL': '/bin/bash', 05:59:34 INFO - 'SHLVL': '1', 05:59:34 INFO - 'TERM': 'linux', 05:59:34 INFO - 'TMOUT': '86400', 05:59:34 INFO - 'USER': 'cltbld', 05:59:34 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1464785920.385426-1563640472', 05:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:34 INFO - '_': '/tools/buildbot/bin/python'} 05:59:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:59:34 INFO - mozversion application_buildid: 20160601030219 05:59:34 INFO - mozversion application_changeset: 25321494921c824703a605127fb1f99b1faf5910 05:59:34 INFO - mozversion application_display_name: Nightly 05:59:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:59:34 INFO - mozversion application_name: Firefox 05:59:34 INFO - mozversion application_remotingname: firefox 05:59:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:59:34 INFO - mozversion application_vendor: Mozilla 05:59:34 INFO - mozversion application_version: 49.0a1 05:59:34 INFO - mozversion platform_buildid: 20160601030219 05:59:34 INFO - mozversion platform_changeset: 25321494921c824703a605127fb1f99b1faf5910 05:59:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:59:34 INFO - mozversion platform_version: 49.0a1 05:59:34 INFO - using testdate: 1464785974 05:59:34 INFO - actual date: 1464785974 05:59:34 INFO - starting webserver on 'localhost:36852' 05:59:34 INFO - SUITE-START | Running 2 tests 05:59:34 INFO - TEST-START | dromaeo_css 05:59:34 INFO - operating with platform_type : linux_ 05:59:34 INFO - Initialising browser for dromaeo_css test... 05:59:34 INFO - TEST-INFO | started process 7611 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWHR593/profile http://localhost:36852/getInfo.html) 05:59:38 INFO - PROCESS | 7611 | __metrics Screen width/height:1600/1200 05:59:38 INFO - PROCESS | 7611 | colorDepth:24 05:59:38 INFO - PROCESS | 7611 | Browser inner width/height: 1024/697 05:59:38 INFO - PROCESS | 7611 | __metrics 05:59:39 ERROR - PROCESS | 7611 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:59:40 INFO - TEST-INFO | 7611: exit 0 05:59:40 INFO - Browser initialized. 05:59:40 INFO - Running cycle 1/1 for dromaeo_css test... 05:59:40 INFO - TEST-INFO | started process 7697 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWHR593/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:52 INFO - PROCESS | 7697 | RSS: Main: 156037120 05:59:52 INFO - PROCESS | 7697 | 06:00:43 INFO - PROCESS | 7697 | 1464786043905 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:02:51 INFO - PROCESS | 7697 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36852/tests/dromaeo/cssquery-ext.html) 06:02:52 INFO - PROCESS | 7697 | RSS: Main: 250339328 06:02:52 INFO - PROCESS | 7697 | 06:05:55 INFO - PROCESS | 7697 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-ext.html (next: http://localhost:36852/tests/dromaeo/cssquery-jquery.html) 06:05:55 INFO - PROCESS | 7697 | RSS: Main: 246919168 06:05:55 INFO - PROCESS | 7697 | 06:08:57 INFO - PROCESS | 7697 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36852/tests/dromaeo/cssquery-mootools.html) 06:08:58 INFO - PROCESS | 7697 | RSS: Main: 245616640 06:08:58 INFO - PROCESS | 7697 | 06:12:00 INFO - PROCESS | 7697 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36852/tests/dromaeo/cssquery-prototype.html) 06:12:00 INFO - PROCESS | 7697 | RSS: Main: 241246208 06:12:00 INFO - PROCESS | 7697 | 06:15:03 INFO - PROCESS | 7697 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36852/tests/dromaeo/cssquery-yui.html) 06:15:04 INFO - PROCESS | 7697 | RSS: Main: 207101952 06:15:04 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-yui.html (next: http://localhost:36852/tests/dromaeo/cssquery-dojo.html) 06:18:06 INFO - PROCESS | 7697 | RSS: Main: 279326720 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | __start_tp_report 06:18:06 INFO - PROCESS | 7697 | _x_x_mozilla_page_load 06:18:06 INFO - PROCESS | 7697 | _x_x_mozilla_page_load_details 06:18:06 INFO - PROCESS | 7697 | |i|pagename|runs| 06:18:06 INFO - PROCESS | 7697 | |0;dojo.html;3169.3819705157493;3196.5844440222754;3200.4719221328482;3218.549403083567;3252.967470325296;5319.760610772515;5434.429384914576;5554.527865131464;5607.551395888329;5645.971770141154;5409.080456322425;5425.240466334716;5481.808136715215;5526;5541.9168712469345;4826.155422801014;4934.333864928225;4937.1606826839525;4967.950320496785;5229.633925625208;5123.871903202413;5161.380634323887;5180.1452760294505;5191.221316802472;5233.816816411427;4905.117078925792;4936.605071594282;4994.575461085776;5001.374828146481;5082.2122571001555;5038.823641172578;5054.267131265982;5056.84829455117;5131.974340128276;5170.844874653766;5048.394192696863;5083.847484575469;5089.134847076006;5096.643234973553;5120.743962801845;5271.209318602202;5388.137897936312;5439.048166570834;5515.062439385274;5528.778848846041;5904.261967254093;5944.881102377972;5961.612495187842;5963.075723262901;5964.075568286922;5758.3665796762325;5926.97036514819;5968.432998865101;5979.701014949279;5982.611130276561;5442.183672449125;5507.146392309159;5507.7246137693355;5572.58205634579;5583.134614134816;6043.923342459259;6049.138042644727;6055.840342407839;6095.512359011268;6101.694915254219;5647.265855438766;5756.42435756421;5797.275340582427;5833.4749872511675;5851.180834683148;5847.766089356463;5999.880002399927;6005.369436209223;6007.879842403127;6009.038553831366;5841.678707671034;5874.265716785402;5882.088276317178;5895.322037965603;5919.644821310735;5844.853878653068;5875.50058245054;5924.229850119543;5933.228680271537;5940.524758019348;4487.618552423015;4534.523874993079;4543.341215523797;4561.270196768567;4562.5665561771575;4037.596240375939;4038.353863381845;4040.1111755413763;4052.392141178788;4079.4900637420324;5388.5419739321815;5439.5376393006245;5598.048331783607;5614.07367784311;5615.719214039282;5962.880742385215;5973.044312909914;6035.245594300713;6050.727717252734;6076.635401875901;5682.40334764852;5685.403032681592;5688.8862222755315;5689.402612725687;5696.259486266758;4978.153713868651;5027.622928280393;5114.64197506171;5182.2485739567555;5203.661761985459;6164.352742962014;6172.475339596095;6174.660393678301;6176.567640265228;6181.072839074085;5988.161313326141;6005.549583781147;6009.819705408845;6013.158157857991;6057.878842423215;5855.648661080349;6026.668533230714;6040.7583696651645;6072.30168530607;6162.845928851814;5816.912746308724;5859.5019423347785;5862.237909071793;5871.119332100048;5872;5902.498287645598;5971.61184523022;5981.611195272293;5982.371850955587;5989.1914591529585;5636.492715655447;5698.6580805151825;5757.712114394348;5782.190493330853;5824.70876456167;5803.3616302207565;5810.7094645266725;5816.331121920864;5816.680082595498;5820.039693450533;5567.331920169606;5706.629069110494;5719.48524632785;5721.427857214246;5725.398833122462;5859.326177489644;5908.143319218775;5918.378570250062;6006.519478441627;6006.669633170218;5761.711435034601;5805.76776928935;5809.5933284671355;5813.069908814569;5857.238558987305 06:18:06 INFO - PROCESS | 7697 | |1;ext.html;624.7407325959725;638.4286063972743;641.5990006246096;647.2233320015982;651.3811878715219;32132.196695082606;45826.77086614566;47159.528404715944;47309.52690473103;47460;46286.305705414445;46619.53380466194;46677.29984050233;46840.29739553909;46875.76562117185;8635;8817.779555511099;8835.072317406677;8845.690400835972;8863.379563430562;8675.95662021692;8798.340124490656;8814.603342849587;8817.29461643067;8819.941607007167;8783.472991620523;8822.911770882272;8834.337424693142;8836.513991730451;8850.114988501131;8764.21122099011;8808;8808.955955220246;8813.867791983126;8824.426412283245;8715.738527844174;8764.21122099014;8802.603882825288;8813.325468161029;8814.955925220333;41609.79195104035;41877;41903.790481047705;41926;42038.36942445866;3492.1269682579355;3693.095191678029;3726.441033844918;3922.5489068757174;4179.958200418017;32246.193845153823;32369.35261294761;32789.344213115844;32807.50788738171;33397.499037514455;4382.912341753179;4391.29739241723;4442.355858400546;4442.378067070613;4516.683832131752;356781.9999999974;358309;358644;359198;359490;51775;58562;60243;61664.69167654133;62192.37807621866;65455.67272163704;65511.34488655148;65728.67135664291;65734.34265657378;65880.67059664671;16904.915475422546;16954.745678814827;17216.741748873777;17264;17460;11858;11929.880701193051;12015.038796896228;12057.758844823054;12752.80870786939;18430.815691843178;19328.584957777737;19460.4473757401;19566.856589282703;19588.690488092598;7270.600116993616;7332.8900066499045;7411.777646670608;7428.33145016951;7481.962590187123;967.8064387122604;1032.3650954662978;1043.7912417516527;1046.2571574182264;1130.4291332876844;32070;32701.836490817393;32753.67246327554;32808;33150.502742458884;1096.7916095941746;1147.6843867936218;1148.3741360958297;1179.6343133628602;1215.2283300104498;17092.743608845878;17189.398371056952;17234.224459899335;17316.787824852392;17409.738853917203;548034;551148;552432;553041;556456;57379;59271;59390.703046484494;59442.405575944555;59540;1291.8320618319747;1366.064245991499;1403.8034675145489;1404.8524904885044;1428.4714655577502;31513.558356405654;33410.83294583609;33677.49483757697;33710.662893370754;34228.65771342354;47769.76115119541;48165.51834481751;48536.27195591998;48695.26957095575;48809.75595122002;402798;407023;407191;407347;408901;24894.502109958063;26570.335741606614;26948.730512694623;27008.864955675097;28969.130926072252;47937.760311198224;48859.511404886915;49822.501774981785;49846.75076624594;49998.750006249735;40962;41479.170416590896;42002.369964451165;42002.78998604987;42381.576184239;40972.79513602413;41008.1798364037;41321.79339103285;41613.37579936243;41616.375754364315;40129.598704012584;41088.58911410848;41391;41412;41541;35311;36418.81790591136;36774.63225367819;36995.63004369922;37386;27926.3018424541;28072.157835264974;28285.29286767765;28287;28501.287467812643 06:18:06 INFO - PROCESS | 7697 | |2;jquery.html;399.9240144372568;408.11642793352394;409.41453721178704;412.53177643374767;416.4502856229776;17878.195481203344;18967.14647840847;19239.71140432893;19371;19406.514837129078;17864.553386165353;18540.165692543833;18625.62748745024;19173.904130479328;19278.22887084515;5363.8122665706705;5572.832815015556;5614.9157762633595;5664.461876121783;5824.213731146283;5590.021746194399;5593.860153496154;5594.552435805146;5599.496045355917;5627.296587926509;5508.421615730331;5544.722763861811;5584.1902924075985;5601.075822489304;5615.101583746601;5528.281323427948;5569.164625306216;5572.5263352615275;5596.356419011825;5611.691356975365;6744.93255067448;7139.857202855914;7157.6779044943105;7158.677859496283;7296.16094149169;23838.16566420167;24158.637620435708;24772.65131221235;25578.36054098644;26102.477950440883;2655.4556315955183;2709.322669332667;2720.9319767005786;3353.6143343515564;3397.813120278384;14690.412383504754;15393.692126157526;15489.14809685467;15892.76160857588;16127.43553975617;2052.4458396232935;2075.201047596671;2092.5187206942337;2103.5687684024892;2206.801387875083;89102;92743.53628231883;92848.53575732146;95237;96709.51645241799;17587.912060439616;17620.91189544044;17650.470485885446;17768.667349948806;17771.73342399839;32676.673233267364;32763.1809204767;33050.66949330524;33062.3387532248;33090.17274568155;8868.645254189889;9113.863292050624;9224.584893679801;9232.446053236827;9459.337846350827;7670.884936725954;7671.6164191790185;7774.455788094892;7794.181610930884;7800.063992320957;8684.522351270742;8858.424202426822;8862.335324850663;8898.199162075443;9187.106061590712;3144.3901561703733;3411.822921092219;3474.6872781449792;3528.3825330567047;3531.805750683737;960.5533426956499;1023.9078482936571;1047.706642140202;1054.4569546683465;1100.763335882789;18267.90866045688;18902.149403276886;18922.148503317385;19128.904355478135;19159.828389629536;990.9950450247846;1003.0922015575916;1027.4503140819625;1035.803197392493;1081.8431327457474;10166.593336266467;10672.466376681135;10727.33726272429;10753.579201693306;10831.891681083245;471312;472180;481969;482141;483054;65342.67328663326;93377;96312;97144;98831;995.9850602240972;997.2420960070253;1020.357174979758;1026.5791025679584;1036.0261354326908;15230.543083707507;16004.359825607311;16672.083035433167;17066;17513;40979.59020409877;41514.37728434015;41754;41895.33663217849;42763;52637.47362526326;53696.46303536915;57100;57659.13511297418;58615.4138458627;7362.889556656547;7596.08846938371;7784.649690764042;8246.216609422096;8546.743597692079;9673.806523869624;10839.512221950188;11738.237014594024;12608.936955315165;12687.746245074863;11356.772864542498;11453.59912403062;11551.960323570918;11637.825432618687;11776.4700588472;11468.311901285615;11526.481308340977;11669.299842009506;11776.88223117792;11832.408379581158;11626.244294120856;11847.881521184678;11988.64034078979;12165;12213.206141600767;7430.92569074324;7545;7592.696292148252;8753.91246087531;8948.910510894808;9835.901640983499;11608.477618507019;11726.765464690488;11734.589289374831;11739.530418783153 06:18:06 INFO - PROCESS | 7697 | |3;mootools.html;1317.097788015209;1450.209635748517;1520.7794869743886;1607.5659571915583;1803.1344954421886;8532.70135545256;8811;8813.647454101836;9108.544572771352;9113.088691130884;8656.91343086569;8863.556822158882;8874.112588741124;8875.245604123642;8882.200601945824;2061.80412860778;2234.575430668176;2308.849924754894;2339.508703172336;2382.4401265702545;2398.472336086067;2404.230646193219;2404.2546810488716;2409.6144616861307;2410.216679579135;2295.380247333219;2298.482841360689;2326.4067662745974;2327.313442534448;2333.4866329407505;2018.4247489465506;2237.865728056322;2301.3096071178747;2310.202979971907;2329.2080692564523;2248.6289762189217;2267.7051983242236;2274.749777524471;2275.24916777463;2277.350954977823;8784.429012114193;8826.205641492232;8853.911460885372;8874.511901845395;8876.245519130882;5270.4729527047375;5376.274202982587;5381;5385.569154467672;5391.48780865821;3372.37611041958;3425.4348032574594;3447.67247111524;3458.3083383323183;3528.947065794015;1118.2004866520397;1127.8928501792316;1137.726945533066;1143.5368675686361;1147.2886810177743;12522.874771252262;12645.178063425848;12653.177543459646;12668.68328291791;12669.176503527333;4616.930746038812;4704.458987216514;4791.891567710197;4796.784144713496;4809.78355973982;10666.093382062456;10671.83992240117;10677.78644427107;10678.038976492153;10684.786304273872;5350.785968561292;5407.540359069444;5416.837494875082;5428.945710542844;5430.049741295257;4331.155424692218;4830.565249127595;5001.674891132065;5001.7499125043605;5004.374453193351;4094.8566800161852;4129.6489798367475;4130.339145736668;4253.48958125027;4308.763018033976;1251.949922003128;1270.9809352859716;1279.1877158004734;1288;1290.1098242212843;766.7891329884327;881.9955900220586;896.5606852642159;897.5781382750097;910.1171863292728;6243.188385509855;6268.62388256697;6328.968355158287;6354.078658594478;6507.804765857032;967.6419724701906;976.511744127936;1008.6671398438498;1025.8102251083574;1041.671873359889;4139.834406623701;4313;4328.610425061696;4389.18800021997;4468.955310446919;11046.392448415414;11327.603533876274;11413;11439.771204575862;11525.48135333912;10596.947015264874;11286.153538484647;11296.265742726697;11311.208215425006;11324.094072474347;8097.554634495042;8239.258466737903;8243.505389676518;8295.8340833183;8366.414350995494;6097.5426842987845;6206.689665516797;6330.272018717907;6370.235571731422;6435.324290949383;9933.3543319684;10254.948725256327;10463;10480.004399582027;10660.147188224768;10466.372017678963;10607.151427885905;10677.733056673645;10762.892371076188;10763.892361076601;7013.894791578028;7760.961195193988;7877.448578599443;7914.762557123296;8021.919780802117;8719.694810681849;8738.475691458532;8786.604602793017;8815.206631402947;8839.027706952358;7561.168271490022;7715.884261735966;7746.690132394641;7774.572398517909;7854.72508462201;7466.813329666585;7666.118396384487;7682.193369696101;7785.688572457265;7808.570528620753;6910.032595436744;6967.198772141269;6990.545614535039;6992.930070699228;7002.894956575554;5105.668131571436;5226.9215961761365;5320.494553017457;5372.704501252469;5394.0290747665795;5189.429162792015;5248.6588371755715;5305.9204111939125;5373.11343628297;5390.703511306916 06:18:06 INFO - PROCESS | 7697 | |4;prototype.html;334.28062709855334;338.34867879332285;343.9896803095908;346.1571074433754;349.3502086119818;5301.416844147146;5304.416514183446;5321.308229930113;5342.332208473941;5355.250264962909;4965;5056.570191533724;5147.279380886679;5234.947650523493;5267.130923397644;4135.27632664284;4171.416001759756;4174.937375939363;4188.308929026707;4200.957990420087;4181.498220213579;4182.100848317611;4206.747595144286;4224.831006759726;4224.894377640569;3930.6265904739003;4033.415154802552;4129.772862492562;4163.2506148893335;4165.812538435777;4126.896827579305;4140.799168241207;4143.440635514197;4146.336586146233;4168.708190426656;3693;3697.85208591656;3700.278445703089;3707.295613833363;3712.313222053902;5000;5072.644914855961;5075.6447048706605;5081.212412076134;5125.410577783566;4727.952720472786;4750.738709370983;4766.451858036336;4782.593479554242;4786.0906427778955;5463.289772329612;5465.535429488498;5466.070767969415;5493.587980901449;5505.752241149158;3881.758731459235;4005.0988527581353;4040.919181616351;4041.9191616167514;4085.407615895708;5675.177099320616;5685.260916080924;5693.629914055573;5694.430556944314;5696.943030569682;4143.19207754485;4152.979235103805;4158.355454904495;4194.832206711759;4203.138356636943;4072.0634254121123;4174.123434078817;4222.3244280914905;4264.893377665521;4266.5946735060115;4696.637974987284;4710.858674239778;4723.4331880174595;4746.145693775154;4770.260609605517;5454.481824226771;5499.587530935116;5507.614466987273;5518.806841760519;5540.501354878081;1424.0387738276622;1438.6547228665045;1445.284584130862;1450.9770611718714;1459.109942934809;929.9163075323119;932.8507438809758;951.990480095204;952.2145976294448;952.623713633111;4784.6889952153;4806.351142595775;4811.639127065414;4821.2045012572535;4837.612990960785;5002.59979201663;5078.162103252922;5090.389153301628;5095.261187127846;5142.202958541432;4480.753558554311;4788.683112144188;4790.473047964721;4826.879328016757;4839.644899428165;2680.316519287569;2692.9461410771673;2700.190872115648;2701.8784154712694;2705.7158998304894;5121.231815227671;5157.58739300855;5269.499397557226;5274.446183150791;5291.544825173031;5161.793528258902;5175.16281719984;5178.818741344035;5380.650257733235;5842.415758424208;5822.5633077518505;5845.795397161079;5880.26496687914;5896.904125334148;5904;5713.742976545153;5723.941070901897;5743.253377060955;5749.281339832521;5804.564657650609;5491.313585801775;5630.971845140748;5647.209390685225;5673.4114447954635;5691.402402747652;5808.215890854681;5867.178594996619;5875.941240587539;5910.822675319747;5935.881282374415;3838.7312888097563;4075.4090656855183;4083.4895638045246;4086.5096188457574;4110.34234522475;5613.298337707786;5674.971625141848;5714.142878568248;5715.142728590745;5784.334801497882;5521.950829342412;5562.860928476659;5586.888262234651;5610.18652295423;5645.915311270416;5456.61803673739;5571.253556749902;5580.469855363896;5591.748371323219;5660.575456840671;5471.835844924659;5493.945060549343;5503.229547863382;5506;5519.613627046068;5630.690312032878;5676.858078548069;5678.716064196857;5705.7147142643535;5709.628874123335;5209.53114219722;5509.311336082989;5529.834104976857;5546.611737178358;5560.443955604407 06:18:06 INFO - PROCESS | 7697 | |5;yui.html;760.3005235183631;789.9328557072648;793.6547601793219;845.5265051571121;846.407514739682;14131.788023179648;14846.183459909715;14902.925485372585;14916.850831491709;14934.178620175899;14613.196274204915;14652.194129322883;14657.633559161026;14683.77974330386;14689.706205875877;685.8216863615446;802.4623502253474;828.4656396624204;834.586879494651;835.1147783349669;818.5825229133155;823.6705317872869;823.8846561481397;825.5129473610568;826.1449399871135;725.0791494801599;881.1144799476549;882.2897567458226;882.9426087304305;883;846.1749793950879;859.3769517100114;865.6623916672503;866.4714524140269;867.3148212911792;629.6694235526385;630.3035146163515;630.6342321453546;630.9432151106422;632.1024145713099;13908.443662253498;13931.512397066053;13932.094413863066;13937.512187073404;13986.300684965812;13682.31588420585;13813.654658633513;13844.30778461083;13851.722965540734;13869.583912482642;12359.134860559765;12366.876331236663;12382.380880956007;12395.814062789066;12608.432620532098;4386.7367957922625;4411.448568928884;4439.400680908068;4476.306172543261;4479.4176757021705;57148;57928;58130.41869581334;59647.70176149135;60682.696586517224;10593.205509586813;10671.575890919357;10756.677299681021;10962.835557466644;11034.337939723642;20762.792372076387;20769.688454673193;20778.48053798637;20793.1682732689;20814.479638008866;8004;8009.239122283374;8164.183581641788;8255.091939886608;8278.83442331162;4627.907441851212;4720.575148236675;4724.976375118103;4746.667733258639;4800.183968725325;3335.7498187636024;3396.847141878621;3399.1841957930415;3403.2002479417315;3447.0692912913373;610.6305685060474;616.6947361056306;622.8069298517474;632.6488798716664;635.9491240700733;566.5835610826122;570.5834740639274;583.6789765628887;666.1805978646292;674.9190097188368;9208.073760279092;9375.765605859771;9455.952720236355;9520.8571871422;9725.756856078655;623.370395900135;661.1075048684238;703.5708217987024;706.8303607134251;707.2008630247788;4265.168292182994;4285.314349704033;4333.241682705514;4392.780360981938;4458.888527786831;67491.32508674948;67784.6610766943;68650;70139.2986070143;70376.296237038;58403.41596584065;58908.705456472446;60889;60964.08553871696;61533;54157.72921135448;54221.1866821998;55091.724541377036;56609.71695141498;57426;8328.708495202638;8392.244697977212;8510.574471276288;8584.484930904166;8679;37427.625723743506;37663.246735065695;37751.00801929754;37813.62186378101;38206;49715;50859.23711144262;51197.48802511927;51459;51590;11549.48027338755;11668.766624667289;11694.415279236175;11840.881591184323;11976.401179940794;12026.398680065786;12063.353228539989;12463.626091217278;12522.87477125217;12589.74820503603;10753.085987690822;11165.609203677832;11317.151213658844;11485.770284594428;11666.941665291959;11743.47154378072;11872.52509899629;11875;12004.819927700917;12162.270263784201;11955.521779128738;12035.819462707892;12086.637400877637;12125.69685757863;12576.371181441074;9888.456134912649;10020.599176032876;10067;10116.140128089195;10127.139193168663;9981.550830212513;9989.650362237287;10043;10070.748231294276;10078.949605251926 06:18:06 INFO - PROCESS | 7697 | __end_tp_report 06:18:06 INFO - PROCESS | 7697 | __start_cc_report 06:18:06 INFO - PROCESS | 7697 | _x_x_mozilla_cycle_collect,771 06:18:06 INFO - PROCESS | 7697 | __end_cc_report 06:18:06 INFO - PROCESS | 7697 | __startTimestamp1464787086703__endTimestamp 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | ------- Summary: start ------- 06:18:06 INFO - PROCESS | 7697 | Number of tests: 6 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | [#0] dojo.html Cycles:165 Average:5512.29 Median:5725.40 stddev:629.31 (11.0%) stddev-sans-first:603.81 06:18:06 INFO - PROCESS | 7697 | Values: 3169.4 3196.6 3200.5 3218.5 3253.0 5319.8 5434.4 5554.5 5607.6 5646.0 5409.1 5425.2 5481.8 5526.0 5541.9 4826.2 4934.3 4937.2 4968.0 5229.6 5123.9 5161.4 5180.1 5191.2 5233.8 4905.1 4936.6 4994.6 5001.4 5082.2 5038.8 5054.3 5056.8 5132.0 5170.8 5048.4 5083.8 5089.1 5096.6 5120.7 5271.2 5388.1 5439.0 5515.1 5528.8 5904.3 5944.9 5961.6 5963.1 5964.1 5758.4 5927.0 5968.4 5979.7 5982.6 5442.2 5507.1 5507.7 5572.6 5583.1 6043.9 6049.1 6055.8 6095.5 6101.7 5647.3 5756.4 5797.3 5833.5 5851.2 5847.8 5999.9 6005.4 6007.9 6009.0 5841.7 5874.3 5882.1 5895.3 5919.6 5844.9 5875.5 5924.2 5933.2 5940.5 4487.6 4534.5 4543.3 4561.3 4562.6 4037.6 4038.4 4040.1 4052.4 4079.5 5388.5 5439.5 5598.0 5614.1 5615.7 5962.9 5973.0 6035.2 6050.7 6076.6 5682.4 5685.4 5688.9 5689.4 5696.3 4978.2 5027.6 5114.6 5182.2 5203.7 6164.4 6172.5 6174.7 6176.6 6181.1 5988.2 6005.5 6009.8 6013.2 6057.9 5855.6 6026.7 6040.8 6072.3 6162.8 5816.9 5859.5 5862.2 5871.1 5872.0 5902.5 5971.6 5981.6 5982.4 5989.2 5636.5 5698.7 5757.7 5782.2 5824.7 5803.4 5810.7 5816.3 5816.7 5820.0 5567.3 5706.6 5719.5 5721.4 5725.4 5859.3 5908.1 5918.4 6006.5 6006.7 5761.7 5805.8 5809.6 5813.1 5857.2 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | [#1] ext.html Cycles:180 Average:60426.06 Median:41614.88 stddev:118437.44 (284.6%) stddev-sans-first:118684.58 06:18:06 INFO - PROCESS | 7697 | Values: 624.7 638.4 641.6 647.2 651.4 32132.2 45826.8 47159.5 47309.5 47460.0 46286.3 46619.5 46677.3 46840.3 46875.8 8635.0 8817.8 8835.1 8845.7 8863.4 8676.0 8798.3 8814.6 8817.3 8819.9 8783.5 8822.9 8834.3 8836.5 8850.1 8764.2 8808.0 8809.0 8813.9 8824.4 8715.7 8764.2 8802.6 8813.3 8815.0 41609.8 41877.0 41903.8 41926.0 42038.4 3492.1 3693.1 3726.4 3922.5 4180.0 32246.2 32369.4 32789.3 32807.5 33397.5 4382.9 4391.3 4442.4 4442.4 4516.7 356782.0 358309.0 358644.0 359198.0 359490.0 51775.0 58562.0 60243.0 61664.7 62192.4 65455.7 65511.3 65728.7 65734.3 65880.7 16904.9 16954.7 17216.7 17264.0 17460.0 11858.0 11929.9 12015.0 12057.8 12752.8 18430.8 19328.6 19460.4 19566.9 19588.7 7270.6 7332.9 7411.8 7428.3 7482.0 967.8 1032.4 1043.8 1046.3 1130.4 32070.0 32701.8 32753.7 32808.0 33150.5 1096.8 1147.7 1148.4 1179.6 1215.2 17092.7 17189.4 17234.2 17316.8 17409.7 548034.0 551148.0 552432.0 553041.0 556456.0 57379.0 59271.0 59390.7 59442.4 59540.0 1291.8 1366.1 1403.8 1404.9 1428.5 31513.6 33410.8 33677.5 33710.7 34228.7 47769.8 48165.5 48536.3 48695.3 48809.8 402798.0 407023.0 407191.0 407347.0 408901.0 24894.5 26570.3 26948.7 27008.9 28969.1 47937.8 48859.5 49822.5 49846.8 49998.8 40962.0 41479.2 42002.4 42002.8 42381.6 40972.8 41008.2 41321.8 41613.4 41616.4 40129.6 41088.6 41391.0 41412.0 41541.0 35311.0 36418.8 36774.6 36995.6 37386.0 27926.3 28072.2 28285.3 28287.0 28501.3 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | [#2] jquery.html Cycles:180 Average:29869.69 Median:3443.26 stddev:78986.35 (2293.9%) stddev-sans-first:79176.94 06:18:06 INFO - PROCESS | 7697 | Values: 399.9 408.1 409.4 412.5 416.5 17878.2 18967.1 19239.7 19371.0 19406.5 17864.6 18540.2 18625.6 19173.9 19278.2 5363.8 5572.8 5614.9 5664.5 5824.2 5590.0 5593.9 5594.6 5599.5 5627.3 5508.4 5544.7 5584.2 5601.1 5615.1 5528.3 5569.2 5572.5 5596.4 5611.7 6744.9 7139.9 7157.7 7158.7 7296.2 23838.2 24158.6 24772.7 25578.4 26102.5 2655.5 2709.3 2720.9 3353.6 3397.8 14690.4 15393.7 15489.1 15892.8 16127.4 2052.4 2075.2 2092.5 2103.6 2206.8 89102.0 92743.5 92848.5 95237.0 96709.5 17587.9 17620.9 17650.5 17768.7 17771.7 32676.7 32763.2 33050.7 33062.3 33090.2 8868.6 9113.9 9224.6 9232.4 9459.3 7670.9 7671.6 7774.5 7794.2 7800.1 8684.5 8858.4 8862.3 8898.2 9187.1 3144.4 3411.8 3474.7 3528.4 3531.8 960.6 1023.9 1047.7 1054.5 1100.8 18267.9 18902.1 18922.1 19128.9 19159.8 991.0 1003.1 1027.5 1035.8 1081.8 10166.6 10672.5 10727.3 10753.6 10831.9 471312.0 472180.0 481969.0 482141.0 483054.0 65342.7 93377.0 96312.0 97144.0 98831.0 996.0 997.2 1020.4 1026.6 1036.0 15230.5 16004.4 16672.1 17066.0 17513.0 40979.6 41514.4 41754.0 41895.3 42763.0 52637.5 53696.5 57100.0 57659.1 58615.4 7362.9 7596.1 7784.6 8246.2 8546.7 9673.8 10839.5 11738.2 12608.9 12687.7 11356.8 11453.6 11552.0 11637.8 11776.5 11468.3 11526.5 11669.3 11776.9 11832.4 11626.2 11847.9 11988.6 12165.0 12213.2 7430.9 7545.0 7592.7 8753.9 8948.9 9835.9 11608.5 11726.8 11734.6 11739.5 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | [#3] mootools.html Cycles:180 Average:5926.49 Median:4794.34 stddev:3393.36 (70.8%) stddev-sans-first:3385.20 06:18:06 INFO - PROCESS | 7697 | Values: 1317.1 1450.2 1520.8 1607.6 1803.1 8532.7 8811.0 8813.6 9108.5 9113.1 8656.9 8863.6 8874.1 8875.2 8882.2 2061.8 2234.6 2308.8 2339.5 2382.4 2398.5 2404.2 2404.3 2409.6 2410.2 2295.4 2298.5 2326.4 2327.3 2333.5 2018.4 2237.9 2301.3 2310.2 2329.2 2248.6 2267.7 2274.7 2275.2 2277.4 8784.4 8826.2 8853.9 8874.5 8876.2 5270.5 5376.3 5381.0 5385.6 5391.5 3372.4 3425.4 3447.7 3458.3 3528.9 1118.2 1127.9 1137.7 1143.5 1147.3 12522.9 12645.2 12653.2 12668.7 12669.2 4616.9 4704.5 4791.9 4796.8 4809.8 10666.1 10671.8 10677.8 10678.0 10684.8 5350.8 5407.5 5416.8 5428.9 5430.0 4331.2 4830.6 5001.7 5001.7 5004.4 4094.9 4129.6 4130.3 4253.5 4308.8 1251.9 1271.0 1279.2 1288.0 1290.1 766.8 882.0 896.6 897.6 910.1 6243.2 6268.6 6329.0 6354.1 6507.8 967.6 976.5 1008.7 1025.8 1041.7 4139.8 4313.0 4328.6 4389.2 4469.0 11046.4 11327.6 11413.0 11439.8 11525.5 10596.9 11286.2 11296.3 11311.2 11324.1 8097.6 8239.3 8243.5 8295.8 8366.4 6097.5 6206.7 6330.3 6370.2 6435.3 9933.4 10254.9 10463.0 10480.0 10660.1 10466.4 10607.2 10677.7 10762.9 10763.9 7013.9 7761.0 7877.4 7914.8 8021.9 8719.7 8738.5 8786.6 8815.2 8839.0 7561.2 7715.9 7746.7 7774.6 7854.7 7466.8 7666.1 7682.2 7785.7 7808.6 6910.0 6967.2 6990.5 6992.9 7002.9 5105.7 5226.9 5320.5 5372.7 5394.0 5189.4 5248.7 5305.9 5373.1 5390.7 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | [#4] prototype.html Cycles:180 Average:4602.58 Median:5123.32 stddev:1341.84 (26.2%) stddev-sans-first:1306.80 06:18:06 INFO - PROCESS | 7697 | Values: 334.3 338.3 344.0 346.2 349.4 5301.4 5304.4 5321.3 5342.3 5355.3 4965.0 5056.6 5147.3 5234.9 5267.1 4135.3 4171.4 4174.9 4188.3 4201.0 4181.5 4182.1 4206.7 4224.8 4224.9 3930.6 4033.4 4129.8 4163.3 4165.8 4126.9 4140.8 4143.4 4146.3 4168.7 3693.0 3697.9 3700.3 3707.3 3712.3 5000.0 5072.6 5075.6 5081.2 5125.4 4728.0 4750.7 4766.5 4782.6 4786.1 5463.3 5465.5 5466.1 5493.6 5505.8 3881.8 4005.1 4040.9 4041.9 4085.4 5675.2 5685.3 5693.6 5694.4 5696.9 4143.2 4153.0 4158.4 4194.8 4203.1 4072.1 4174.1 4222.3 4264.9 4266.6 4696.6 4710.9 4723.4 4746.1 4770.3 5454.5 5499.6 5507.6 5518.8 5540.5 1424.0 1438.7 1445.3 1451.0 1459.1 929.9 932.9 952.0 952.2 952.6 4784.7 4806.4 4811.6 4821.2 4837.6 5002.6 5078.2 5090.4 5095.3 5142.2 4480.8 4788.7 4790.5 4826.9 4839.6 2680.3 2692.9 2700.2 2701.9 2705.7 5121.2 5157.6 5269.5 5274.4 5291.5 5161.8 5175.2 5178.8 5380.7 5842.4 5822.6 5845.8 5880.3 5896.9 5904.0 5713.7 5723.9 5743.3 5749.3 5804.6 5491.3 5631.0 5647.2 5673.4 5691.4 5808.2 5867.2 5875.9 5910.8 5935.9 3838.7 4075.4 4083.5 4086.5 4110.3 5613.3 5675.0 5714.1 5715.1 5784.3 5522.0 5562.9 5586.9 5610.2 5645.9 5456.6 5571.3 5580.5 5591.7 5660.6 5471.8 5493.9 5503.2 5506.0 5519.6 5630.7 5676.9 5678.7 5705.7 5709.6 5209.5 5509.3 5529.8 5546.6 5560.4 06:18:06 INFO - PROCESS | 7697 | 06:18:06 INFO - PROCESS | 7697 | [#5] yui.html Cycles:180 Average:15620.93 Median:4773.43 stddev:18961.18 (397.2%) stddev-sans-first:18981.54 06:18:06 INFO - PROCESS | 7697 | Values: 760.3 789.9 793.7 845.5 846.4 14131.8 14846.2 14902.9 14916.9 14934.2 14613.2 14652.2 14657.6 14683.8 14689.7 685.8 802.5 828.5 834.6 835.1 818.6 823.7 823.9 825.5 826.1 725.1 881.1 882.3 882.9 883.0 846.2 859.4 865.7 866.5 867.3 629.7 630.3 630.6 630.9 632.1 13908.4 13931.5 13932.1 13937.5 13986.3 13682.3 13813.7 13844.3 13851.7 13869.6 12359.1 12366.9 12382.4 12395.8 12608.4 4386.7 4411.4 4439.4 4476.3 4479.4 57148.0 57928.0 58130.4 59647.7 60682.7 10593.2 10671.6 10756.7 10962.8 11034.3 20762.8 20769.7 20778.5 20793.2 20814.5 8004.0 8009.2 8164.2 8255.1 8278.8 4627.9 4720.6 4725.0 4746.7 4800.2 3335.7 3396.8 3399.2 3403.2 3447.1 610.6 616.7 622.8 632.6 635.9 566.6 570.6 583.7 666.2 674.9 9208.1 9375.8 9456.0 9520.9 9725.8 623.4 661.1 703.6 706.8 707.2 4265.2 4285.3 4333.2 4392.8 4458.9 67491.3 67784.7 68650.0 70139.3 70376.3 58403.4 58908.7 60889.0 60964.1 61533.0 54157.7 54221.2 55091.7 56609.7 57426.0 8328.7 8392.2 8510.6 8584.5 8679.0 37427.6 37663.2 37751.0 37813.6 38206.0 49715.0 50859.2 51197.5 51459.0 51590.0 11549.5 11668.8 11694.4 11840.9 11976.4 12026.4 12063.4 12463.6 12522.9 12589.7 10753.1 11165.6 11317.2 11485.8 11666.9 11743.5 11872.5 11875.0 12004.8 12162.3 11955.5 12035.8 12086.6 12125.7 12576.4 9888.5 10020.6 10067.0 10116.1 10127.1 9981.6 9989.7 10043.0 10070.7 10078.9 06:18:06 INFO - PROCESS | 7697 | -------- Summary: end -------- 06:18:06 INFO - PROCESS | 7697 | 06:18:07 INFO - TEST-INFO | 7697: exit 0 06:18:07 INFO - TEST-OK | dromaeo_css | took 1112572ms 06:18:07 INFO - TEST-START | kraken 06:18:07 INFO - operating with platform_type : linux_ 06:18:07 INFO - Initialising browser for kraken test... 06:18:07 INFO - TEST-INFO | started process 7878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpchNpkf/profile http://localhost:36852/getInfo.html) 06:18:11 INFO - PROCESS | 7878 | __metrics Screen width/height:1600/1200 06:18:11 INFO - PROCESS | 7878 | colorDepth:24 06:18:11 INFO - PROCESS | 7878 | Browser inner width/height: 1024/697 06:18:11 INFO - PROCESS | 7878 | __metrics 06:18:12 ERROR - PROCESS | 7878 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:18:13 INFO - TEST-INFO | 7878: exit 0 06:18:13 INFO - Browser initialized. 06:18:13 INFO - Running cycle 1/1 for kraken test... 06:18:13 INFO - TEST-INFO | started process 7960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpchNpkf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:18:25 INFO - PROCESS | 7960 | RSS: Main: 155926528 06:18:25 INFO - PROCESS | 7960 | 06:18:27 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-beat-detection) 06:18:28 INFO - PROCESS | 7960 | RSS: Main: 214708224 06:18:28 INFO - PROCESS | 7960 | 06:18:31 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-dft) 06:18:31 INFO - PROCESS | 7960 | RSS: Main: 197484544 06:18:31 INFO - PROCESS | 7960 | 06:18:34 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-fft) 06:18:35 INFO - PROCESS | 7960 | RSS: Main: 268906496 06:18:35 INFO - PROCESS | 7960 | 06:18:37 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-oscillator) 06:18:37 INFO - PROCESS | 7960 | RSS: Main: 179634176 06:18:37 INFO - PROCESS | 7960 | 06:18:40 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36852/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:18:40 INFO - PROCESS | 7960 | RSS: Main: 183398400 06:18:40 INFO - PROCESS | 7960 | 06:18:43 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36852/tests/kraken/driver.html?testName=imaging-darkroom) 06:18:44 INFO - PROCESS | 7960 | RSS: Main: 218320896 06:18:44 INFO - PROCESS | 7960 | 06:18:47 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36852/tests/kraken/driver.html?testName=imaging-desaturate) 06:18:48 INFO - PROCESS | 7960 | RSS: Main: 220262400 06:18:48 INFO - PROCESS | 7960 | 06:18:51 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36852/tests/kraken/driver.html?testName=json-parse-financial) 06:18:51 INFO - PROCESS | 7960 | RSS: Main: 221192192 06:18:51 INFO - PROCESS | 7960 | 06:18:53 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36852/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:18:53 INFO - PROCESS | 7960 | RSS: Main: 208416768 06:18:53 INFO - PROCESS | 7960 | 06:18:55 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:18:56 INFO - PROCESS | 7960 | RSS: Main: 211001344 06:18:56 INFO - PROCESS | 7960 | 06:18:58 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:18:58 INFO - PROCESS | 7960 | RSS: Main: 218673152 06:18:58 INFO - PROCESS | 7960 | 06:19:01 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:19:02 INFO - PROCESS | 7960 | RSS: Main: 224133120 06:19:02 INFO - PROCESS | 7960 | 06:19:04 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:19:05 INFO - PROCESS | 7960 | RSS: Main: 225792000 06:19:05 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36852/tests/kraken/driver.html?testName=ai-astar) 06:19:07 INFO - PROCESS | 7960 | RSS: Main: 268337152 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | __start_tp_report 06:19:07 INFO - PROCESS | 7960 | _x_x_mozilla_page_load 06:19:07 INFO - PROCESS | 7960 | _x_x_mozilla_page_load_details 06:19:07 INFO - PROCESS | 7960 | |i|pagename|runs| 06:19:07 INFO - PROCESS | 7960 | |0;ai-astar;110;103;110;109;109;110;112;109;109;109 06:19:07 INFO - PROCESS | 7960 | |1;audio-beat-detection;154;124;127;195;125;126;147;128;156;131 06:19:07 INFO - PROCESS | 7960 | |2;audio-dft;161;160;160;160;161;162;161;161;162;161 06:19:07 INFO - PROCESS | 7960 | |3;audio-fft;75;74;74;134;77;76;76;76;97;75 06:19:07 INFO - PROCESS | 7960 | |4;audio-oscillator;83;84;115;84;84;83;125;84;83;124 06:19:07 INFO - PROCESS | 7960 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;116;116;116 06:19:07 INFO - PROCESS | 7960 | |6;imaging-darkroom;146;147;145;145;145;146;146;146;145;146 06:19:07 INFO - PROCESS | 7960 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 06:19:07 INFO - PROCESS | 7960 | |8;json-parse-financial;52;52;55;53;52;54;52;53;53;53 06:19:07 INFO - PROCESS | 7960 | |9;json-stringify-tinderbox;62;61;62;62;62;63;64;62;62;62 06:19:07 INFO - PROCESS | 7960 | |10;stanford-crypto-aes;81;80;80;79;91;83;82;80;108;79 06:19:07 INFO - PROCESS | 7960 | |11;stanford-crypto-ccm;119;120;119;119;119;118;120;122;144;121 06:19:07 INFO - PROCESS | 7960 | |12;stanford-crypto-pbkdf2;159;158;163;157;165;158;158;159;159;158 06:19:07 INFO - PROCESS | 7960 | |13;stanford-crypto-sha256-iterative;61;67;63;63;63;62;62;63;62;63 06:19:07 INFO - PROCESS | 7960 | __end_tp_report 06:19:07 INFO - PROCESS | 7960 | __start_cc_report 06:19:07 INFO - PROCESS | 7960 | _x_x_mozilla_cycle_collect,1613 06:19:07 INFO - PROCESS | 7960 | __end_cc_report 06:19:07 INFO - PROCESS | 7960 | __startTimestamp1464787147262__endTimestamp 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | ------- Summary: start ------- 06:19:07 INFO - PROCESS | 7960 | Number of tests: 14 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#0] ai-astar Cycles:10 Average:109.00 Median:109.50 stddev:2.31 (2.1%) stddev-sans-first:2.42 06:19:07 INFO - PROCESS | 7960 | Values: 110.0 103.0 110.0 109.0 109.0 110.0 112.0 109.0 109.0 109.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:139.00 stddev:22.56 (16.2%) stddev-sans-first:23.45 06:19:07 INFO - PROCESS | 7960 | Values: 154.0 124.0 127.0 195.0 125.0 126.0 147.0 128.0 156.0 131.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 06:19:07 INFO - PROCESS | 7960 | Values: 161.0 160.0 160.0 160.0 161.0 162.0 161.0 161.0 162.0 161.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#3] audio-fft Cycles:10 Average:83.40 Median:76.00 stddev:19.06 (25.1%) stddev-sans-first:19.97 06:19:07 INFO - PROCESS | 7960 | Values: 75.0 74.0 74.0 134.0 77.0 76.0 76.0 76.0 97.0 75.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:83.50 stddev:18.43 (22.1%) stddev-sans-first:19.04 06:19:07 INFO - PROCESS | 7960 | Values: 83.0 84.0 115.0 84.0 84.0 83.0 125.0 84.0 83.0 124.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 06:19:07 INFO - PROCESS | 7960 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 06:19:07 INFO - PROCESS | 7960 | Values: 146.0 147.0 145.0 145.0 145.0 146.0 146.0 146.0 145.0 146.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:19:07 INFO - PROCESS | 7960 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.99 (1.9%) stddev-sans-first:1.00 06:19:07 INFO - PROCESS | 7960 | Values: 52.0 52.0 55.0 53.0 52.0 54.0 52.0 53.0 53.0 53.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 06:19:07 INFO - PROCESS | 7960 | Values: 62.0 61.0 62.0 62.0 62.0 63.0 64.0 62.0 62.0 62.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:80.50 stddev:9.04 (11.2%) stddev-sans-first:9.51 06:19:07 INFO - PROCESS | 7960 | Values: 81.0 80.0 80.0 79.0 91.0 83.0 82.0 80.0 108.0 79.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:120.00 stddev:7.78 (6.5%) stddev-sans-first:8.17 06:19:07 INFO - PROCESS | 7960 | Values: 119.0 120.0 119.0 119.0 119.0 118.0 120.0 122.0 144.0 121.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.40 Median:159.00 stddev:2.55 (1.6%) stddev-sans-first:2.70 06:19:07 INFO - PROCESS | 7960 | Values: 159.0 158.0 163.0 157.0 165.0 158.0 158.0 159.0 159.0 158.0 06:19:07 INFO - PROCESS | 7960 | 06:19:07 INFO - PROCESS | 7960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:1.60 (2.5%) stddev-sans-first:1.54 06:19:07 INFO - PROCESS | 7960 | Values: 61.0 67.0 63.0 63.0 63.0 62.0 62.0 63.0 62.0 63.0 06:19:07 INFO - PROCESS | 7960 | -------- Summary: end -------- 06:19:07 INFO - PROCESS | 7960 | 06:19:08 INFO - TEST-INFO | 7960: exit 0 06:19:08 INFO - TEST-OK | kraken | took 60839ms 06:19:08 INFO - SUITE-END | took 1173s 06:19:08 INFO - Completed test suite (00:19:33) 06:19:08 INFO - javascript benchmark 06:19:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3169.3819705157493, 3196.5844440222754, 3200.4719221328482, 3218.549403083567, 3252.967470325296, 5319.760610772515, 5434.429384914576, 5554.527865131464, 5607.551395888329, 5645.971770141154, 5409.080456322425, 5425.240466334716, 5481.808136715215, 5526.0, 5541.9168712469345, 4826.155422801014, 4934.333864928225, 4937.1606826839525, 4967.950320496785, 5229.633925625208, 5123.871903202413, 5161.380634323887, 5180.1452760294505, 5191.221316802472, 5233.816816411427, 4905.117078925792, 4936.605071594282, 4994.575461085776, 5001.374828146481, 5082.2122571001555, 5038.823641172578, 5054.267131265982, 5056.84829455117, 5131.974340128276, 5170.844874653766, 5048.394192696863, 5083.847484575469, 5089.134847076006, 5096.643234973553, 5120.743962801845, 5271.209318602202, 5388.137897936312, 5439.048166570834, 5515.062439385274, 5528.778848846041, 5904.261967254093, 5944.881102377972, 5961.612495187842, 5963.075723262901, 5964.075568286922, 5758.3665796762325, 5926.97036514819, 5968.432998865101, 5979.701014949279, 5982.611130276561, 5442.183672449125, 5507.146392309159, 5507.7246137693355, 5572.58205634579, 5583.134614134816, 6043.923342459259, 6049.138042644727, 6055.840342407839, 6095.512359011268, 6101.694915254219, 5647.265855438766, 5756.42435756421, 5797.275340582427, 5833.4749872511675, 5851.180834683148, 5847.766089356463, 5999.880002399927, 6005.369436209223, 6007.879842403127, 6009.038553831366, 5841.678707671034, 5874.265716785402, 5882.088276317178, 5895.322037965603, 5919.644821310735, 5844.853878653068, 5875.50058245054, 5924.229850119543, 5933.228680271537, 5940.524758019348, 4487.618552423015, 4534.523874993079, 4543.341215523797, 4561.270196768567, 4562.5665561771575, 4037.596240375939, 4038.353863381845, 4040.1111755413763, 4052.392141178788, 4079.4900637420324, 5388.5419739321815, 5439.5376393006245, 5598.048331783607, 5614.07367784311, 5615.719214039282, 5962.880742385215, 5973.044312909914, 6035.245594300713, 6050.727717252734, 6076.635401875901, 5682.40334764852, 5685.403032681592, 5688.8862222755315, 5689.402612725687, 5696.259486266758, 4978.153713868651, 5027.622928280393, 5114.64197506171, 5182.2485739567555, 5203.661761985459, 6164.352742962014, 6172.475339596095, 6174.660393678301, 6176.567640265228, 6181.072839074085, 5988.161313326141, 6005.549583781147, 6009.819705408845, 6013.158157857991, 6057.878842423215, 5855.648661080349, 6026.668533230714, 6040.7583696651645, 6072.30168530607, 6162.845928851814, 5816.912746308724, 5859.5019423347785, 5862.237909071793, 5871.119332100048, 5872.0, 5902.498287645598, 5971.61184523022, 5981.611195272293, 5982.371850955587, 5989.1914591529585, 5636.492715655447, 5698.6580805151825, 5757.712114394348, 5782.190493330853, 5824.70876456167, 5803.3616302207565, 5810.7094645266725, 5816.331121920864, 5816.680082595498, 5820.039693450533, 5567.331920169606, 5706.629069110494, 5719.48524632785, 5721.427857214246, 5725.398833122462, 5859.326177489644, 5908.143319218775, 5918.378570250062, 6006.519478441627, 6006.669633170218, 5761.711435034601, 5805.76776928935, 5809.5933284671355, 5813.069908814569, 5857.238558987305], "value": 5469.703871295689, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.7407325959725, 638.4286063972743, 641.5990006246096, 647.2233320015982, 651.3811878715219, 32132.196695082606, 45826.77086614566, 47159.528404715944, 47309.52690473103, 47460.0, 46286.305705414445, 46619.53380466194, 46677.29984050233, 46840.29739553909, 46875.76562117185, 8635.0, 8817.779555511099, 8835.072317406677, 8845.690400835972, 8863.379563430562, 8675.95662021692, 8798.340124490656, 8814.603342849587, 8817.29461643067, 8819.941607007167, 8783.472991620523, 8822.911770882272, 8834.337424693142, 8836.513991730451, 8850.114988501131, 8764.21122099011, 8808.0, 8808.955955220246, 8813.867791983126, 8824.426412283245, 8715.738527844174, 8764.21122099014, 8802.603882825288, 8813.325468161029, 8814.955925220333, 41609.79195104035, 41877.0, 41903.790481047705, 41926.0, 42038.36942445866, 3492.1269682579355, 3693.095191678029, 3726.441033844918, 3922.5489068757174, 4179.958200418017, 32246.193845153823, 32369.35261294761, 32789.344213115844, 32807.50788738171, 33397.499037514455, 4382.912341753179, 4391.29739241723, 4442.355858400546, 4442.378067070613, 4516.683832131752, 356781.9999999974, 358309.0, 358644.0, 359198.0, 359490.0, 51775.0, 58562.0, 60243.0, 61664.69167654133, 62192.37807621866, 65455.67272163704, 65511.34488655148, 65728.67135664291, 65734.34265657378, 65880.67059664671, 16904.915475422546, 16954.745678814827, 17216.741748873777, 17264.0, 17460.0, 11858.0, 11929.880701193051, 12015.038796896228, 12057.758844823054, 12752.80870786939, 18430.815691843178, 19328.584957777737, 19460.4473757401, 19566.856589282703, 19588.690488092598, 7270.600116993616, 7332.8900066499045, 7411.777646670608, 7428.33145016951, 7481.962590187123, 967.8064387122604, 1032.3650954662978, 1043.7912417516527, 1046.2571574182264, 1130.4291332876844, 32070.0, 32701.836490817393, 32753.67246327554, 32808.0, 33150.502742458884, 1096.7916095941746, 1147.6843867936218, 1148.3741360958297, 1179.6343133628602, 1215.2283300104498, 17092.743608845878, 17189.398371056952, 17234.224459899335, 17316.787824852392, 17409.738853917203, 548034.0, 551148.0, 552432.0, 553041.0, 556456.0, 57379.0, 59271.0, 59390.703046484494, 59442.405575944555, 59540.0, 1291.8320618319747, 1366.064245991499, 1403.8034675145489, 1404.8524904885044, 1428.4714655577502, 31513.558356405654, 33410.83294583609, 33677.49483757697, 33710.662893370754, 34228.65771342354, 47769.76115119541, 48165.51834481751, 48536.27195591998, 48695.26957095575, 48809.75595122002, 402798.0, 407023.0, 407191.0, 407347.0, 408901.0, 24894.502109958063, 26570.335741606614, 26948.730512694623, 27008.864955675097, 28969.130926072252, 47937.760311198224, 48859.511404886915, 49822.501774981785, 49846.75076624594, 49998.750006249735, 40962.0, 41479.170416590896, 42002.369964451165, 42002.78998604987, 42381.576184239, 40972.79513602413, 41008.1798364037, 41321.79339103285, 41613.37579936243, 41616.375754364315, 40129.598704012584, 41088.58911410848, 41391.0, 41412.0, 41541.0, 35311.0, 36418.81790591136, 36774.63225367819, 36995.63004369922, 37386.0, 27926.3018424541, 28072.157835264974, 28285.29286767765, 28287.0, 28501.287467812643], "value": 20351.249033161454, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.9240144372568, 408.11642793352394, 409.41453721178704, 412.53177643374767, 416.4502856229776, 17878.195481203344, 18967.14647840847, 19239.71140432893, 19371.0, 19406.514837129078, 17864.553386165353, 18540.165692543833, 18625.62748745024, 19173.904130479328, 19278.22887084515, 5363.8122665706705, 5572.832815015556, 5614.9157762633595, 5664.461876121783, 5824.213731146283, 5590.021746194399, 5593.860153496154, 5594.552435805146, 5599.496045355917, 5627.296587926509, 5508.421615730331, 5544.722763861811, 5584.1902924075985, 5601.075822489304, 5615.101583746601, 5528.281323427948, 5569.164625306216, 5572.5263352615275, 5596.356419011825, 5611.691356975365, 6744.93255067448, 7139.857202855914, 7157.6779044943105, 7158.677859496283, 7296.16094149169, 23838.16566420167, 24158.637620435708, 24772.65131221235, 25578.36054098644, 26102.477950440883, 2655.4556315955183, 2709.322669332667, 2720.9319767005786, 3353.6143343515564, 3397.813120278384, 14690.412383504754, 15393.692126157526, 15489.14809685467, 15892.76160857588, 16127.43553975617, 2052.4458396232935, 2075.201047596671, 2092.5187206942337, 2103.5687684024892, 2206.801387875083, 89102.0, 92743.53628231883, 92848.53575732146, 95237.0, 96709.51645241799, 17587.912060439616, 17620.91189544044, 17650.470485885446, 17768.667349948806, 17771.73342399839, 32676.673233267364, 32763.1809204767, 33050.66949330524, 33062.3387532248, 33090.17274568155, 8868.645254189889, 9113.863292050624, 9224.584893679801, 9232.446053236827, 9459.337846350827, 7670.884936725954, 7671.6164191790185, 7774.455788094892, 7794.181610930884, 7800.063992320957, 8684.522351270742, 8858.424202426822, 8862.335324850663, 8898.199162075443, 9187.106061590712, 3144.3901561703733, 3411.822921092219, 3474.6872781449792, 3528.3825330567047, 3531.805750683737, 960.5533426956499, 1023.9078482936571, 1047.706642140202, 1054.4569546683465, 1100.763335882789, 18267.90866045688, 18902.149403276886, 18922.148503317385, 19128.904355478135, 19159.828389629536, 990.9950450247846, 1003.0922015575916, 1027.4503140819625, 1035.803197392493, 1081.8431327457474, 10166.593336266467, 10672.466376681135, 10727.33726272429, 10753.579201693306, 10831.891681083245, 471312.0, 472180.0, 481969.0, 482141.0, 483054.0, 65342.67328663326, 93377.0, 96312.0, 97144.0, 98831.0, 995.9850602240972, 997.2420960070253, 1020.357174979758, 1026.5791025679584, 1036.0261354326908, 15230.543083707507, 16004.359825607311, 16672.083035433167, 17066.0, 17513.0, 40979.59020409877, 41514.37728434015, 41754.0, 41895.33663217849, 42763.0, 52637.47362526326, 53696.46303536915, 57100.0, 57659.13511297418, 58615.4138458627, 7362.889556656547, 7596.08846938371, 7784.649690764042, 8246.216609422096, 8546.743597692079, 9673.806523869624, 10839.512221950188, 11738.237014594024, 12608.936955315165, 12687.746245074863, 11356.772864542498, 11453.59912403062, 11551.960323570918, 11637.825432618687, 11776.4700588472, 11468.311901285615, 11526.481308340977, 11669.299842009506, 11776.88223117792, 11832.408379581158, 11626.244294120856, 11847.881521184678, 11988.64034078979, 12165.0, 12213.206141600767, 7430.92569074324, 7545.0, 7592.696292148252, 8753.91246087531, 8948.910510894808, 9835.901640983499, 11608.477618507019, 11726.765464690488, 11734.589289374831, 11739.530418783153], "value": 10091.106789239562, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.097788015209, 1450.209635748517, 1520.7794869743886, 1607.5659571915583, 1803.1344954421886, 8532.70135545256, 8811.0, 8813.647454101836, 9108.544572771352, 9113.088691130884, 8656.91343086569, 8863.556822158882, 8874.112588741124, 8875.245604123642, 8882.200601945824, 2061.80412860778, 2234.575430668176, 2308.849924754894, 2339.508703172336, 2382.4401265702545, 2398.472336086067, 2404.230646193219, 2404.2546810488716, 2409.6144616861307, 2410.216679579135, 2295.380247333219, 2298.482841360689, 2326.4067662745974, 2327.313442534448, 2333.4866329407505, 2018.4247489465506, 2237.865728056322, 2301.3096071178747, 2310.202979971907, 2329.2080692564523, 2248.6289762189217, 2267.7051983242236, 2274.749777524471, 2275.24916777463, 2277.350954977823, 8784.429012114193, 8826.205641492232, 8853.911460885372, 8874.511901845395, 8876.245519130882, 5270.4729527047375, 5376.274202982587, 5381.0, 5385.569154467672, 5391.48780865821, 3372.37611041958, 3425.4348032574594, 3447.67247111524, 3458.3083383323183, 3528.947065794015, 1118.2004866520397, 1127.8928501792316, 1137.726945533066, 1143.5368675686361, 1147.2886810177743, 12522.874771252262, 12645.178063425848, 12653.177543459646, 12668.68328291791, 12669.176503527333, 4616.930746038812, 4704.458987216514, 4791.891567710197, 4796.784144713496, 4809.78355973982, 10666.093382062456, 10671.83992240117, 10677.78644427107, 10678.038976492153, 10684.786304273872, 5350.785968561292, 5407.540359069444, 5416.837494875082, 5428.945710542844, 5430.049741295257, 4331.155424692218, 4830.565249127595, 5001.674891132065, 5001.7499125043605, 5004.374453193351, 4094.8566800161852, 4129.6489798367475, 4130.339145736668, 4253.48958125027, 4308.763018033976, 1251.949922003128, 1270.9809352859716, 1279.1877158004734, 1288.0, 1290.1098242212843, 766.7891329884327, 881.9955900220586, 896.5606852642159, 897.5781382750097, 910.1171863292728, 6243.188385509855, 6268.62388256697, 6328.968355158287, 6354.078658594478, 6507.804765857032, 967.6419724701906, 976.511744127936, 1008.6671398438498, 1025.8102251083574, 1041.671873359889, 4139.834406623701, 4313.0, 4328.610425061696, 4389.18800021997, 4468.955310446919, 11046.392448415414, 11327.603533876274, 11413.0, 11439.771204575862, 11525.48135333912, 10596.947015264874, 11286.153538484647, 11296.265742726697, 11311.208215425006, 11324.094072474347, 8097.554634495042, 8239.258466737903, 8243.505389676518, 8295.8340833183, 8366.414350995494, 6097.5426842987845, 6206.689665516797, 6330.272018717907, 6370.235571731422, 6435.324290949383, 9933.3543319684, 10254.948725256327, 10463.0, 10480.004399582027, 10660.147188224768, 10466.372017678963, 10607.151427885905, 10677.733056673645, 10762.892371076188, 10763.892361076601, 7013.894791578028, 7760.961195193988, 7877.448578599443, 7914.762557123296, 8021.919780802117, 8719.694810681849, 8738.475691458532, 8786.604602793017, 8815.206631402947, 8839.027706952358, 7561.168271490022, 7715.884261735966, 7746.690132394641, 7774.572398517909, 7854.72508462201, 7466.813329666585, 7666.118396384487, 7682.193369696101, 7785.688572457265, 7808.570528620753, 6910.032595436744, 6967.198772141269, 6990.545614535039, 6992.930070699228, 7002.894956575554, 5105.668131571436, 5226.9215961761365, 5320.494553017457, 5372.704501252469, 5394.0290747665795, 5189.429162792015, 5248.6588371755715, 5305.9204111939125, 5373.11343628297, 5390.703511306916], "value": 4714.8133451737185, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.28062709855334, 338.34867879332285, 343.9896803095908, 346.1571074433754, 349.3502086119818, 5301.416844147146, 5304.416514183446, 5321.308229930113, 5342.332208473941, 5355.250264962909, 4965.0, 5056.570191533724, 5147.279380886679, 5234.947650523493, 5267.130923397644, 4135.27632664284, 4171.416001759756, 4174.937375939363, 4188.308929026707, 4200.957990420087, 4181.498220213579, 4182.100848317611, 4206.747595144286, 4224.831006759726, 4224.894377640569, 3930.6265904739003, 4033.415154802552, 4129.772862492562, 4163.2506148893335, 4165.812538435777, 4126.896827579305, 4140.799168241207, 4143.440635514197, 4146.336586146233, 4168.708190426656, 3693.0, 3697.85208591656, 3700.278445703089, 3707.295613833363, 3712.313222053902, 5000.0, 5072.644914855961, 5075.6447048706605, 5081.212412076134, 5125.410577783566, 4727.952720472786, 4750.738709370983, 4766.451858036336, 4782.593479554242, 4786.0906427778955, 5463.289772329612, 5465.535429488498, 5466.070767969415, 5493.587980901449, 5505.752241149158, 3881.758731459235, 4005.0988527581353, 4040.919181616351, 4041.9191616167514, 4085.407615895708, 5675.177099320616, 5685.260916080924, 5693.629914055573, 5694.430556944314, 5696.943030569682, 4143.19207754485, 4152.979235103805, 4158.355454904495, 4194.832206711759, 4203.138356636943, 4072.0634254121123, 4174.123434078817, 4222.3244280914905, 4264.893377665521, 4266.5946735060115, 4696.637974987284, 4710.858674239778, 4723.4331880174595, 4746.145693775154, 4770.260609605517, 5454.481824226771, 5499.587530935116, 5507.614466987273, 5518.806841760519, 5540.501354878081, 1424.0387738276622, 1438.6547228665045, 1445.284584130862, 1450.9770611718714, 1459.109942934809, 929.9163075323119, 932.8507438809758, 951.990480095204, 952.2145976294448, 952.623713633111, 4784.6889952153, 4806.351142595775, 4811.639127065414, 4821.2045012572535, 4837.612990960785, 5002.59979201663, 5078.162103252922, 5090.389153301628, 5095.261187127846, 5142.202958541432, 4480.753558554311, 4788.683112144188, 4790.473047964721, 4826.879328016757, 4839.644899428165, 2680.316519287569, 2692.9461410771673, 2700.190872115648, 2701.8784154712694, 2705.7158998304894, 5121.231815227671, 5157.58739300855, 5269.499397557226, 5274.446183150791, 5291.544825173031, 5161.793528258902, 5175.16281719984, 5178.818741344035, 5380.650257733235, 5842.415758424208, 5822.5633077518505, 5845.795397161079, 5880.26496687914, 5896.904125334148, 5904.0, 5713.742976545153, 5723.941070901897, 5743.253377060955, 5749.281339832521, 5804.564657650609, 5491.313585801775, 5630.971845140748, 5647.209390685225, 5673.4114447954635, 5691.402402747652, 5808.215890854681, 5867.178594996619, 5875.941240587539, 5910.822675319747, 5935.881282374415, 3838.7312888097563, 4075.4090656855183, 4083.4895638045246, 4086.5096188457574, 4110.34234522475, 5613.298337707786, 5674.971625141848, 5714.142878568248, 5715.142728590745, 5784.334801497882, 5521.950829342412, 5562.860928476659, 5586.888262234651, 5610.18652295423, 5645.915311270416, 5456.61803673739, 5571.253556749902, 5580.469855363896, 5591.748371323219, 5660.575456840671, 5471.835844924659, 5493.945060549343, 5503.229547863382, 5506.0, 5519.613627046068, 5630.690312032878, 5676.858078548069, 5678.716064196857, 5705.7147142643535, 5709.628874123335, 5209.53114219722, 5509.311336082989, 5529.834104976857, 5546.611737178358, 5560.443955604407], "value": 4180.551530616935, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.3005235183631, 789.9328557072648, 793.6547601793219, 845.5265051571121, 846.407514739682, 14131.788023179648, 14846.183459909715, 14902.925485372585, 14916.850831491709, 14934.178620175899, 14613.196274204915, 14652.194129322883, 14657.633559161026, 14683.77974330386, 14689.706205875877, 685.8216863615446, 802.4623502253474, 828.4656396624204, 834.586879494651, 835.1147783349669, 818.5825229133155, 823.6705317872869, 823.8846561481397, 825.5129473610568, 826.1449399871135, 725.0791494801599, 881.1144799476549, 882.2897567458226, 882.9426087304305, 883.0, 846.1749793950879, 859.3769517100114, 865.6623916672503, 866.4714524140269, 867.3148212911792, 629.6694235526385, 630.3035146163515, 630.6342321453546, 630.9432151106422, 632.1024145713099, 13908.443662253498, 13931.512397066053, 13932.094413863066, 13937.512187073404, 13986.300684965812, 13682.31588420585, 13813.654658633513, 13844.30778461083, 13851.722965540734, 13869.583912482642, 12359.134860559765, 12366.876331236663, 12382.380880956007, 12395.814062789066, 12608.432620532098, 4386.7367957922625, 4411.448568928884, 4439.400680908068, 4476.306172543261, 4479.4176757021705, 57148.0, 57928.0, 58130.41869581334, 59647.70176149135, 60682.696586517224, 10593.205509586813, 10671.575890919357, 10756.677299681021, 10962.835557466644, 11034.337939723642, 20762.792372076387, 20769.688454673193, 20778.48053798637, 20793.1682732689, 20814.479638008866, 8004.0, 8009.239122283374, 8164.183581641788, 8255.091939886608, 8278.83442331162, 4627.907441851212, 4720.575148236675, 4724.976375118103, 4746.667733258639, 4800.183968725325, 3335.7498187636024, 3396.847141878621, 3399.1841957930415, 3403.2002479417315, 3447.0692912913373, 610.6305685060474, 616.6947361056306, 622.8069298517474, 632.6488798716664, 635.9491240700733, 566.5835610826122, 570.5834740639274, 583.6789765628887, 666.1805978646292, 674.9190097188368, 9208.073760279092, 9375.765605859771, 9455.952720236355, 9520.8571871422, 9725.756856078655, 623.370395900135, 661.1075048684238, 703.5708217987024, 706.8303607134251, 707.2008630247788, 4265.168292182994, 4285.314349704033, 4333.241682705514, 4392.780360981938, 4458.888527786831, 67491.32508674948, 67784.6610766943, 68650.0, 70139.2986070143, 70376.296237038, 58403.41596584065, 58908.705456472446, 60889.0, 60964.08553871696, 61533.0, 54157.72921135448, 54221.1866821998, 55091.724541377036, 56609.71695141498, 57426.0, 8328.708495202638, 8392.244697977212, 8510.574471276288, 8584.484930904166, 8679.0, 37427.625723743506, 37663.246735065695, 37751.00801929754, 37813.62186378101, 38206.0, 49715.0, 50859.23711144262, 51197.48802511927, 51459.0, 51590.0, 11549.48027338755, 11668.766624667289, 11694.415279236175, 11840.881591184323, 11976.401179940794, 12026.398680065786, 12063.353228539989, 12463.626091217278, 12522.87477125217, 12589.74820503603, 10753.085987690822, 11165.609203677832, 11317.151213658844, 11485.770284594428, 11666.941665291959, 11743.47154378072, 11872.52509899629, 11875.0, 12004.819927700917, 12162.270263784201, 11955.521779128738, 12035.819462707892, 12086.637400877637, 12125.69685757863, 12576.371181441074, 9888.456134912649, 10020.599176032876, 10067.0, 10116.140128089195, 10127.139193168663, 9981.550830212513, 9989.650362237287, 10043.0, 10070.748231294276, 10078.949605251926], "value": 6800.805528859068, "unit": "score"}], "name": "dromaeo_css", "value": 7294.011047594649, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 110.0, 109.0, 109.0, 110.0, 112.0, 109.0, 109.0, 109.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 127.0, 195.0, 125.0, 126.0, 147.0, 128.0, 156.0, 131.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 162.0, 161.0, 161.0, 162.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 134.0, 77.0, 76.0, 76.0, 76.0, 97.0, 75.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 115.0, 84.0, 84.0, 83.0, 125.0, 84.0, 83.0, 124.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 55.0, 53.0, 52.0, 54.0, 52.0, 53.0, 53.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 62.0, 62.0, 63.0, 64.0, 62.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 80.0, 79.0, 91.0, 83.0, 82.0, 80.0, 108.0, 79.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 119.0, 119.0, 119.0, 118.0, 120.0, 122.0, 144.0, 121.0], "value": 122.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 163.0, 157.0, 165.0, 158.0, 158.0, 159.0, 159.0, 158.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 67.0, 63.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 63.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1490.7, "alertThreshold": 2.0}]} 06:19:08 INFO - Return code: 0 06:19:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:19:08 ERROR - # TBPL SUCCESS # 06:19:08 INFO - Running post-action listener: _resource_record_post_action 06:19:08 INFO - ##### 06:19:08 INFO - ##### Finished run-tests step (success) 06:19:08 INFO - ##### 06:19:08 INFO - Running post-run listener: _resource_record_post_run 06:19:08 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547328000; Read time: 20; Write time: 512912 06:19:08 INFO - TinderboxPrint: CPU usage
13.2% 06:19:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 06:19:08 INFO - TinderboxPrint: I/O write bytes / time
547,328,000 / 512,912 06:19:08 INFO - TinderboxPrint: CPU idle
8,250.5 (86.8%) 06:19:08 INFO - TinderboxPrint: CPU user
1,210.8 (12.7%) 06:19:08 INFO - TinderboxPrint: Swap in / out
0 / 0 06:19:08 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 258727936; Read time: 0; Write time: 277432 06:19:08 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288448512; Read time: 20; Write time: 235456 06:19:09 INFO - Running post-run listener: _upload_blobber_files 06:19:09 INFO - Blob upload gear active. 06:19:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:19:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:19:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:19:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:19:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:19:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:19:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:10 INFO - (blobuploader) - INFO - Done attempting. 06:19:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:19:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:19:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:11 INFO - (blobuploader) - INFO - Done attempting. 06:19:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:19:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:19:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:16 INFO - (blobuploader) - INFO - Done attempting. 06:19:16 INFO - (blobuploader) - INFO - Iteration through files over. 06:19:16 INFO - Return code: 0 06:19:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:19:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:19:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/292cc756eaa7380bc2cb88e4da59455e38e96c201f278b18d8c34a601ab3647ea209970e52e33bbfaa3881427b3b36fb7fd080392f4627b6073c3ffea430f708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caa8962732cca8c7f26556e4b31f06f4dd64344544f54795e53016bda2bfa1e42814f07ac7a962c2c645fbe5bee6bd990fed74117d31a16804ac550656f94431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e58bc087374b34db185c028201745c5833d2caa04bfa208e86fb565ad861b2eb1ab1bfc12eb8e16196cb0a1688bdfbe8451380f3a04acceb62e43a834b50ad0"} 06:19:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:19:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:19:16 INFO - Contents: 06:19:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/292cc756eaa7380bc2cb88e4da59455e38e96c201f278b18d8c34a601ab3647ea209970e52e33bbfaa3881427b3b36fb7fd080392f4627b6073c3ffea430f708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caa8962732cca8c7f26556e4b31f06f4dd64344544f54795e53016bda2bfa1e42814f07ac7a962c2c645fbe5bee6bd990fed74117d31a16804ac550656f94431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e58bc087374b34db185c028201745c5833d2caa04bfa208e86fb565ad861b2eb1ab1bfc12eb8e16196cb0a1688bdfbe8451380f3a04acceb62e43a834b50ad0"} 06:19:16 INFO - Running post-run listener: copy_logs_to_upload_dir 06:19:16 INFO - Copying logs to upload dir... 06:19:16 INFO - mkdir: /builds/slave/test/build/upload/logs 06:19:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.937603 ========= master_lag: 313.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 47 secs) (at 2016-06-01 06:24:29.596377) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 06:24:29.602992) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1464785920.385426-1563640472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/292cc756eaa7380bc2cb88e4da59455e38e96c201f278b18d8c34a601ab3647ea209970e52e33bbfaa3881427b3b36fb7fd080392f4627b6073c3ffea430f708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caa8962732cca8c7f26556e4b31f06f4dd64344544f54795e53016bda2bfa1e42814f07ac7a962c2c645fbe5bee6bd990fed74117d31a16804ac550656f94431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e58bc087374b34db185c028201745c5833d2caa04bfa208e86fb565ad861b2eb1ab1bfc12eb8e16196cb0a1688bdfbe8451380f3a04acceb62e43a834b50ad0"} build_url:https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/Lc2sD23fRjK9D0MZroVWBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/292cc756eaa7380bc2cb88e4da59455e38e96c201f278b18d8c34a601ab3647ea209970e52e33bbfaa3881427b3b36fb7fd080392f4627b6073c3ffea430f708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caa8962732cca8c7f26556e4b31f06f4dd64344544f54795e53016bda2bfa1e42814f07ac7a962c2c645fbe5bee6bd990fed74117d31a16804ac550656f94431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e58bc087374b34db185c028201745c5833d2caa04bfa208e86fb565ad861b2eb1ab1bfc12eb8e16196cb0a1688bdfbe8451380f3a04acceb62e43a834b50ad0"}' ========= master_lag: 0.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 06:24:29.913164) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-06-01 06:24:29.913471) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1464785920.385426-1563640472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 2.31 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-06-01 06:24:32.226358) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2016-06-01 06:24:32.226725) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2016-06-01 06:24:39.137421) ========= ========= Total master_lag: 315.90 =========