builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1463559813.56 results: success (0) buildid: 20160517223201 builduid: e605c79088354fdaa55256757bf8ffc3 revision: cb356a5f82cdc4f1e44165c96906b04742ed6311 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.555973) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.556516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.556863) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.591147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.591489) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-18 01:23:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-05-18 01:23:33 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107262 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.712669) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.713062) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017192 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:33.778744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 01:23:33.779226) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cb356a5f82cdc4f1e44165c96906b04742ed6311 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cb356a5f82cdc4f1e44165c96906b04742ed6311 --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=c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-18 01:23:33,858 truncating revision to first 12 chars 2016-05-18 01:23:33,858 Setting DEBUG logging. 2016-05-18 01:23:33,858 attempt 1/10 2016-05-18 01:23:33,858 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/cb356a5f82cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 01:23:34,866 unpacking tar archive at: mozilla-inbound-cb356a5f82cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.302283 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 01:23:35.095192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:35.095779) ========= script_repo_revision: cb356a5f82cdc4f1e44165c96906b04742ed6311 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:35.096477) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:35.097028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 01:23:35.112847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-18 01:23:35.113168) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:23:35 INFO - MultiFileLogger online at 20160518 01:23:35 in /builds/slave/test 01:23:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 01:23:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:23:35 INFO - {'append_to_log': False, 01:23:35 INFO - 'base_work_dir': '/builds/slave/test', 01:23:35 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 01:23:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:23:35 INFO - 'branch': 'Mozilla-Inbound', 01:23:35 INFO - 'buildbot_json_path': 'buildprops.json', 01:23:35 INFO - 'config_files': ('talos/linux_config.py',), 01:23:35 INFO - 'default_actions': ('clobber', 01:23:35 INFO - 'read-buildbot-config', 01:23:35 INFO - 'download-and-extract', 01:23:35 INFO - 'populate-webroot', 01:23:35 INFO - 'create-virtualenv', 01:23:35 INFO - 'install', 01:23:35 INFO - 'run-tests'), 01:23:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:23:35 INFO - 'download_minidump_stackwalk': True, 01:23:35 INFO - 'download_symbols': 'ondemand', 01:23:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:23:35 INFO - 'tooltool.py': '/tools/tooltool.py', 01:23:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:23:35 INFO - '/tools/misc-python/virtualenv.py')}, 01:23:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:23:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:23:35 INFO - 'installer_path': 'installer.exe', 01:23:35 INFO - 'log_level': 'info', 01:23:35 INFO - 'log_name': 'talos', 01:23:35 INFO - 'log_to_console': True, 01:23:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:23:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:23:35 INFO - 'opt_config_files': (), 01:23:35 INFO - 'pip_index': False, 01:23:35 INFO - 'sps_profile': False, 01:23:35 INFO - 'sps_profile_interval': 0, 01:23:35 INFO - 'suite': 'dromaeojs', 01:23:35 INFO - 'system_bits': '32', 01:23:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:23:35 INFO - 'title': 'talos-linux64-ix-004', 01:23:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:23:35 INFO - 'use_talos_json': True, 01:23:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:23:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:23:35 INFO - 'work_dir': 'build'} 01:23:35 INFO - ##### 01:23:35 INFO - ##### Running clobber step. 01:23:35 INFO - ##### 01:23:35 INFO - Running pre-action listener: _resource_record_pre_action 01:23:35 INFO - Running main action method: clobber 01:23:35 INFO - rmtree: /builds/slave/test/build 01:23:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:23:35 INFO - Running post-action listener: _resource_record_post_action 01:23:35 INFO - ##### 01:23:35 INFO - ##### Finished clobber step (success) 01:23:35 INFO - ##### 01:23:35 INFO - ##### 01:23:35 INFO - ##### Running read-buildbot-config step. 01:23:35 INFO - ##### 01:23:35 INFO - Running pre-action listener: _resource_record_pre_action 01:23:35 INFO - Running main action method: read_buildbot_config 01:23:35 INFO - Using buildbot properties: 01:23:35 INFO - { 01:23:35 INFO - "project": "", 01:23:35 INFO - "product": "firefox", 01:23:35 INFO - "script_repo_revision": "production", 01:23:35 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 01:23:35 INFO - "repository": "", 01:23:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 01:23:35 INFO - "buildid": "20160517223201", 01:23:35 INFO - "pgo_build": "True", 01:23:35 INFO - "basedir": "/builds/slave/test", 01:23:35 INFO - "buildnumber": 1019, 01:23:35 INFO - "slavename": "talos-linux64-ix-004", 01:23:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:23:35 INFO - "platform": "ubuntu64_hw", 01:23:35 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 01:23:35 INFO - "branch": "mozilla-inbound", 01:23:35 INFO - "repo_path": "integration/mozilla-inbound", 01:23:35 INFO - "revision": "cb356a5f82cdc4f1e44165c96906b04742ed6311", 01:23:35 INFO - "stage_platform": "linux64-pgo", 01:23:35 INFO - "builduid": "e605c79088354fdaa55256757bf8ffc3", 01:23:35 INFO - "slavebuilddir": "test" 01:23:35 INFO - } 01:23:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 01:23:35 INFO - Running post-action listener: _resource_record_post_action 01:23:35 INFO - ##### 01:23:35 INFO - ##### Finished read-buildbot-config step (success) 01:23:35 INFO - ##### 01:23:35 INFO - ##### 01:23:35 INFO - ##### Running download-and-extract step. 01:23:35 INFO - ##### 01:23:35 INFO - Running pre-action listener: _resource_record_pre_action 01:23:35 INFO - Running main action method: download_and_extract 01:23:35 INFO - mkdir: /builds/slave/test/build/tests 01:23:35 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')]} 01:23:35 INFO - https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:23:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:23:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:23:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:43 INFO - Downloaded 1361 bytes. 01:23:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:23:43 INFO - Using the following test package requirements: 01:23:43 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:23:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:23:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:23:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'jsshell-linux-x86_64.zip'], 01:23:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:23:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:23:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:23:43 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:23:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:23:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:23:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:23:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:23: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')]} 01:23:43 INFO - https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:23:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:23:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:23:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:49 INFO - Downloaded 24347545 bytes. 01:23:49 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'] 01:23:49 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 01:23:50 INFO - Return code: 0 01:23:50 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 01:23: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')]} 01:23:50 INFO - https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:23:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:23:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:23:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:51 INFO - Downloaded 24347545 bytes. 01:23:51 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'] 01:23:51 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 01:23:52 INFO - Return code: 0 01:23:52 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')]} 01:23:52 INFO - https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:23:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:23:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:23:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:53 INFO - Downloaded 10840194 bytes. 01:23:53 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'] 01:23:53 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 01:23:54 INFO - Return code: 0 01:23: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')]} 01:23:54 INFO - https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:23:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:23:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:23:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-SEj3maTjqcBtwSei5wSw/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 01:23:55 INFO - Downloaded 58335699 bytes. 01:23:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:23:55 INFO - mkdir: /builds/slave/test/properties 01:23:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:23:55 INFO - Writing to file /builds/slave/test/properties/build_url 01:23:55 INFO - Contents: 01:23:55 INFO - build_url:https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:23:56 INFO - Running post-action listener: _resource_record_post_action 01:23:56 INFO - Running post-action listener: set_extra_try_arguments 01:23:56 INFO - ##### 01:23:56 INFO - ##### Finished download-and-extract step (success) 01:23:56 INFO - ##### 01:23:56 INFO - ##### 01:23:56 INFO - ##### Running populate-webroot step. 01:23:56 INFO - ##### 01:23:56 INFO - Running pre-action listener: _resource_record_pre_action 01:23:56 INFO - Running main action method: populate_webroot 01:23:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:23:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:23:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:23:56 INFO - '--tppagecycles', 01:23:56 INFO - '7'], 01:23:56 INFO - 'tests': ['tsvgm']}}, 01:23:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:23:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:23:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:23:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:23:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:23:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:23:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['damp', 'tps']}, 01:23:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'tests': ['damp', 'tps']}, 01:23:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['dromaeo_dom']}, 01:23:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:23:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['a11yr', 01:23:56 INFO - 'ts_paint', 01:23:56 INFO - 'tpaint', 01:23:56 INFO - 'sessionrestore', 01:23:56 INFO - 'sessionrestore_no_auto_restore', 01:23:56 INFO - 'tabpaint']}, 01:23:56 INFO - 'other-e10s': {'tests': ['a11yr', 01:23:56 INFO - 'ts_paint', 01:23:56 INFO - 'tpaint', 01:23:56 INFO - 'sessionrestore', 01:23:56 INFO - 'sessionrestore_no_auto_restore', 01:23:56 INFO - 'tabpaint']}, 01:23:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:23:56 INFO - 'ts_paint', 01:23:56 INFO - 'tpaint', 01:23:56 INFO - 'sessionrestore', 01:23:56 INFO - 'sessionrestore_no_auto_restore', 01:23:56 INFO - 'tabpaint']}, 01:23:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:23:56 INFO - 'ts_paint', 01:23:56 INFO - 'tpaint', 01:23:56 INFO - 'sessionrestore', 01:23:56 INFO - 'sessionrestore_no_auto_restore', 01:23:56 INFO - 'tabpaint']}, 01:23:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['a11yr', 01:23:56 INFO - 'ts_paint', 01:23:56 INFO - 'tpaint', 01:23:56 INFO - 'sessionrestore', 01:23:56 INFO - 'sessionrestore_no_auto_restore', 01:23:56 INFO - 'tabpaint']}, 01:23:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['a11yr', 01:23:56 INFO - 'ts_paint', 01:23:56 INFO - 'tpaint', 01:23:56 INFO - 'sessionrestore', 01:23:56 INFO - 'sessionrestore_no_auto_restore', 01:23:56 INFO - 'tabpaint']}, 01:23:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['tsvgx', 01:23:56 INFO - 'tsvgr_opacity', 01:23:56 INFO - 'tart', 01:23:56 INFO - 'tscrollx', 01:23:56 INFO - 'cart']}, 01:23:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:23:56 INFO - 'tsvgr_opacity', 01:23:56 INFO - 'tart', 01:23:56 INFO - 'tscrollx', 01:23:56 INFO - 'cart']}, 01:23:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'talos_options': ['--disable-e10s'], 01:23:56 INFO - 'tests': ['tp5o']}, 01:23:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'tests': ['tp5o']}, 01:23:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'talos_options': ['--disable-e10s', 01:23:56 INFO - '--xperf_path', 01:23:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:23:56 INFO - 'tests': ['tp5n']}, 01:23:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:23:56 INFO - 'talos_options': ['--xperf_path', 01:23:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:23:56 INFO - 'tests': ['tp5n']}}, 01:23:56 INFO - 'talos.zip': {'path': '', 01:23:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:23:56 INFO - Running post-action listener: _resource_record_post_action 01:23:56 INFO - ##### 01:23:56 INFO - ##### Finished populate-webroot step (success) 01:23:56 INFO - ##### 01:23:56 INFO - ##### 01:23:56 INFO - ##### Running create-virtualenv step. 01:23:56 INFO - ##### 01:23:56 INFO - Running pre-action listener: _resource_record_pre_action 01:23:56 INFO - Running main action method: create_virtualenv 01:23:56 INFO - Creating virtualenv /builds/slave/test/build/venv 01:23:56 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 01:23:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:23:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:23:56 INFO - Using real prefix '/usr' 01:23:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:23:56 INFO - Installing distribute.............................................................................................................................................................................................done. 01:23:58 INFO - Installing pip.................done. 01:23:58 INFO - Return code: 0 01:23:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:23:58 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')]} 01:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:23:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:58 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')]} 01:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:23:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:58 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:23:58 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 01:23:58 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 01:23:58 INFO - Using env: {'DISPLAY': ':0', 01:23:58 INFO - 'HOME': '/home/cltbld', 01:23:58 INFO - 'LANG': 'en_US.UTF-8', 01:23:58 INFO - 'LANGUAGE': 'en_US:en', 01:23:58 INFO - 'LOGNAME': 'cltbld', 01:23:58 INFO - 'MAIL': '/var/mail/cltbld', 01:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:23:58 INFO - 'MOZ_NO_REMOTE': '1', 01:23:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:23:58 INFO - 'NO_EM_RESTART': '1', 01:23:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:23:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:23:58 INFO - 'PWD': '/builds/slave/test', 01:23:58 INFO - 'SHELL': '/bin/bash', 01:23:58 INFO - 'SHLVL': '1', 01:23:58 INFO - 'TERM': 'linux', 01:23:58 INFO - 'TMOUT': '86400', 01:23:58 INFO - 'USER': 'cltbld', 01:23:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:23:58 INFO - '_': '/tools/buildbot/bin/python'} 01:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:23:58 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 01:23:58 INFO - Cleaning up... 01:23:58 INFO - Return code: 0 01:23:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:23:58 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')]} 01:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:23:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:58 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')]} 01:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:23:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:58 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:23:58 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 01:23:58 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 01:23:58 INFO - Using env: {'DISPLAY': ':0', 01:23:58 INFO - 'HOME': '/home/cltbld', 01:23:58 INFO - 'LANG': 'en_US.UTF-8', 01:23:58 INFO - 'LANGUAGE': 'en_US:en', 01:23:58 INFO - 'LOGNAME': 'cltbld', 01:23:58 INFO - 'MAIL': '/var/mail/cltbld', 01:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:23:58 INFO - 'MOZ_NO_REMOTE': '1', 01:23:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:23:58 INFO - 'NO_EM_RESTART': '1', 01:23:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:23:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:23:58 INFO - 'PWD': '/builds/slave/test', 01:23:58 INFO - 'SHELL': '/bin/bash', 01:23:58 INFO - 'SHLVL': '1', 01:23:58 INFO - 'TERM': 'linux', 01:23:58 INFO - 'TMOUT': '86400', 01:23:58 INFO - 'USER': 'cltbld', 01:23:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:23:58 INFO - '_': '/tools/buildbot/bin/python'} 01:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:23:59 INFO - Downloading/unpacking psutil>=3.1.1 01:23:59 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 01:23:59 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 01:23:59 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 01:23:59 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 01:23:59 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 01:23:59 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 01:24:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:24:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:24:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:24:01 INFO - Installing collected packages: psutil 01:24:01 INFO - Running setup.py install for psutil 01:24:01 INFO - building 'psutil._psutil_linux' extension 01:24:01 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 01:24:01 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 01:24:01 INFO - building 'psutil._psutil_posix' extension 01:24:01 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 01:24:01 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 01:24:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:24:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:24:01 INFO - Successfully installed psutil 01:24:01 INFO - Cleaning up... 01:24:01 INFO - Return code: 0 01:24:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:24:01 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')]} 01:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:01 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')]} 01:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:01 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:24:01 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 01:24:01 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 01:24:01 INFO - Using env: {'DISPLAY': ':0', 01:24:01 INFO - 'HOME': '/home/cltbld', 01:24:01 INFO - 'LANG': 'en_US.UTF-8', 01:24:01 INFO - 'LANGUAGE': 'en_US:en', 01:24:01 INFO - 'LOGNAME': 'cltbld', 01:24:01 INFO - 'MAIL': '/var/mail/cltbld', 01:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:01 INFO - 'MOZ_NO_REMOTE': '1', 01:24:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:01 INFO - 'NO_EM_RESTART': '1', 01:24:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:01 INFO - 'PWD': '/builds/slave/test', 01:24:01 INFO - 'SHELL': '/bin/bash', 01:24:01 INFO - 'SHLVL': '1', 01:24:01 INFO - 'TERM': 'linux', 01:24:01 INFO - 'TMOUT': '86400', 01:24:01 INFO - 'USER': 'cltbld', 01:24:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:01 INFO - '_': '/tools/buildbot/bin/python'} 01:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:24:01 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 01:24:01 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 01:24:01 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 01:24:01 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 01:24:01 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 01:24:01 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 01:24:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:24:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:24:03 INFO - Installing collected packages: mozsystemmonitor 01:24:03 INFO - Running setup.py install for mozsystemmonitor 01:24:03 INFO - Successfully installed mozsystemmonitor 01:24:03 INFO - Cleaning up... 01:24:03 INFO - Return code: 0 01:24:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:24: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')]} 01:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24: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')]} 01:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:03 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:24:03 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 01:24:03 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 01:24:03 INFO - Using env: {'DISPLAY': ':0', 01:24:03 INFO - 'HOME': '/home/cltbld', 01:24:03 INFO - 'LANG': 'en_US.UTF-8', 01:24:03 INFO - 'LANGUAGE': 'en_US:en', 01:24:03 INFO - 'LOGNAME': 'cltbld', 01:24:03 INFO - 'MAIL': '/var/mail/cltbld', 01:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:03 INFO - 'MOZ_NO_REMOTE': '1', 01:24:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:03 INFO - 'NO_EM_RESTART': '1', 01:24:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:03 INFO - 'PWD': '/builds/slave/test', 01:24:03 INFO - 'SHELL': '/bin/bash', 01:24:03 INFO - 'SHLVL': '1', 01:24:03 INFO - 'TERM': 'linux', 01:24:03 INFO - 'TMOUT': '86400', 01:24:03 INFO - 'USER': 'cltbld', 01:24:03 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:03 INFO - '_': '/tools/buildbot/bin/python'} 01:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:04 INFO - Downloading/unpacking blobuploader==1.2.4 01:24:04 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 01:24:04 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 01:24:04 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 01:24:04 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 01:24:04 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 01:24:04 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 01:24:06 INFO - Downloading blobuploader-1.2.4.tar.gz 01:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:24:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:24:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 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 01:24:06 INFO - Downloading docopt-0.6.1.tar.gz 01:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:24:06 INFO - Installing collected packages: blobuploader, requests, docopt 01:24:06 INFO - Running setup.py install for blobuploader 01:24:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:24:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:24:06 INFO - Running setup.py install for requests 01:24:07 INFO - Running setup.py install for docopt 01:24:07 INFO - Successfully installed blobuploader requests docopt 01:24:07 INFO - Cleaning up... 01:24:07 INFO - Return code: 0 01:24:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:24: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')]} 01:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24: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')]} 01:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:07 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:24:07 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 01:24:07 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 01:24:07 INFO - Using env: {'DISPLAY': ':0', 01:24:07 INFO - 'HOME': '/home/cltbld', 01:24:07 INFO - 'LANG': 'en_US.UTF-8', 01:24:07 INFO - 'LANGUAGE': 'en_US:en', 01:24:07 INFO - 'LOGNAME': 'cltbld', 01:24:07 INFO - 'MAIL': '/var/mail/cltbld', 01:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:07 INFO - 'MOZ_NO_REMOTE': '1', 01:24:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:07 INFO - 'NO_EM_RESTART': '1', 01:24:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:07 INFO - 'PWD': '/builds/slave/test', 01:24:07 INFO - 'SHELL': '/bin/bash', 01:24:07 INFO - 'SHLVL': '1', 01:24:07 INFO - 'TERM': 'linux', 01:24:07 INFO - 'TMOUT': '86400', 01:24:07 INFO - 'USER': 'cltbld', 01:24:07 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:07 INFO - '_': '/tools/buildbot/bin/python'} 01:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:24:07 INFO - Running setup.py (path:/tmp/pip-cTVg92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:24:07 INFO - Running setup.py (path:/tmp/pip-D6AyOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:24:07 INFO - Running setup.py (path:/tmp/pip-pEFM9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:24:07 INFO - Running setup.py (path:/tmp/pip-gsBITg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:24:07 INFO - Running setup.py (path:/tmp/pip-JBPG9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:24:08 INFO - Running setup.py (path:/tmp/pip-t51aF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:24:08 INFO - Running setup.py (path:/tmp/pip-vr7yKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:24:08 INFO - Running setup.py (path:/tmp/pip-xkSfvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:24:08 INFO - Running setup.py (path:/tmp/pip-6Lx3oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:24:08 INFO - Running setup.py (path:/tmp/pip-XzzaHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:24:08 INFO - Running setup.py (path:/tmp/pip-f5nZOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:24:08 INFO - Running setup.py (path:/tmp/pip-Q6F5UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:24:08 INFO - Running setup.py (path:/tmp/pip-MouFoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:24:08 INFO - Running setup.py (path:/tmp/pip-ZM4MPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:08 INFO - Running setup.py (path:/tmp/pip-K9BYKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:24:08 INFO - Running setup.py (path:/tmp/pip-_fVKVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:24:08 INFO - Running setup.py (path:/tmp/pip-x2DPzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:24:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:24:08 INFO - Running setup.py install for manifestparser 01:24:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:24:08 INFO - Running setup.py install for mozcrash 01:24:09 INFO - Running setup.py install for mozdebug 01:24:09 INFO - Running setup.py install for mozdevice 01:24:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Running setup.py install for mozfile 01:24:09 INFO - Running setup.py install for mozhttpd 01:24:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Running setup.py install for mozinfo 01:24:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Running setup.py install for mozInstall 01:24:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Running setup.py install for mozleak 01:24:09 INFO - Running setup.py install for mozlog 01:24:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Running setup.py install for moznetwork 01:24:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:24:09 INFO - Running setup.py install for mozprocess 01:24:10 INFO - Running setup.py install for mozprofile 01:24:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:24:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:24:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:24:10 INFO - Running setup.py install for mozrunner 01:24:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:24:10 INFO - Running setup.py install for mozscreenshot 01:24:10 INFO - Running setup.py install for moztest 01:24:10 INFO - Running setup.py install for mozversion 01:24:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:24:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:24:10 INFO - Cleaning up... 01:24:10 INFO - Return code: 0 01:24:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:24: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')]} 01:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24: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')]} 01:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:10 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:24:10 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 01:24:10 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 01:24:10 INFO - Using env: {'DISPLAY': ':0', 01:24:10 INFO - 'HOME': '/home/cltbld', 01:24:10 INFO - 'LANG': 'en_US.UTF-8', 01:24:10 INFO - 'LANGUAGE': 'en_US:en', 01:24:10 INFO - 'LOGNAME': 'cltbld', 01:24:10 INFO - 'MAIL': '/var/mail/cltbld', 01:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:10 INFO - 'MOZ_NO_REMOTE': '1', 01:24:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:10 INFO - 'NO_EM_RESTART': '1', 01:24:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:10 INFO - 'PWD': '/builds/slave/test', 01:24:10 INFO - 'SHELL': '/bin/bash', 01:24:10 INFO - 'SHLVL': '1', 01:24:10 INFO - 'TERM': 'linux', 01:24:10 INFO - 'TMOUT': '86400', 01:24:10 INFO - 'USER': 'cltbld', 01:24:10 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:10 INFO - '_': '/tools/buildbot/bin/python'} 01:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:24:10 INFO - Running setup.py (path:/tmp/pip-8EKf0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:24:10 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)) 01:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:24:10 INFO - Running setup.py (path:/tmp/pip-RRFyuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:24:10 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)) 01:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:24:10 INFO - Running setup.py (path:/tmp/pip-mOrr8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:24:11 INFO - Running setup.py (path:/tmp/pip-nF0epY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:24:11 INFO - Running setup.py (path:/tmp/pip-q8E01N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:24:11 INFO - Running setup.py (path:/tmp/pip-DSgb11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:24:11 INFO - Running setup.py (path:/tmp/pip-Jj6Ct7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:24:11 INFO - Running setup.py (path:/tmp/pip-r6kCD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:24:11 INFO - Running setup.py (path:/tmp/pip-NL7Tdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:24:11 INFO - Running setup.py (path:/tmp/pip-Nww7gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:24:11 INFO - Running setup.py (path:/tmp/pip-MnD42s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:24:11 INFO - Running setup.py (path:/tmp/pip-A3lFia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:24:11 INFO - Running setup.py (path:/tmp/pip-0H6byL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:24:11 INFO - Running setup.py (path:/tmp/pip-aOEs0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:11 INFO - Running setup.py (path:/tmp/pip-5NNUhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:11 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)) 01:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:24:11 INFO - Running setup.py (path:/tmp/pip-vjXd7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:24:12 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)) 01:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:24:12 INFO - Running setup.py (path:/tmp/pip-QN7G0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:24:12 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)) 01:24:12 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)) 01:24:12 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)) 01:24:12 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)) 01:24:12 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)) 01:24:12 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)) 01:24:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:24:12 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 01:24:12 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 01:24:12 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 01:24:12 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 01:24:12 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 01:24:12 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 01:24:13 INFO - Downloading blessings-1.6.tar.gz 01:24:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:24:14 INFO - Installing collected packages: blessings 01:24:14 INFO - Running setup.py install for blessings 01:24:14 INFO - Successfully installed blessings 01:24:14 INFO - Cleaning up... 01:24:14 INFO - Return code: 0 01:24:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:24:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:24:14 INFO - Reading from file tmpfile_stdout 01:24:14 INFO - Current package versions: 01:24:14 INFO - argparse == 1.2.1 01:24:14 INFO - blessings == 1.6 01:24:14 INFO - blobuploader == 1.2.4 01:24:14 INFO - docopt == 0.6.1 01:24:14 INFO - manifestparser == 1.1 01:24:14 INFO - mozInstall == 1.12 01:24:14 INFO - mozcrash == 0.17 01:24:14 INFO - mozdebug == 0.1 01:24:14 INFO - mozdevice == 0.48 01:24:14 INFO - mozfile == 1.2 01:24:14 INFO - mozhttpd == 0.7 01:24:14 INFO - mozinfo == 0.9 01:24:14 INFO - mozleak == 0.1 01:24:14 INFO - mozlog == 3.2 01:24:14 INFO - moznetwork == 0.27 01:24:14 INFO - mozprocess == 0.22 01:24:14 INFO - mozprofile == 0.28 01:24:14 INFO - mozrunner == 6.11 01:24:14 INFO - mozscreenshot == 0.1 01:24:14 INFO - mozsystemmonitor == 0.3 01:24:14 INFO - moztest == 0.7 01:24:14 INFO - mozversion == 1.4 01:24:14 INFO - psutil == 3.1.1 01:24:14 INFO - requests == 1.2.3 01:24:14 INFO - wsgiref == 0.1.2 01:24:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:24:14 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')]} 01:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:14 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')]} 01:24:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:14 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:24:14 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 01:24:14 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 01:24:14 INFO - Using env: {'DISPLAY': ':0', 01:24:14 INFO - 'HOME': '/home/cltbld', 01:24:14 INFO - 'LANG': 'en_US.UTF-8', 01:24:14 INFO - 'LANGUAGE': 'en_US:en', 01:24:14 INFO - 'LOGNAME': 'cltbld', 01:24:14 INFO - 'MAIL': '/var/mail/cltbld', 01:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:14 INFO - 'MOZ_NO_REMOTE': '1', 01:24:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:14 INFO - 'NO_EM_RESTART': '1', 01:24:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:14 INFO - 'PWD': '/builds/slave/test', 01:24:14 INFO - 'SHELL': '/bin/bash', 01:24:14 INFO - 'SHLVL': '1', 01:24:14 INFO - 'TERM': 'linux', 01:24:14 INFO - 'TMOUT': '86400', 01:24:14 INFO - 'USER': 'cltbld', 01:24:14 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:14 INFO - '_': '/tools/buildbot/bin/python'} 01:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 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)) 01:24:14 INFO - Cleaning up... 01:24:14 INFO - Return code: 0 01:24:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:24:14 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')]} 01:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:14 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')]} 01:24:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:14 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:24:14 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 01:24:14 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 01:24:14 INFO - Using env: {'DISPLAY': ':0', 01:24:14 INFO - 'HOME': '/home/cltbld', 01:24:14 INFO - 'LANG': 'en_US.UTF-8', 01:24:14 INFO - 'LANGUAGE': 'en_US:en', 01:24:14 INFO - 'LOGNAME': 'cltbld', 01:24:14 INFO - 'MAIL': '/var/mail/cltbld', 01:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:14 INFO - 'MOZ_NO_REMOTE': '1', 01:24:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:14 INFO - 'NO_EM_RESTART': '1', 01:24:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:14 INFO - 'PWD': '/builds/slave/test', 01:24:14 INFO - 'SHELL': '/bin/bash', 01:24:14 INFO - 'SHLVL': '1', 01:24:14 INFO - 'TERM': 'linux', 01:24:14 INFO - 'TMOUT': '86400', 01:24:14 INFO - 'USER': 'cltbld', 01:24:14 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:14 INFO - '_': '/tools/buildbot/bin/python'} 01:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:14 INFO - Downloading/unpacking jsonschema 01:24:14 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 01:24:14 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 01:24:14 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 01:24:14 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 01:24:14 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 01:24:14 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 01:24:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:24:16 INFO - Downloading/unpacking functools32 (from jsonschema) 01:24: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 01:24: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 01:24: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 01:24: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 01:24: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 01:24: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 01:24:16 INFO - Downloading functools32-3.2.3-2.tar.gz 01:24:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:24:16 INFO - warning: no files found matching '*.txt' 01:24:16 INFO - no previously-included directories found matching 'build' 01:24:16 INFO - no previously-included directories found matching 'dist' 01:24:16 INFO - no previously-included directories found matching '.git*' 01:24:17 INFO - Installing collected packages: jsonschema, functools32 01:24:17 INFO - Running setup.py install for functools32 01:24:17 INFO - warning: no files found matching '*.txt' 01:24:17 INFO - no previously-included directories found matching 'build' 01:24:17 INFO - no previously-included directories found matching 'dist' 01:24:17 INFO - no previously-included directories found matching '.git*' 01:24:17 INFO - Successfully installed jsonschema functools32 01:24:17 INFO - Cleaning up... 01:24:17 INFO - Return code: 0 01:24:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:24:17 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')]} 01:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:17 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')]} 01:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:17 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 0x7f3d8e68bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', '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 01:24:17 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 01:24:17 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 01:24:17 INFO - Using env: {'DISPLAY': ':0', 01:24:17 INFO - 'HOME': '/home/cltbld', 01:24:17 INFO - 'LANG': 'en_US.UTF-8', 01:24:17 INFO - 'LANGUAGE': 'en_US:en', 01:24:17 INFO - 'LOGNAME': 'cltbld', 01:24:17 INFO - 'MAIL': '/var/mail/cltbld', 01:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:17 INFO - 'MOZ_NO_REMOTE': '1', 01:24:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:17 INFO - 'NO_EM_RESTART': '1', 01:24:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:17 INFO - 'PWD': '/builds/slave/test', 01:24:17 INFO - 'SHELL': '/bin/bash', 01:24:17 INFO - 'SHLVL': '1', 01:24:17 INFO - 'TERM': 'linux', 01:24:17 INFO - 'TMOUT': '86400', 01:24:17 INFO - 'USER': 'cltbld', 01:24:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:17 INFO - '_': '/tools/buildbot/bin/python'} 01:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:17 INFO - Downloading/unpacking flake8 01:24:17 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 01:24:17 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 01:24:17 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 01:24:17 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 01:24:17 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 01:24:17 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 01:24:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:24:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:24:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 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 01:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:24:20 INFO - no previously-included directories found matching 'docs/_build' 01:24:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:24:20 INFO - Running setup.py install for pep8 01:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:24:20 INFO - no previously-included directories found matching 'docs/_build' 01:24:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:24:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:24:20 INFO - Cleaning up... 01:24:20 INFO - Return code: 0 01:24:20 INFO - Running post-action listener: _resource_record_post_action 01:24:20 INFO - Running post-action listener: _start_resource_monitoring 01:24:20 INFO - Starting resource monitoring. 01:24:20 INFO - ##### 01:24:20 INFO - ##### Finished create-virtualenv step (success) 01:24:20 INFO - ##### 01:24:20 INFO - ##### 01:24:20 INFO - ##### Running install step. 01:24:20 INFO - ##### 01:24:20 INFO - Running pre-action listener: _resource_record_pre_action 01:24:20 INFO - Running main action method: install 01:24:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:24:20 INFO - Reading from file tmpfile_stdout 01:24:20 INFO - Detecting whether we're running mozinstall >=1.0... 01:24:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:24:20 INFO - Reading from file tmpfile_stdout 01:24:20 INFO - Output received: 01:24:20 INFO - Usage: mozinstall [options] installer 01:24:20 INFO - Options: 01:24:20 INFO - -h, --help show this help message and exit 01:24:20 INFO - -d DEST, --destination=DEST 01:24:20 INFO - Directory to install application into. [default: 01:24:20 INFO - "/builds/slave/test"] 01:24:20 INFO - --app=APP Application being installed. [default: firefox] 01:24:20 INFO - mkdir: /builds/slave/test/build/application 01:24:20 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'] 01:24:20 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 01:24:37 INFO - Reading from file tmpfile_stdout 01:24:37 INFO - Output received: 01:24:37 INFO - /builds/slave/test/build/application/firefox/firefox 01:24:37 INFO - Running post-action listener: _resource_record_post_action 01:24:37 INFO - ##### 01:24:37 INFO - ##### Finished install step (success) 01:24:37 INFO - ##### 01:24:37 INFO - ##### 01:24:37 INFO - ##### Running run-tests step. 01:24:37 INFO - ##### 01:24:37 INFO - Running pre-action listener: _resource_record_pre_action 01:24:37 INFO - Running main action method: run_tests 01:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:24:37 INFO - Python 2.7.3 01:24:37 INFO - Return code: 0 01:24:37 INFO - grabbing minidump binary from tooltool 01:24:37 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')]} 01:24:37 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 0xe219c0>, '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 0xe228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe22d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:24:37 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 01:24:37 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 01:24:37 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 01:24:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:24:37 INFO - Return code: 0 01:24:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:24:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:24:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:24:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:24:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:24:38 INFO - Return code: 0 01:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 01:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 01:24:38 INFO - Using env: {'DISPLAY': ':0', 01:24:38 INFO - 'HOME': '/home/cltbld', 01:24:38 INFO - 'LANG': 'en_US.UTF-8', 01:24:38 INFO - 'LANGUAGE': 'en_US:en', 01:24:38 INFO - 'LOGNAME': 'cltbld', 01:24:38 INFO - 'MAIL': '/var/mail/cltbld', 01:24:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:24:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:38 INFO - 'MOZ_NO_REMOTE': '1', 01:24:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:24:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:38 INFO - 'NO_EM_RESTART': '1', 01:24:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:38 INFO - 'PWD': '/builds/slave/test', 01:24:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:24:38 INFO - 'SHELL': '/bin/bash', 01:24:38 INFO - 'SHLVL': '1', 01:24:38 INFO - 'TERM': 'linux', 01:24:38 INFO - 'TMOUT': '86400', 01:24:38 INFO - 'USER': 'cltbld', 01:24:38 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757', 01:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:38 INFO - '_': '/tools/buildbot/bin/python'} 01:24:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 01:24:38 INFO - mozversion application_buildid: 20160517223201 01:24:38 INFO - mozversion application_changeset: cb356a5f82cdc4f1e44165c96906b04742ed6311 01:24:38 INFO - mozversion application_display_name: Nightly 01:24:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:24:38 INFO - mozversion application_name: Firefox 01:24:38 INFO - mozversion application_remotingname: firefox 01:24:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:24:38 INFO - mozversion application_vendor: Mozilla 01:24:38 INFO - mozversion application_version: 49.0a1 01:24:38 INFO - mozversion platform_buildid: 20160517223201 01:24:38 INFO - mozversion platform_changeset: cb356a5f82cdc4f1e44165c96906b04742ed6311 01:24:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:24:38 INFO - mozversion platform_version: 49.0a1 01:24:38 INFO - using testdate: 1463559878 01:24:38 INFO - actual date: 1463559878 01:24:38 INFO - starting webserver on 'localhost:39989' 01:24:38 INFO - SUITE-START | Running 2 tests 01:24:38 INFO - TEST-START | dromaeo_css 01:24:38 INFO - operating with platform_type : linux_ 01:24:38 INFO - Initialising browser for dromaeo_css test... 01:24:38 INFO - TEST-INFO | started process 25443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSkEjRA/profile http://localhost:39989/getInfo.html) 01:24:42 INFO - PROCESS | 25443 | __metrics Screen width/height:1600/1200 01:24:42 INFO - PROCESS | 25443 | colorDepth:24 01:24:42 INFO - PROCESS | 25443 | Browser inner width/height: 1024/697 01:24:42 INFO - PROCESS | 25443 | __metrics 01:24:43 ERROR - PROCESS | 25443 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:24:44 INFO - TEST-INFO | 25443: exit 0 01:24:44 INFO - Browser initialized. 01:24:44 INFO - Running cycle 1/1 for dromaeo_css test... 01:24:44 INFO - TEST-INFO | started process 25528 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSkEjRA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:24:56 INFO - PROCESS | 25528 | RSS: Main: 152674304 01:24:56 INFO - PROCESS | 25528 | 01:25:47 INFO - PROCESS | 25528 | 1463559947489 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] 01:27:55 INFO - PROCESS | 25528 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39989/tests/dromaeo/cssquery-ext.html) 01:27:56 INFO - PROCESS | 25528 | RSS: Main: 242192384 01:27:56 INFO - PROCESS | 25528 | 01:30:58 INFO - PROCESS | 25528 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-ext.html (next: http://localhost:39989/tests/dromaeo/cssquery-jquery.html) 01:30:59 INFO - PROCESS | 25528 | RSS: Main: 235294720 01:30:59 INFO - PROCESS | 25528 | 01:34:01 INFO - PROCESS | 25528 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39989/tests/dromaeo/cssquery-mootools.html) 01:34:01 INFO - PROCESS | 25528 | RSS: Main: 244027392 01:34:01 INFO - PROCESS | 25528 | 01:37:03 INFO - PROCESS | 25528 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39989/tests/dromaeo/cssquery-prototype.html) 01:37:04 INFO - PROCESS | 25528 | RSS: Main: 239792128 01:37:04 INFO - PROCESS | 25528 | 01:40:07 INFO - PROCESS | 25528 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39989/tests/dromaeo/cssquery-yui.html) 01:40:07 INFO - PROCESS | 25528 | RSS: Main: 208486400 01:40:07 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-yui.html (next: http://localhost:39989/tests/dromaeo/cssquery-dojo.html) 01:43:10 INFO - PROCESS | 25528 | RSS: Main: 318812160 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | __start_tp_report 01:43:10 INFO - PROCESS | 25528 | _x_x_mozilla_page_load 01:43:10 INFO - PROCESS | 25528 | _x_x_mozilla_page_load_details 01:43:10 INFO - PROCESS | 25528 | |i|pagename|runs| 01:43:10 INFO - PROCESS | 25528 | |0;dojo.html;3159;3165.0663054398938;3186.569813075235;3193.616765988079;3227.9031629051137;5366.624336296461;5412.323459567554;5412.675239485634;5489.478499542542;5516.972415137929;5342.225377320296;5358.2498450217;5361.678299302044;5377.247185394038;5428.995635807379;4843.709377437356;4898.559129678329;4915.5084491550915;4934.037862616791;4995.925061124086;4934.679245849008;4966.379202599675;4985.451600323961;4999.125153098212;5036.420811606676;4817.90364192716;4826.131296366652;4851.151048566522;4871.0744958458135;4919.557239848413;4888.682235654706;4897.730624815669;4902.240152776325;4960.107180707471;5004.524570165829;4899.461059283476;4936.777844996984;4937.851864444073;4948.1093403187415;4982.6013918886765;5188.948110518884;5251.4485978972425;5323.733813309358;5324.1215199492035;5381.327334083239;5767.855803604902;5789.710514474259;5791.971040144815;5800.796972105998;5809.506191973687;5566.749496272678;5867.8533036674;5912.940870591282;5918.763249470016;5921;5351.117065684158;5386.48828361305;5395.271638328853;5449;5480.123180291174;6042.879142417127;6055.303640081359;6055.697215139226;6060.121282414113;6062.333143354227;5580.49775520197;5658.547316214693;5779.422057794187;5837.47462728348;5837.532997360201;5778;5845.707714614252;5847.94152058474;5858.150568167677;5910.615809972338;5771.88456230873;5790.71046447676;5798.10129429939;5806.4193580641595;5807.041838096751;5680.77276908919;5713.542916566665;5727.083666613322;5785.334686511021;5791.276090488689;4400.823967041282;4405.163019026375;4405.383246345515;4436.44544431946;4444.755538445416;3907.3552863777727;3943.0930885896405;3947.6644485218503;3973.960260397359;3975;5338.252644629755;5404.3784964728775;5426.186072089139;5438.673679579238;5504.862378440571;5727.455891690282;5749.568782341341;5752.913706294409;5791.160281759121;5802.622829516068;5441.755121019563;5449.346078470529;5450.5367043801725;5450.8909821803345;5458.372287186945;4934.1118598651865;4998.075356059141;4999.925001124986;5046.697198168122;5055.87360315995;6093.542984276197;6102.481289090389;6105.877882442326;6108.572399932051;6108.786192483241;5904.380040095728;5917.349091600006;5921.14143449206;5923.614965027259;5923.674197919156;5851.61964472308;6017.759289628366;6026.367231440811;6075.3317135115985;6103.458891526233;5710.114932185518;5748.137779332967;5762.481376676119;5763.4812866842185;5812.3025236971835;5806.941930580809;5852;5856;5864.882702345843;5866.061430171152;5482.095454250004;5619.269494965628;5701.1448282756255;5709.571782116275;5743.483086522233;5740.1102829061565;5742.08126699726;5744.913826292525;5749.080147176599;5751.4536119068625;5503.229547863222;5633.690147041952;5636.520895723909;5643.294588176478;5645.548356131418;5611.298587676541;5625.549956003591;5664.518515926193;5726.284214473191;5737.512311453574;5534.695591742493;5535.142052981794;5547.278853748987;5569.387367389665;5581.218629391969 01:43:10 INFO - PROCESS | 25528 | |1;ext.html;631.9557630965832;643.0900276109305;645.9515536334776;646.4020780777785;660.9537332386732;42994.78502607487;45301.77349113254;45382.31926521101;49642.25536616945;49735.50264497354;46982;47518;47565.28652070222;47616.52383476173;47678;8937.865932011026;9085.091490850928;9090.181883630472;9090.181883630472;9090.590923408463;8943.731688049369;9034.412763170405;9036.638534458614;9041.819163616723;9048.638054477813;9008.68469603564;9100.180983711465;9111.769911062021;9117.954410227972;9119.49842758648;9029.0519495453;9032.14194651507;9052.592633331516;9071.954640226822;9075.727728168165;8904.421212621159;8993.370464067519;9000.414973026798;9001.459912405277;9005.369624126313;42982.57017429817;43140.35289470661;43285.56714432847;43403.131937361395;43473.56526434727;3548.183917698944;3584.5698516178227;3635.4183330666965;3713.3687273163628;3782.7352085354037;33109.337813243845;33203.83398083018;33628.49557256643;33660.83169584136;34437.33064528214;4088.407180958774;4156.43888075112;4179.582041795827;4212.873613791591;4363.127374525107;349518;349522;351872;352128;352881;53435.73282133642;58968;59629.403705963254;60867.69566152141;62220;66243;66283.33716662868;66358;66365;66543;17800.643987120184;17978.460646180636;17984.730229046574;17985.280588776302;17993.370232041816;12349.93825030869;12444.564440244549;12446.315452650191;12572.497100116076;13872.93063534696;18247.452576422464;18323;18353.90823045903;18377;18409.26362945494;6930.430828123521;6961.408280296231;7042.542234754725;7223.711051557879;7297.1608265049135;977.2133432586694;989.7080361293436;1047.4134484688445;1097.506122244977;1141.1042331769686;31904;33944.49083263753;34289.657103428646;34889.47665785015;35268.29463410769;1141.2695874640237;1143.3825734103657;1145.4330106597288;1172.1091970102784;1175.2008634128704;16989.915050424668;17963.461096167135;18156;18168.1824317906;18190.090495475175;533648;540023;541242;542061;542071;60558;61419.38580614226;61915;61923.69038154781;62081.37918620756;1396.7136736968896;1404.9719005620034;1419.8012278281049;1427.6502256947322;1449.0508716790519;31609.841950790098;32897.506537402434;33407.331853363285;33468.49797253092;34042.31915361629;54514;55337.72331138318;55345;55351.72324138354;55447;452301;462389;463008;507340;510348;45005.54994450013;45098.77450612726;45635.315470267305;45989;46292.768536157106;49796;50378.244326335865;50839.49160508347;51244.487555123975;51703;42026.789866051695;42771.7861410691;42772.572274276856;42838.35742463928;44259.55740442683;42429.363559546015;42475.3628695576;42677.35983960305;42707.35938960856;42864.57135428606;43301;43863.12273754571;43906.34140487959;44003;44541.77729111463;36055.27889442144;38493.57757845769;38832.6116738829;38975.80512097535;39122.84899603126;29198.992241861375;29599.26001849971;29634.555481668223;30198.396032078796;30256.697433025387 01:43:10 INFO - PROCESS | 25528 | |2;jquery.html;407.9755214687117;412.9793510324484;414.6164797562258;415.7110807988448;425.93185090385543;18081.909590452062;19929.900350498247;19949.601007979833;20366;21531.46171345717;12020.819687704692;12074.879251207485;12341.568045118423;12345.506179752821;12374.071944604146;5105.18317069269;5251.923355712089;5260.763265653036;5269.130593452076;5277.736113194344;4978.079055374767;5174.948250517503;5181.274621552986;5182.119039763231;5182.37811462625;5166.354205724285;5170.2503137045105;5170.638055336128;5170.715610641413;5173.896522069556;5120.718360490171;5123.205903085028;5210.218467229927;5261.710605916673;5266.131088370415;5082.567981721558;5102.744862756885;5111.539961403491;5112.028714544262;5113.309703190059;21095.78904210969;23031.654525182137;23735.16926907567;23979.640305395435;24141.879290603436;2445.095314733542;2447.8531288122767;2448.326710154704;2608.5565453872696;2639.5908634161738;10327.225458090674;10410.89589104107;10626.309289896131;10690.625828096057;10702.839457408145;1862.8602854785809;1889.1215584753156;1951.9654583070994;1958.8824670519814;2030.0661695620033;49589.75205123988;60500;85043;85446.14553854507;86037.13962860355;11096.722581935355;11287.75834658187;11352.94323528377;11378.146639001943;11618.76762464763;15954.04275743459;16003.919980400024;16064;16090.115043672711;16104.919475402783;8395.832083358298;8568.871466928002;8746.56267186638;8796.516191609522;8815.955920220358;8357.623906924204;8394.622241999125;8395.454295470898;8604.02107515275;8668.353012927524;4760.95239047612;4880.780364883589;4936.679115857458;4943.8516844494725;5045.621578381636;1897.8766380185243;1903.7525121734086;1994.8603597748308;2016.9846080110328;2048.365006847882;943.6791490893129;1000.444753162002;1072.0191025212;1080.0603474976663;1102.2174256278133;18062.729059064124;19148.521286967938;19169.137388817537;19442.319518816774;19702.507437314183;1015.603914473356;1016.9338992965434;1037.0873631204643;1037.1184493180888;1097.868255809308;10514.16002199612;10579.576816927236;10644.520996555173;10670.146388289073;10679.626213082505;473420;498958;501074;509445;512720;89974;90698.09301907028;91872;92170.53914730383;93123;1060.0936199549428;1066.5573786878358;1083.4214569325113;1084.2952081147316;1089.7765957978595;17334.5666358337;17414.912925435292;17716.557086072437;17740.911295443955;17812.643747124726;40804.79597601993;41160.970975724646;41931.7903410481;42730;43416.13167736691;59521;61070;61144;62219;62678;8709.477431354138;8861.33539984491;8879.289656827568;9092.045335239705;9330.766730831783;10706.143508519455;13482.865171348161;13848.792268116187;13960.790588140982;14109.365078571695;11832.645020649394;11961.282323060645;12121.75756484883;12271.386430678609;12286.631401057983;11721.706957326134;11732;11993.34036627994;12056.578019769266;12088.576899808464;11591.710207244887;12317.137800353888;12356.691082723004;12412.689682758004;12534.122611417113;8499.10759370282;8969.865452018355;8999.820003600022;9171.495567743837;12150.392480376122;12413.379331033231;12483.063770217088;12635.494580216688;12937.54718584845;13197.802032969705 01:43:10 INFO - PROCESS | 25528 | |3;mootools.html;1367.036239451187;1544.729672307346;1588.3567155302103;1759.1819803791234;1865.9440216793491;8165.101838797735;8460.957695211515;8473.322134229262;8706.1729135732;8723.86914196286;8463.703770368038;8639;8644.740657780261;8653;8663.09037551056;2068.2347531413398;2120.0141934000685;2122.9469263268466;2324.2562380038435;2354.09367393553;2364.3734410381176;2458.6926634170727;2461.852288862674;2464.408541949928;2465.6671349367784;2278.088764494199;2295.5638428698576;2307.976920230793;2318.7681231876845;2346.718393792747;2063.9277625283044;2225.977740222593;2406.68713067301;2434.513097380522;2440.4386990992084;2395.7843794058444;2421.8910149043336;2422.7213870404953;2425.042108367185;2426.708794944618;9123.726288211365;9220.077992200793;9226.53867306632;9228.8615670765;9229.123233292894;5374.8925021499745;5476.726163691839;5500.614956953015;5503.532199763025;5503.862403439906;3283.5567198428143;3311.61916379617;3426;3456.5333679953137;3470.4794280857823;1183.946722397494;1189.6966273600262;1210.104522653239;1210.7881120803825;1248.7065539598277;12642.367881605975;12658.620241392773;12667.366631668472;12675.49298028087;12705.555305564261;4691.7654117294;4778.232054139921;4866.489018653061;4875.219964805614;4890.902181956341;10757.354558726502;10859.34843909368;10862.891371086345;10864.402457864735;10870.61952831647;5506.531944784738;5507.917381239285;5558.6942718150085;5565.666060036411;5570.220169176318;4457.509673935864;4765.8570242892765;4871.415430148334;5042.067217564762;5104.259882317043;4116.361963895601;4143.647770226639;4166.291730405838;4180.895477613084;4198.433211516467;1303.6610481274747;1310.1091257944688;1323.6955500235;1325.5228117877557;1331.4274861809322;907.120093509295;929.7303781903307;1046.7016900183412;1049.375621505203;1068.8503609494676;6410.45511131559;6444.45222156122;6546.607203567801;6569.113169722026;6573.704183311763;950.2445555783169;952.514217748953;1033.2870319479534;1042.3224903812581;1044.7022598559374;4584.266517357207;4653.7207767534055;4659.324397962344;4659.697119687209;4664.720116793003;10541.31481453703;11334.093272538177;11648.767024659459;11665.24175928562;11676.591319303954;10958.178136639784;11150.665480035612;11273.323600583828;11294.435278236055;11308.321500709984;8433.28317093054;8624.611892464854;8639;8647.135286471303;8649.394542382099;6385.904211436739;6547.770828020996;6627.271000190071;6661.833454163685;6771.6614169292325;10357.4303413313;10449.21630877672;10491.790164196826;10514.421706806199;10610.946945265225;10280.177585793268;10662.786744265226;10666.83999739989;10737.355758654505;10762.300450470695;7442.8511429770015;7950.681972721027;8000.399970002157;8117.675292988214;8243.0520490142;9123.726288211365;9165.129312715282;9207.86188207164;9259.305552083486;9271.258299335903;7986.440949133505;8162.877556836534;8189.099199087977;8266.628001739817;8324.70863519774;8087.272145506932;8141.8371632568205;8169.591520423836;8240.1347858474;8319.958400207961;7544.735934242494;7601;7606.163322034461;7610.505317154367;7621.047369078865;5366.060939335523;5378.435264297193;5410.431904650112;5565.888682226414;5640.54875609942;5661.915071273852;5704.1443783432815;5732.05421105513;5820.679862607597;5853.03424934898 01:43:10 INFO - PROCESS | 25528 | |4;prototype.html;335.45990954563155;336.2206238322249;345.4283161367935;346.1571074433754;350.3798277049624;5166.380034395868;5280;5326.201069839526;5399.865003374917;5405.945940540593;5276;5291.359678499658;5319.281896943911;5333.38666053404;5343.225232341309;4215.419217793327;4221.577842215769;4236.809343579539;4238.491381034265;4260.190563792885;3921.4313924480934;4010.7994600270017;4164.187983343234;4229.619334259916;4259.424977628027;4100.507939047318;4135.503739551258;4136.81384337704;4139.710220284582;4145.357469592218;4090.2433049885867;4131.987663022549;4145.97927010366;4157.875263742078;4162.751174647608;3455.1707590178426;3671.6695497405094;3687.557493100818;3698.8335524901445;3701.61133081028;5086.694798312114;5101.795928162869;5105.774614092591;5121.257417674454;5128.333316668846;4731.668783185179;4741.715497070187;4742.407199100113;4746.382970213885;4760.452547956995;5371.4897084776885;5499.285092937933;5509.173623956399;5522.171674248854;5526.226328314039;3952.7825969571663;3998.620131087542;4004.3392840181336;4006.357393468693;4020.195960807821;5674.148877668342;5724.112762521816;5734.311882574076;5751.511121554715;5768.192453056576;3965.1871366369824;4080.8775736727353;4092.0792821615496;4098.467199264077;4106.527749308868;4087.202995415865;4120.1553681495225;4148.56440073794;4154.522229943595;4163.500379954425;4707.976460117678;4710.222813235778;4712.151812673752;4713.86867151888;4735.526447355237;5410.566199957015;5530.557555395559;5540.418256083134;5543.1685247213245;5554.389017208104;1380.061558140474;1382.3411905713185;1383.7785954247272;1390.7705228637162;1392.262101086426;887.7603047177226;893.9061398553189;912.5254867468879;914.1544071733753;924.8843894513186;4818.229083346648;4857.4171099467585;4865.175559165313;4873.926891096637;4883.218685010382;5046.571041461517;5065.721385323843;5072.163093089673;5083.262926875583;5087.567556757717;4636.304554316879;4852.344933433941;4887.853364399074;4911.312416261726;4911.7544122793715;2694.191742477249;2705.688845782721;2707.7563019328354;2716.904908328199;2718.238893109932;5210.400803907523;5237.166991553002;5264.105102132647;5311.681299122065;5317.973410132925;4816.494268101869;5063.746812659426;5149.995750828627;5170.784865556591;5187.792488300426;5213.452587478336;5232.895342093213;5254.161043634774;5292.735363231823;5323.787048518094;5009.221726287115;5069.327122049788;5085.923711144262;5088.974555127201;5093.8726531837;4906.9018619626695;4995.025969935828;5021.824236151717;5044.621653376085;5085.542301192762;5203.349581302337;5251.52736253739;5260.395579348183;5265.315508983807;5291.444398338119;3734.1971476132762;3846.4614953907035;3968.4245784361683;4059.1881623674794;4094.9181016380103;5647.519960803378;5663.235463212413;5682.488576028178;5682.744276507485;5720.227769251264;5422.945770542402;5594.300712410949;5608.495235428831;5616.157576363578;5619.80330688424;5439.3200849893765;5474.124140137559;5491.450854914477;5496.0107180707855;5511.200875872896;5383.138697808332;5389.8922021558565;5394.892102157856;5400.486953739389;5406.432324605859;5224.9216261756865;5466.699331536804;5477.3427188737605;5484.424135465878;5489.972550137224;5347.625666203328;5363.704996225089;5371.5971302153275;5379.811706590251;5400.918986215289 01:43:10 INFO - PROCESS | 25528 | |5;yui.html;760.4035683521619;890.363390176024;890.7194233816351;893.1738142218451;893.2139717048996;15537.14545699986;15995;16020.118893460884;16029;16055.277512511935;14953.551393458214;14954.626134346647;14967.47613833516;14985.7002859943;14987.400503979828;701.9122609673791;798.1659166171365;829.4194064155085;832.138736407818;835.1481488881337;825.6779855856221;830.1325115254574;833.5207255827893;836.0260296754304;837.5226121110969;869.1829680100685;871.8910136232971;873.3842640938124;874.1477059866611;874.7813046738315;734.2290594875386;859.8495263328924;894.8255090257402;897.3583887520455;897.5557099235855;650.7624716978269;651.3746803069059;652.3313603556345;652.6899722631712;652.8367908022994;13838.654033649138;13844.930775346058;13861.514846980308;13865.792013119812;13879.236641984688;14153.433862645481;14528.418863245457;14541.418343266256;14550.12699238049;14785.852141478555;12762.234265944073;12898.484060637564;13022.348882555929;13116.606501804865;13246.271455069967;4525.751083690396;4602.746848923308;4632.5830675239395;4655.790489427984;4717.905641887143;57058.7147064262;58414;58561.70719146377;60698.39301607016;64288.357116428706;11734.413279335999;12234.449449774782;12280.324582148067;12360.25838449696;12767.042471814651;21897.671534926987;21905.233316833834;21910.671339929915;21916;21935.890320548297;9101.180893719464;9259.398139120936;9262.67580634688;9307.860382094274;9529;4781.665283430127;4861.51384861511;4900.362952816111;4951.851444456672;5030.622703297267;3584.4802503636824;3614.0603443104983;3706.684931780775;3710.7216958728204;3710.9072273193387;641.0960545630638;702.3854127638316;719.0329007484925;742.8699977503916;757.5871150223142;764.9885251721229;780.8008957715859;787.7282337593521;794.1780257433644;830.7466222801983;9744.512774361254;10080.445575493419;10142.645007424704;10179.134773544332;10185.083342499063;705.3158436316764;716.2416268061895;716.992830071703;772.0503780350201;812.9227723366271;5058.595312375075;5145.794168233304;5177.326947496801;5179.741012949337;5187.377514698261;74098.25901741021;76484.61757691287;77329;77469.22530774733;77557.61221193858;60588;63027;64146;67426;67468.32531674621;60700.08949865756;61158.69420652957;62058;62171.06743398853;63055.369446305864;10184.185265178916;10367.84448233262;10501.737456563647;10585.629502967358;10608.257421980386;38565.80717096396;39024.6097539021;39771.204575907745;40171.598284016785;40248.597514025656;52730.73634631802;52734.472655272955;52776;53010.939781203386;53337.19994200012;12797.29614871191;12855.100142990275;12959.676008099874;13063;13434.731305374033;14430.711385772436;14510.274486275855;15361.769573456631;15732.370705171665;15745.606359840638;12786.041046921704;13279.203247805162;14341.498047568284;14685;14713.632159196106;13537.729245415234;13554.118982266122;13605.727885442433;13750.793738094137;13796;12839.614811555668;13084.803727943898;13150.671233219246;13162.341882905625;13395.062345635712;11747.412629368668;11911.642650720492;11912.40437978115;12048.819267710816;12068.39658017078;12127.211731237441;12136.93931530372;12155.623776737915;12309.384530773246;12356.938215309225 01:43:10 INFO - PROCESS | 25528 | __end_tp_report 01:43:10 INFO - PROCESS | 25528 | __start_cc_report 01:43:10 INFO - PROCESS | 25528 | _x_x_mozilla_cycle_collect,784 01:43:10 INFO - PROCESS | 25528 | __end_cc_report 01:43:10 INFO - PROCESS | 25528 | __startTimestamp1463560990488__endTimestamp 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | ------- Summary: start ------- 01:43:10 INFO - PROCESS | 25528 | Number of tests: 6 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | [#0] dojo.html Cycles:165 Average:5395.03 Median:5580.50 stddev:620.88 (11.1%) stddev-sans-first:597.49 01:43:10 INFO - PROCESS | 25528 | Values: 3159.0 3165.1 3186.6 3193.6 3227.9 5366.6 5412.3 5412.7 5489.5 5517.0 5342.2 5358.2 5361.7 5377.2 5429.0 4843.7 4898.6 4915.5 4934.0 4995.9 4934.7 4966.4 4985.5 4999.1 5036.4 4817.9 4826.1 4851.2 4871.1 4919.6 4888.7 4897.7 4902.2 4960.1 5004.5 4899.5 4936.8 4937.9 4948.1 4982.6 5188.9 5251.4 5323.7 5324.1 5381.3 5767.9 5789.7 5792.0 5800.8 5809.5 5566.7 5867.9 5912.9 5918.8 5921.0 5351.1 5386.5 5395.3 5449.0 5480.1 6042.9 6055.3 6055.7 6060.1 6062.3 5580.5 5658.5 5779.4 5837.5 5837.5 5778.0 5845.7 5847.9 5858.2 5910.6 5771.9 5790.7 5798.1 5806.4 5807.0 5680.8 5713.5 5727.1 5785.3 5791.3 4400.8 4405.2 4405.4 4436.4 4444.8 3907.4 3943.1 3947.7 3974.0 3975.0 5338.3 5404.4 5426.2 5438.7 5504.9 5727.5 5749.6 5752.9 5791.2 5802.6 5441.8 5449.3 5450.5 5450.9 5458.4 4934.1 4998.1 4999.9 5046.7 5055.9 6093.5 6102.5 6105.9 6108.6 6108.8 5904.4 5917.3 5921.1 5923.6 5923.7 5851.6 6017.8 6026.4 6075.3 6103.5 5710.1 5748.1 5762.5 5763.5 5812.3 5806.9 5852.0 5856.0 5864.9 5866.1 5482.1 5619.3 5701.1 5709.6 5743.5 5740.1 5742.1 5744.9 5749.1 5751.5 5503.2 5633.7 5636.5 5643.3 5645.5 5611.3 5625.5 5664.5 5726.3 5737.5 5534.7 5535.1 5547.3 5569.4 5581.2 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | [#1] ext.html Cycles:180 Average:63211.76 Median:43954.67 stddev:122779.70 (279.3%) stddev-sans-first:123034.22 01:43:10 INFO - PROCESS | 25528 | Values: 632.0 643.1 646.0 646.4 661.0 42994.8 45301.8 45382.3 49642.3 49735.5 46982.0 47518.0 47565.3 47616.5 47678.0 8937.9 9085.1 9090.2 9090.2 9090.6 8943.7 9034.4 9036.6 9041.8 9048.6 9008.7 9100.2 9111.8 9118.0 9119.5 9029.1 9032.1 9052.6 9072.0 9075.7 8904.4 8993.4 9000.4 9001.5 9005.4 42982.6 43140.4 43285.6 43403.1 43473.6 3548.2 3584.6 3635.4 3713.4 3782.7 33109.3 33203.8 33628.5 33660.8 34437.3 4088.4 4156.4 4179.6 4212.9 4363.1 349518.0 349522.0 351872.0 352128.0 352881.0 53435.7 58968.0 59629.4 60867.7 62220.0 66243.0 66283.3 66358.0 66365.0 66543.0 17800.6 17978.5 17984.7 17985.3 17993.4 12349.9 12444.6 12446.3 12572.5 13872.9 18247.5 18323.0 18353.9 18377.0 18409.3 6930.4 6961.4 7042.5 7223.7 7297.2 977.2 989.7 1047.4 1097.5 1141.1 31904.0 33944.5 34289.7 34889.5 35268.3 1141.3 1143.4 1145.4 1172.1 1175.2 16989.9 17963.5 18156.0 18168.2 18190.1 533648.0 540023.0 541242.0 542061.0 542071.0 60558.0 61419.4 61915.0 61923.7 62081.4 1396.7 1405.0 1419.8 1427.7 1449.1 31609.8 32897.5 33407.3 33468.5 34042.3 54514.0 55337.7 55345.0 55351.7 55447.0 452301.0 462389.0 463008.0 507340.0 510348.0 45005.5 45098.8 45635.3 45989.0 46292.8 49796.0 50378.2 50839.5 51244.5 51703.0 42026.8 42771.8 42772.6 42838.4 44259.6 42429.4 42475.4 42677.4 42707.4 42864.6 43301.0 43863.1 43906.3 44003.0 44541.8 36055.3 38493.6 38832.6 38975.8 39122.8 29199.0 29599.3 29634.6 30198.4 30256.7 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | [#2] jquery.html Cycles:180 Average:29074.14 Median:22281.56 stddev:82227.49 (369.0%) stddev-sans-first:82429.99 01:43:10 INFO - PROCESS | 25528 | Values: 408.0 413.0 414.6 415.7 425.9 18081.9 19929.9 19949.6 20366.0 21531.5 12020.8 12074.9 12341.6 12345.5 12374.1 5105.2 5251.9 5260.8 5269.1 5277.7 4978.1 5174.9 5181.3 5182.1 5182.4 5166.4 5170.3 5170.6 5170.7 5173.9 5120.7 5123.2 5210.2 5261.7 5266.1 5082.6 5102.7 5111.5 5112.0 5113.3 21095.8 23031.7 23735.2 23979.6 24141.9 2445.1 2447.9 2448.3 2608.6 2639.6 10327.2 10410.9 10626.3 10690.6 10702.8 1862.9 1889.1 1952.0 1958.9 2030.1 49589.8 60500.0 85043.0 85446.1 86037.1 11096.7 11287.8 11352.9 11378.1 11618.8 15954.0 16003.9 16064.0 16090.1 16104.9 8395.8 8568.9 8746.6 8796.5 8816.0 8357.6 8394.6 8395.5 8604.0 8668.4 4761.0 4880.8 4936.7 4943.9 5045.6 1897.9 1903.8 1994.9 2017.0 2048.4 943.7 1000.4 1072.0 1080.1 1102.2 18062.7 19148.5 19169.1 19442.3 19702.5 1015.6 1016.9 1037.1 1037.1 1097.9 10514.2 10579.6 10644.5 10670.1 10679.6 473420.0 498958.0 501074.0 509445.0 512720.0 89974.0 90698.1 91872.0 92170.5 93123.0 1060.1 1066.6 1083.4 1084.3 1089.8 17334.6 17414.9 17716.6 17740.9 17812.6 40804.8 41161.0 41931.8 42730.0 43416.1 59521.0 61070.0 61144.0 62219.0 62678.0 8709.5 8861.3 8879.3 9092.0 9330.8 10706.1 13482.9 13848.8 13960.8 14109.4 11832.6 11961.3 12121.8 12271.4 12286.6 11721.7 11732.0 11993.3 12056.6 12088.6 11591.7 12317.1 12356.7 12412.7 12534.1 8499.1 8969.9 8999.8 9171.5 12150.4 12413.4 12483.1 12635.5 12937.5 13197.8 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | [#3] mootools.html Cycles:180 Average:6063.78 Median:4662.21 stddev:3422.64 (73.4%) stddev-sans-first:3414.04 01:43:10 INFO - PROCESS | 25528 | Values: 1367.0 1544.7 1588.4 1759.2 1865.9 8165.1 8461.0 8473.3 8706.2 8723.9 8463.7 8639.0 8644.7 8653.0 8663.1 2068.2 2120.0 2122.9 2324.3 2354.1 2364.4 2458.7 2461.9 2464.4 2465.7 2278.1 2295.6 2308.0 2318.8 2346.7 2063.9 2226.0 2406.7 2434.5 2440.4 2395.8 2421.9 2422.7 2425.0 2426.7 9123.7 9220.1 9226.5 9228.9 9229.1 5374.9 5476.7 5500.6 5503.5 5503.9 3283.6 3311.6 3426.0 3456.5 3470.5 1183.9 1189.7 1210.1 1210.8 1248.7 12642.4 12658.6 12667.4 12675.5 12705.6 4691.8 4778.2 4866.5 4875.2 4890.9 10757.4 10859.3 10862.9 10864.4 10870.6 5506.5 5507.9 5558.7 5565.7 5570.2 4457.5 4765.9 4871.4 5042.1 5104.3 4116.4 4143.6 4166.3 4180.9 4198.4 1303.7 1310.1 1323.7 1325.5 1331.4 907.1 929.7 1046.7 1049.4 1068.9 6410.5 6444.5 6546.6 6569.1 6573.7 950.2 952.5 1033.3 1042.3 1044.7 4584.3 4653.7 4659.3 4659.7 4664.7 10541.3 11334.1 11648.8 11665.2 11676.6 10958.2 11150.7 11273.3 11294.4 11308.3 8433.3 8624.6 8639.0 8647.1 8649.4 6385.9 6547.8 6627.3 6661.8 6771.7 10357.4 10449.2 10491.8 10514.4 10610.9 10280.2 10662.8 10666.8 10737.4 10762.3 7442.9 7950.7 8000.4 8117.7 8243.1 9123.7 9165.1 9207.9 9259.3 9271.3 7986.4 8162.9 8189.1 8266.6 8324.7 8087.3 8141.8 8169.6 8240.1 8320.0 7544.7 7601.0 7606.2 7610.5 7621.0 5366.1 5378.4 5410.4 5565.9 5640.5 5661.9 5704.1 5732.1 5820.7 5853.0 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | [#4] prototype.html Cycles:180 Average:4508.97 Median:5077.71 stddev:1292.54 (25.5%) stddev-sans-first:1257.63 01:43:10 INFO - PROCESS | 25528 | Values: 335.5 336.2 345.4 346.2 350.4 5166.4 5280.0 5326.2 5399.9 5405.9 5276.0 5291.4 5319.3 5333.4 5343.2 4215.4 4221.6 4236.8 4238.5 4260.2 3921.4 4010.8 4164.2 4229.6 4259.4 4100.5 4135.5 4136.8 4139.7 4145.4 4090.2 4132.0 4146.0 4157.9 4162.8 3455.2 3671.7 3687.6 3698.8 3701.6 5086.7 5101.8 5105.8 5121.3 5128.3 4731.7 4741.7 4742.4 4746.4 4760.5 5371.5 5499.3 5509.2 5522.2 5526.2 3952.8 3998.6 4004.3 4006.4 4020.2 5674.1 5724.1 5734.3 5751.5 5768.2 3965.2 4080.9 4092.1 4098.5 4106.5 4087.2 4120.2 4148.6 4154.5 4163.5 4708.0 4710.2 4712.2 4713.9 4735.5 5410.6 5530.6 5540.4 5543.2 5554.4 1380.1 1382.3 1383.8 1390.8 1392.3 887.8 893.9 912.5 914.2 924.9 4818.2 4857.4 4865.2 4873.9 4883.2 5046.6 5065.7 5072.2 5083.3 5087.6 4636.3 4852.3 4887.9 4911.3 4911.8 2694.2 2705.7 2707.8 2716.9 2718.2 5210.4 5237.2 5264.1 5311.7 5318.0 4816.5 5063.7 5150.0 5170.8 5187.8 5213.5 5232.9 5254.2 5292.7 5323.8 5009.2 5069.3 5085.9 5089.0 5093.9 4906.9 4995.0 5021.8 5044.6 5085.5 5203.3 5251.5 5260.4 5265.3 5291.4 3734.2 3846.5 3968.4 4059.2 4094.9 5647.5 5663.2 5682.5 5682.7 5720.2 5422.9 5594.3 5608.5 5616.2 5619.8 5439.3 5474.1 5491.5 5496.0 5511.2 5383.1 5389.9 5394.9 5400.5 5406.4 5224.9 5466.7 5477.3 5484.4 5490.0 5347.6 5363.7 5371.6 5379.8 5400.9 01:43:10 INFO - PROCESS | 25528 | 01:43:10 INFO - PROCESS | 25528 | [#5] yui.html Cycles:180 Average:16945.64 Median:4617.66 stddev:20323.77 (440.1%) stddev-sans-first:20344.44 01:43:10 INFO - PROCESS | 25528 | Values: 760.4 890.4 890.7 893.2 893.2 15537.1 15995.0 16020.1 16029.0 16055.3 14953.6 14954.6 14967.5 14985.7 14987.4 701.9 798.2 829.4 832.1 835.1 825.7 830.1 833.5 836.0 837.5 869.2 871.9 873.4 874.1 874.8 734.2 859.8 894.8 897.4 897.6 650.8 651.4 652.3 652.7 652.8 13838.7 13844.9 13861.5 13865.8 13879.2 14153.4 14528.4 14541.4 14550.1 14785.9 12762.2 12898.5 13022.3 13116.6 13246.3 4525.8 4602.7 4632.6 4655.8 4717.9 57058.7 58414.0 58561.7 60698.4 64288.4 11734.4 12234.4 12280.3 12360.3 12767.0 21897.7 21905.2 21910.7 21916.0 21935.9 9101.2 9259.4 9262.7 9307.9 9529.0 4781.7 4861.5 4900.4 4951.9 5030.6 3584.5 3614.1 3706.7 3710.7 3710.9 641.1 702.4 719.0 742.9 757.6 765.0 780.8 787.7 794.2 830.7 9744.5 10080.4 10142.6 10179.1 10185.1 705.3 716.2 717.0 772.1 812.9 5058.6 5145.8 5177.3 5179.7 5187.4 74098.3 76484.6 77329.0 77469.2 77557.6 60588.0 63027.0 64146.0 67426.0 67468.3 60700.1 61158.7 62058.0 62171.1 63055.4 10184.2 10367.8 10501.7 10585.6 10608.3 38565.8 39024.6 39771.2 40171.6 40248.6 52730.7 52734.5 52776.0 53010.9 53337.2 12797.3 12855.1 12959.7 13063.0 13434.7 14430.7 14510.3 15361.8 15732.4 15745.6 12786.0 13279.2 14341.5 14685.0 14713.6 13537.7 13554.1 13605.7 13750.8 13796.0 12839.6 13084.8 13150.7 13162.3 13395.1 11747.4 11911.6 11912.4 12048.8 12068.4 12127.2 12136.9 12155.6 12309.4 12356.9 01:43:10 INFO - PROCESS | 25528 | -------- Summary: end -------- 01:43:10 INFO - PROCESS | 25528 | 01:43:11 INFO - TEST-INFO | 25528: exit 0 01:43:11 INFO - TEST-OK | dromaeo_css | took 1112822ms 01:43:11 INFO - TEST-START | kraken 01:43:11 INFO - operating with platform_type : linux_ 01:43:11 INFO - Initialising browser for kraken test... 01:43:11 INFO - TEST-INFO | started process 25707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV0rDtc/profile http://localhost:39989/getInfo.html) 01:43:15 INFO - PROCESS | 25707 | __metrics Screen width/height:1600/1200 01:43:15 INFO - PROCESS | 25707 | colorDepth:24 01:43:15 INFO - PROCESS | 25707 | Browser inner width/height: 1024/697 01:43:15 INFO - PROCESS | 25707 | __metrics 01:43:16 ERROR - PROCESS | 25707 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:43:17 INFO - TEST-INFO | 25707: exit 0 01:43:17 INFO - Browser initialized. 01:43:17 INFO - Running cycle 1/1 for kraken test... 01:43:17 INFO - TEST-INFO | started process 25788 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV0rDtc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:43:29 INFO - PROCESS | 25788 | RSS: Main: 153116672 01:43:29 INFO - PROCESS | 25788 | 01:43:31 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-beat-detection) 01:43:32 INFO - PROCESS | 25788 | RSS: Main: 208527360 01:43:32 INFO - PROCESS | 25788 | 01:43:35 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-dft) 01:43:35 INFO - PROCESS | 25788 | RSS: Main: 198074368 01:43:35 INFO - PROCESS | 25788 | 01:43:39 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-fft) 01:43:39 INFO - PROCESS | 25788 | RSS: Main: 264953856 01:43:39 INFO - PROCESS | 25788 | 01:43:41 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-oscillator) 01:43:42 INFO - PROCESS | 25788 | RSS: Main: 174993408 01:43:42 INFO - PROCESS | 25788 | 01:43:44 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39989/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:43:45 INFO - PROCESS | 25788 | RSS: Main: 178618368 01:43:45 INFO - PROCESS | 25788 | 01:43:48 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39989/tests/kraken/driver.html?testName=imaging-darkroom) 01:43:49 INFO - PROCESS | 25788 | RSS: Main: 214704128 01:43:49 INFO - PROCESS | 25788 | 01:43:52 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39989/tests/kraken/driver.html?testName=imaging-desaturate) 01:43:53 INFO - PROCESS | 25788 | RSS: Main: 212365312 01:43:53 INFO - PROCESS | 25788 | 01:43:56 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39989/tests/kraken/driver.html?testName=json-parse-financial) 01:43:56 INFO - PROCESS | 25788 | RSS: Main: 213803008 01:43:56 INFO - PROCESS | 25788 | 01:43:58 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39989/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:43:59 INFO - PROCESS | 25788 | RSS: Main: 202309632 01:43:59 INFO - PROCESS | 25788 | 01:44:00 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:44:01 INFO - PROCESS | 25788 | RSS: Main: 205103104 01:44:01 INFO - PROCESS | 25788 | 01:44:03 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:44:04 INFO - PROCESS | 25788 | RSS: Main: 216092672 01:44:04 INFO - PROCESS | 25788 | 01:44:06 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:44:07 INFO - PROCESS | 25788 | RSS: Main: 224604160 01:44:07 INFO - PROCESS | 25788 | 01:44:10 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:44:10 INFO - PROCESS | 25788 | RSS: Main: 221003776 01:44:10 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39989/tests/kraken/driver.html?testName=ai-astar) 01:44:12 INFO - PROCESS | 25788 | RSS: Main: 266625024 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | __start_tp_report 01:44:12 INFO - PROCESS | 25788 | _x_x_mozilla_page_load 01:44:12 INFO - PROCESS | 25788 | _x_x_mozilla_page_load_details 01:44:12 INFO - PROCESS | 25788 | |i|pagename|runs| 01:44:12 INFO - PROCESS | 25788 | |0;ai-astar;109;103;110;110;109;110;110;109;109;110 01:44:12 INFO - PROCESS | 25788 | |1;audio-beat-detection;160;124;147;206;126;158;161;127;188;128 01:44:12 INFO - PROCESS | 25788 | |2;audio-dft;161;160;160;162;160;161;162;162;161;161 01:44:12 INFO - PROCESS | 25788 | |3;audio-fft;73;74;74;152;76;74;74;74;130;75 01:44:12 INFO - PROCESS | 25788 | |4;audio-oscillator;91;93;145;92;92;92;120;92;93;130 01:44:12 INFO - PROCESS | 25788 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;116;117 01:44:12 INFO - PROCESS | 25788 | |6;imaging-darkroom;145;146;145;145;145;144;145;145;144;145 01:44:12 INFO - PROCESS | 25788 | |7;imaging-desaturate;94;94;94;94;94;94;95;100;96;95 01:44:12 INFO - PROCESS | 25788 | |8;json-parse-financial;55;55;55;54;54;55;55;107;53;53 01:44:12 INFO - PROCESS | 25788 | |9;json-stringify-tinderbox;60;59;59;59;59;59;58;59;59;59 01:44:12 INFO - PROCESS | 25788 | |10;stanford-crypto-aes;77;78;84;77;79;78;79;79;83;79 01:44:12 INFO - PROCESS | 25788 | |11;stanford-crypto-ccm;122;124;128;121;121;117;127;124;196;121 01:44:12 INFO - PROCESS | 25788 | |12;stanford-crypto-pbkdf2;164;167;161;163;163;162;159;160;161;163 01:44:12 INFO - PROCESS | 25788 | |13;stanford-crypto-sha256-iterative;64;73;63;63;65;62;62;63;60;61 01:44:12 INFO - PROCESS | 25788 | __end_tp_report 01:44:12 INFO - PROCESS | 25788 | __start_cc_report 01:44:12 INFO - PROCESS | 25788 | _x_x_mozilla_cycle_collect,1887 01:44:12 INFO - PROCESS | 25788 | __end_cc_report 01:44:12 INFO - PROCESS | 25788 | __startTimestamp1463561052887__endTimestamp 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | ------- Summary: start ------- 01:44:12 INFO - PROCESS | 25788 | Number of tests: 14 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.13 (1.9%) stddev-sans-first:2.26 01:44:12 INFO - PROCESS | 25788 | Values: 109.0 103.0 110.0 110.0 109.0 110.0 110.0 109.0 109.0 110.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#1] audio-beat-detection Cycles:10 Average:152.50 Median:159.00 stddev:28.04 (17.6%) stddev-sans-first:29.61 01:44:12 INFO - PROCESS | 25788 | Values: 160.0 124.0 147.0 206.0 126.0 158.0 161.0 127.0 188.0 128.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 01:44:12 INFO - PROCESS | 25788 | Values: 161.0 160.0 160.0 162.0 160.0 161.0 162.0 162.0 161.0 161.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#3] audio-fft Cycles:10 Average:87.60 Median:74.00 stddev:28.63 (38.7%) stddev-sans-first:29.87 01:44:12 INFO - PROCESS | 25788 | Values: 73.0 74.0 74.0 152.0 76.0 74.0 74.0 74.0 130.0 75.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:92.00 stddev:20.00 (21.7%) stddev-sans-first:20.65 01:44:12 INFO - PROCESS | 25788 | Values: 91.0 93.0 145.0 92.0 92.0 92.0 120.0 92.0 93.0 130.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 01:44:12 INFO - PROCESS | 25788 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#6] imaging-darkroom Cycles:10 Average:144.90 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 01:44:12 INFO - PROCESS | 25788 | Values: 145.0 146.0 145.0 145.0 145.0 144.0 145.0 145.0 144.0 145.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:94.00 stddev:1.89 (2.0%) stddev-sans-first:1.96 01:44:12 INFO - PROCESS | 25788 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 100.0 96.0 95.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:55.00 stddev:16.67 (30.3%) stddev-sans-first:17.60 01:44:12 INFO - PROCESS | 25788 | Values: 55.0 55.0 55.0 54.0 54.0 55.0 55.0 107.0 53.0 53.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.47 (0.8%) stddev-sans-first:0.33 01:44:12 INFO - PROCESS | 25788 | Values: 60.0 59.0 59.0 59.0 59.0 59.0 58.0 59.0 59.0 59.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.00 stddev:2.36 (3.0%) stddev-sans-first:2.35 01:44:12 INFO - PROCESS | 25788 | Values: 77.0 78.0 84.0 77.0 79.0 78.0 79.0 79.0 83.0 79.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#11] stanford-crypto-ccm Cycles:10 Average:130.10 Median:124.00 stddev:23.37 (18.8%) stddev-sans-first:24.61 01:44:12 INFO - PROCESS | 25788 | Values: 122.0 124.0 128.0 121.0 121.0 117.0 127.0 124.0 196.0 121.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.00 stddev:2.26 (1.4%) stddev-sans-first:2.32 01:44:12 INFO - PROCESS | 25788 | Values: 164.0 167.0 161.0 163.0 163.0 162.0 159.0 160.0 161.0 163.0 01:44:12 INFO - PROCESS | 25788 | 01:44:12 INFO - PROCESS | 25788 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:63.00 stddev:3.60 (5.7%) stddev-sans-first:3.81 01:44:12 INFO - PROCESS | 25788 | Values: 64.0 73.0 63.0 63.0 65.0 62.0 62.0 63.0 60.0 61.0 01:44:12 INFO - PROCESS | 25788 | -------- Summary: end -------- 01:44:12 INFO - PROCESS | 25788 | 01:44:13 INFO - TEST-INFO | 25788: exit 0 01:44:13 INFO - TEST-OK | kraken | took 62660ms 01:44:13 INFO - SUITE-END | took 1175s 01:44:14 INFO - Completed test suite (00:19:35) 01:44:14 INFO - javascript benchmark 01:44:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3159.0, 3165.0663054398938, 3186.569813075235, 3193.616765988079, 3227.9031629051137, 5366.624336296461, 5412.323459567554, 5412.675239485634, 5489.478499542542, 5516.972415137929, 5342.225377320296, 5358.2498450217, 5361.678299302044, 5377.247185394038, 5428.995635807379, 4843.709377437356, 4898.559129678329, 4915.5084491550915, 4934.037862616791, 4995.925061124086, 4934.679245849008, 4966.379202599675, 4985.451600323961, 4999.125153098212, 5036.420811606676, 4817.90364192716, 4826.131296366652, 4851.151048566522, 4871.0744958458135, 4919.557239848413, 4888.682235654706, 4897.730624815669, 4902.240152776325, 4960.107180707471, 5004.524570165829, 4899.461059283476, 4936.777844996984, 4937.851864444073, 4948.1093403187415, 4982.6013918886765, 5188.948110518884, 5251.4485978972425, 5323.733813309358, 5324.1215199492035, 5381.327334083239, 5767.855803604902, 5789.710514474259, 5791.971040144815, 5800.796972105998, 5809.506191973687, 5566.749496272678, 5867.8533036674, 5912.940870591282, 5918.763249470016, 5921.0, 5351.117065684158, 5386.48828361305, 5395.271638328853, 5449.0, 5480.123180291174, 6042.879142417127, 6055.303640081359, 6055.697215139226, 6060.121282414113, 6062.333143354227, 5580.49775520197, 5658.547316214693, 5779.422057794187, 5837.47462728348, 5837.532997360201, 5778.0, 5845.707714614252, 5847.94152058474, 5858.150568167677, 5910.615809972338, 5771.88456230873, 5790.71046447676, 5798.10129429939, 5806.4193580641595, 5807.041838096751, 5680.77276908919, 5713.542916566665, 5727.083666613322, 5785.334686511021, 5791.276090488689, 4400.823967041282, 4405.163019026375, 4405.383246345515, 4436.44544431946, 4444.755538445416, 3907.3552863777727, 3943.0930885896405, 3947.6644485218503, 3973.960260397359, 3975.0, 5338.252644629755, 5404.3784964728775, 5426.186072089139, 5438.673679579238, 5504.862378440571, 5727.455891690282, 5749.568782341341, 5752.913706294409, 5791.160281759121, 5802.622829516068, 5441.755121019563, 5449.346078470529, 5450.5367043801725, 5450.8909821803345, 5458.372287186945, 4934.1118598651865, 4998.075356059141, 4999.925001124986, 5046.697198168122, 5055.87360315995, 6093.542984276197, 6102.481289090389, 6105.877882442326, 6108.572399932051, 6108.786192483241, 5904.380040095728, 5917.349091600006, 5921.14143449206, 5923.614965027259, 5923.674197919156, 5851.61964472308, 6017.759289628366, 6026.367231440811, 6075.3317135115985, 6103.458891526233, 5710.114932185518, 5748.137779332967, 5762.481376676119, 5763.4812866842185, 5812.3025236971835, 5806.941930580809, 5852.0, 5856.0, 5864.882702345843, 5866.061430171152, 5482.095454250004, 5619.269494965628, 5701.1448282756255, 5709.571782116275, 5743.483086522233, 5740.1102829061565, 5742.08126699726, 5744.913826292525, 5749.080147176599, 5751.4536119068625, 5503.229547863222, 5633.690147041952, 5636.520895723909, 5643.294588176478, 5645.548356131418, 5611.298587676541, 5625.549956003591, 5664.518515926193, 5726.284214473191, 5737.512311453574, 5534.695591742493, 5535.142052981794, 5547.278853748987, 5569.387367389665, 5581.218629391969], "value": 5353.100325536587, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.9557630965832, 643.0900276109305, 645.9515536334776, 646.4020780777785, 660.9537332386732, 42994.78502607487, 45301.77349113254, 45382.31926521101, 49642.25536616945, 49735.50264497354, 46982.0, 47518.0, 47565.28652070222, 47616.52383476173, 47678.0, 8937.865932011026, 9085.091490850928, 9090.181883630472, 9090.181883630472, 9090.590923408463, 8943.731688049369, 9034.412763170405, 9036.638534458614, 9041.819163616723, 9048.638054477813, 9008.68469603564, 9100.180983711465, 9111.769911062021, 9117.954410227972, 9119.49842758648, 9029.0519495453, 9032.14194651507, 9052.592633331516, 9071.954640226822, 9075.727728168165, 8904.421212621159, 8993.370464067519, 9000.414973026798, 9001.459912405277, 9005.369624126313, 42982.57017429817, 43140.35289470661, 43285.56714432847, 43403.131937361395, 43473.56526434727, 3548.183917698944, 3584.5698516178227, 3635.4183330666965, 3713.3687273163628, 3782.7352085354037, 33109.337813243845, 33203.83398083018, 33628.49557256643, 33660.83169584136, 34437.33064528214, 4088.407180958774, 4156.43888075112, 4179.582041795827, 4212.873613791591, 4363.127374525107, 349518.0, 349522.0, 351872.0, 352128.0, 352881.0, 53435.73282133642, 58968.0, 59629.403705963254, 60867.69566152141, 62220.0, 66243.0, 66283.33716662868, 66358.0, 66365.0, 66543.0, 17800.643987120184, 17978.460646180636, 17984.730229046574, 17985.280588776302, 17993.370232041816, 12349.93825030869, 12444.564440244549, 12446.315452650191, 12572.497100116076, 13872.93063534696, 18247.452576422464, 18323.0, 18353.90823045903, 18377.0, 18409.26362945494, 6930.430828123521, 6961.408280296231, 7042.542234754725, 7223.711051557879, 7297.1608265049135, 977.2133432586694, 989.7080361293436, 1047.4134484688445, 1097.506122244977, 1141.1042331769686, 31904.0, 33944.49083263753, 34289.657103428646, 34889.47665785015, 35268.29463410769, 1141.2695874640237, 1143.3825734103657, 1145.4330106597288, 1172.1091970102784, 1175.2008634128704, 16989.915050424668, 17963.461096167135, 18156.0, 18168.1824317906, 18190.090495475175, 533648.0, 540023.0, 541242.0, 542061.0, 542071.0, 60558.0, 61419.38580614226, 61915.0, 61923.69038154781, 62081.37918620756, 1396.7136736968896, 1404.9719005620034, 1419.8012278281049, 1427.6502256947322, 1449.0508716790519, 31609.841950790098, 32897.506537402434, 33407.331853363285, 33468.49797253092, 34042.31915361629, 54514.0, 55337.72331138318, 55345.0, 55351.72324138354, 55447.0, 452301.0, 462389.0, 463008.0, 507340.0, 510348.0, 45005.54994450013, 45098.77450612726, 45635.315470267305, 45989.0, 46292.768536157106, 49796.0, 50378.244326335865, 50839.49160508347, 51244.487555123975, 51703.0, 42026.789866051695, 42771.7861410691, 42772.572274276856, 42838.35742463928, 44259.55740442683, 42429.363559546015, 42475.3628695576, 42677.35983960305, 42707.35938960856, 42864.57135428606, 43301.0, 43863.12273754571, 43906.34140487959, 44003.0, 44541.77729111463, 36055.27889442144, 38493.57757845769, 38832.6116738829, 38975.80512097535, 39122.84899603126, 29198.992241861375, 29599.26001849971, 29634.555481668223, 30198.396032078796, 30256.697433025387], "value": 21148.34128216782, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.9755214687117, 412.9793510324484, 414.6164797562258, 415.7110807988448, 425.93185090385543, 18081.909590452062, 19929.900350498247, 19949.601007979833, 20366.0, 21531.46171345717, 12020.819687704692, 12074.879251207485, 12341.568045118423, 12345.506179752821, 12374.071944604146, 5105.18317069269, 5251.923355712089, 5260.763265653036, 5269.130593452076, 5277.736113194344, 4978.079055374767, 5174.948250517503, 5181.274621552986, 5182.119039763231, 5182.37811462625, 5166.354205724285, 5170.2503137045105, 5170.638055336128, 5170.715610641413, 5173.896522069556, 5120.718360490171, 5123.205903085028, 5210.218467229927, 5261.710605916673, 5266.131088370415, 5082.567981721558, 5102.744862756885, 5111.539961403491, 5112.028714544262, 5113.309703190059, 21095.78904210969, 23031.654525182137, 23735.16926907567, 23979.640305395435, 24141.879290603436, 2445.095314733542, 2447.8531288122767, 2448.326710154704, 2608.5565453872696, 2639.5908634161738, 10327.225458090674, 10410.89589104107, 10626.309289896131, 10690.625828096057, 10702.839457408145, 1862.8602854785809, 1889.1215584753156, 1951.9654583070994, 1958.8824670519814, 2030.0661695620033, 49589.75205123988, 60500.0, 85043.0, 85446.14553854507, 86037.13962860355, 11096.722581935355, 11287.75834658187, 11352.94323528377, 11378.146639001943, 11618.76762464763, 15954.04275743459, 16003.919980400024, 16064.0, 16090.115043672711, 16104.919475402783, 8395.832083358298, 8568.871466928002, 8746.56267186638, 8796.516191609522, 8815.955920220358, 8357.623906924204, 8394.622241999125, 8395.454295470898, 8604.02107515275, 8668.353012927524, 4760.95239047612, 4880.780364883589, 4936.679115857458, 4943.8516844494725, 5045.621578381636, 1897.8766380185243, 1903.7525121734086, 1994.8603597748308, 2016.9846080110328, 2048.365006847882, 943.6791490893129, 1000.444753162002, 1072.0191025212, 1080.0603474976663, 1102.2174256278133, 18062.729059064124, 19148.521286967938, 19169.137388817537, 19442.319518816774, 19702.507437314183, 1015.603914473356, 1016.9338992965434, 1037.0873631204643, 1037.1184493180888, 1097.868255809308, 10514.16002199612, 10579.576816927236, 10644.520996555173, 10670.146388289073, 10679.626213082505, 473420.0, 498958.0, 501074.0, 509445.0, 512720.0, 89974.0, 90698.09301907028, 91872.0, 92170.53914730383, 93123.0, 1060.0936199549428, 1066.5573786878358, 1083.4214569325113, 1084.2952081147316, 1089.7765957978595, 17334.5666358337, 17414.912925435292, 17716.557086072437, 17740.911295443955, 17812.643747124726, 40804.79597601993, 41160.970975724646, 41931.7903410481, 42730.0, 43416.13167736691, 59521.0, 61070.0, 61144.0, 62219.0, 62678.0, 8709.477431354138, 8861.33539984491, 8879.289656827568, 9092.045335239705, 9330.766730831783, 10706.143508519455, 13482.865171348161, 13848.792268116187, 13960.790588140982, 14109.365078571695, 11832.645020649394, 11961.282323060645, 12121.75756484883, 12271.386430678609, 12286.631401057983, 11721.706957326134, 11732.0, 11993.34036627994, 12056.578019769266, 12088.576899808464, 11591.710207244887, 12317.137800353888, 12356.691082723004, 12412.689682758004, 12534.122611417113, 8499.10759370282, 8969.865452018355, 8999.820003600022, 9171.495567743837, 12150.392480376122, 12413.379331033231, 12483.063770217088, 12635.494580216688, 12937.54718584845, 13197.802032969705], "value": 9218.374481328552, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.036239451187, 1544.729672307346, 1588.3567155302103, 1759.1819803791234, 1865.9440216793491, 8165.101838797735, 8460.957695211515, 8473.322134229262, 8706.1729135732, 8723.86914196286, 8463.703770368038, 8639.0, 8644.740657780261, 8653.0, 8663.09037551056, 2068.2347531413398, 2120.0141934000685, 2122.9469263268466, 2324.2562380038435, 2354.09367393553, 2364.3734410381176, 2458.6926634170727, 2461.852288862674, 2464.408541949928, 2465.6671349367784, 2278.088764494199, 2295.5638428698576, 2307.976920230793, 2318.7681231876845, 2346.718393792747, 2063.9277625283044, 2225.977740222593, 2406.68713067301, 2434.513097380522, 2440.4386990992084, 2395.7843794058444, 2421.8910149043336, 2422.7213870404953, 2425.042108367185, 2426.708794944618, 9123.726288211365, 9220.077992200793, 9226.53867306632, 9228.8615670765, 9229.123233292894, 5374.8925021499745, 5476.726163691839, 5500.614956953015, 5503.532199763025, 5503.862403439906, 3283.5567198428143, 3311.61916379617, 3426.0, 3456.5333679953137, 3470.4794280857823, 1183.946722397494, 1189.6966273600262, 1210.104522653239, 1210.7881120803825, 1248.7065539598277, 12642.367881605975, 12658.620241392773, 12667.366631668472, 12675.49298028087, 12705.555305564261, 4691.7654117294, 4778.232054139921, 4866.489018653061, 4875.219964805614, 4890.902181956341, 10757.354558726502, 10859.34843909368, 10862.891371086345, 10864.402457864735, 10870.61952831647, 5506.531944784738, 5507.917381239285, 5558.6942718150085, 5565.666060036411, 5570.220169176318, 4457.509673935864, 4765.8570242892765, 4871.415430148334, 5042.067217564762, 5104.259882317043, 4116.361963895601, 4143.647770226639, 4166.291730405838, 4180.895477613084, 4198.433211516467, 1303.6610481274747, 1310.1091257944688, 1323.6955500235, 1325.5228117877557, 1331.4274861809322, 907.120093509295, 929.7303781903307, 1046.7016900183412, 1049.375621505203, 1068.8503609494676, 6410.45511131559, 6444.45222156122, 6546.607203567801, 6569.113169722026, 6573.704183311763, 950.2445555783169, 952.514217748953, 1033.2870319479534, 1042.3224903812581, 1044.7022598559374, 4584.266517357207, 4653.7207767534055, 4659.324397962344, 4659.697119687209, 4664.720116793003, 10541.31481453703, 11334.093272538177, 11648.767024659459, 11665.24175928562, 11676.591319303954, 10958.178136639784, 11150.665480035612, 11273.323600583828, 11294.435278236055, 11308.321500709984, 8433.28317093054, 8624.611892464854, 8639.0, 8647.135286471303, 8649.394542382099, 6385.904211436739, 6547.770828020996, 6627.271000190071, 6661.833454163685, 6771.6614169292325, 10357.4303413313, 10449.21630877672, 10491.790164196826, 10514.421706806199, 10610.946945265225, 10280.177585793268, 10662.786744265226, 10666.83999739989, 10737.355758654505, 10762.300450470695, 7442.8511429770015, 7950.681972721027, 8000.399970002157, 8117.675292988214, 8243.0520490142, 9123.726288211365, 9165.129312715282, 9207.86188207164, 9259.305552083486, 9271.258299335903, 7986.440949133505, 8162.877556836534, 8189.099199087977, 8266.628001739817, 8324.70863519774, 8087.272145506932, 8141.8371632568205, 8169.591520423836, 8240.1347858474, 8319.958400207961, 7544.735934242494, 7601.0, 7606.163322034461, 7610.505317154367, 7621.047369078865, 5366.060939335523, 5378.435264297193, 5410.431904650112, 5565.888682226414, 5640.54875609942, 5661.915071273852, 5704.1443783432815, 5732.05421105513, 5820.679862607597, 5853.03424934898], "value": 4848.087305158532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.45990954563155, 336.2206238322249, 345.4283161367935, 346.1571074433754, 350.3798277049624, 5166.380034395868, 5280.0, 5326.201069839526, 5399.865003374917, 5405.945940540593, 5276.0, 5291.359678499658, 5319.281896943911, 5333.38666053404, 5343.225232341309, 4215.419217793327, 4221.577842215769, 4236.809343579539, 4238.491381034265, 4260.190563792885, 3921.4313924480934, 4010.7994600270017, 4164.187983343234, 4229.619334259916, 4259.424977628027, 4100.507939047318, 4135.503739551258, 4136.81384337704, 4139.710220284582, 4145.357469592218, 4090.2433049885867, 4131.987663022549, 4145.97927010366, 4157.875263742078, 4162.751174647608, 3455.1707590178426, 3671.6695497405094, 3687.557493100818, 3698.8335524901445, 3701.61133081028, 5086.694798312114, 5101.795928162869, 5105.774614092591, 5121.257417674454, 5128.333316668846, 4731.668783185179, 4741.715497070187, 4742.407199100113, 4746.382970213885, 4760.452547956995, 5371.4897084776885, 5499.285092937933, 5509.173623956399, 5522.171674248854, 5526.226328314039, 3952.7825969571663, 3998.620131087542, 4004.3392840181336, 4006.357393468693, 4020.195960807821, 5674.148877668342, 5724.112762521816, 5734.311882574076, 5751.511121554715, 5768.192453056576, 3965.1871366369824, 4080.8775736727353, 4092.0792821615496, 4098.467199264077, 4106.527749308868, 4087.202995415865, 4120.1553681495225, 4148.56440073794, 4154.522229943595, 4163.500379954425, 4707.976460117678, 4710.222813235778, 4712.151812673752, 4713.86867151888, 4735.526447355237, 5410.566199957015, 5530.557555395559, 5540.418256083134, 5543.1685247213245, 5554.389017208104, 1380.061558140474, 1382.3411905713185, 1383.7785954247272, 1390.7705228637162, 1392.262101086426, 887.7603047177226, 893.9061398553189, 912.5254867468879, 914.1544071733753, 924.8843894513186, 4818.229083346648, 4857.4171099467585, 4865.175559165313, 4873.926891096637, 4883.218685010382, 5046.571041461517, 5065.721385323843, 5072.163093089673, 5083.262926875583, 5087.567556757717, 4636.304554316879, 4852.344933433941, 4887.853364399074, 4911.312416261726, 4911.7544122793715, 2694.191742477249, 2705.688845782721, 2707.7563019328354, 2716.904908328199, 2718.238893109932, 5210.400803907523, 5237.166991553002, 5264.105102132647, 5311.681299122065, 5317.973410132925, 4816.494268101869, 5063.746812659426, 5149.995750828627, 5170.784865556591, 5187.792488300426, 5213.452587478336, 5232.895342093213, 5254.161043634774, 5292.735363231823, 5323.787048518094, 5009.221726287115, 5069.327122049788, 5085.923711144262, 5088.974555127201, 5093.8726531837, 4906.9018619626695, 4995.025969935828, 5021.824236151717, 5044.621653376085, 5085.542301192762, 5203.349581302337, 5251.52736253739, 5260.395579348183, 5265.315508983807, 5291.444398338119, 3734.1971476132762, 3846.4614953907035, 3968.4245784361683, 4059.1881623674794, 4094.9181016380103, 5647.519960803378, 5663.235463212413, 5682.488576028178, 5682.744276507485, 5720.227769251264, 5422.945770542402, 5594.300712410949, 5608.495235428831, 5616.157576363578, 5619.80330688424, 5439.3200849893765, 5474.124140137559, 5491.450854914477, 5496.0107180707855, 5511.200875872896, 5383.138697808332, 5389.8922021558565, 5394.892102157856, 5400.486953739389, 5406.432324605859, 5224.9216261756865, 5466.699331536804, 5477.3427188737605, 5484.424135465878, 5489.972550137224, 5347.625666203328, 5363.704996225089, 5371.5971302153275, 5379.811706590251, 5400.918986215289], "value": 4100.385144713531, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.4035683521619, 890.363390176024, 890.7194233816351, 893.1738142218451, 893.2139717048996, 15537.14545699986, 15995.0, 16020.118893460884, 16029.0, 16055.277512511935, 14953.551393458214, 14954.626134346647, 14967.47613833516, 14985.7002859943, 14987.400503979828, 701.9122609673791, 798.1659166171365, 829.4194064155085, 832.138736407818, 835.1481488881337, 825.6779855856221, 830.1325115254574, 833.5207255827893, 836.0260296754304, 837.5226121110969, 869.1829680100685, 871.8910136232971, 873.3842640938124, 874.1477059866611, 874.7813046738315, 734.2290594875386, 859.8495263328924, 894.8255090257402, 897.3583887520455, 897.5557099235855, 650.7624716978269, 651.3746803069059, 652.3313603556345, 652.6899722631712, 652.8367908022994, 13838.654033649138, 13844.930775346058, 13861.514846980308, 13865.792013119812, 13879.236641984688, 14153.433862645481, 14528.418863245457, 14541.418343266256, 14550.12699238049, 14785.852141478555, 12762.234265944073, 12898.484060637564, 13022.348882555929, 13116.606501804865, 13246.271455069967, 4525.751083690396, 4602.746848923308, 4632.5830675239395, 4655.790489427984, 4717.905641887143, 57058.7147064262, 58414.0, 58561.70719146377, 60698.39301607016, 64288.357116428706, 11734.413279335999, 12234.449449774782, 12280.324582148067, 12360.25838449696, 12767.042471814651, 21897.671534926987, 21905.233316833834, 21910.671339929915, 21916.0, 21935.890320548297, 9101.180893719464, 9259.398139120936, 9262.67580634688, 9307.860382094274, 9529.0, 4781.665283430127, 4861.51384861511, 4900.362952816111, 4951.851444456672, 5030.622703297267, 3584.4802503636824, 3614.0603443104983, 3706.684931780775, 3710.7216958728204, 3710.9072273193387, 641.0960545630638, 702.3854127638316, 719.0329007484925, 742.8699977503916, 757.5871150223142, 764.9885251721229, 780.8008957715859, 787.7282337593521, 794.1780257433644, 830.7466222801983, 9744.512774361254, 10080.445575493419, 10142.645007424704, 10179.134773544332, 10185.083342499063, 705.3158436316764, 716.2416268061895, 716.992830071703, 772.0503780350201, 812.9227723366271, 5058.595312375075, 5145.794168233304, 5177.326947496801, 5179.741012949337, 5187.377514698261, 74098.25901741021, 76484.61757691287, 77329.0, 77469.22530774733, 77557.61221193858, 60588.0, 63027.0, 64146.0, 67426.0, 67468.32531674621, 60700.08949865756, 61158.69420652957, 62058.0, 62171.06743398853, 63055.369446305864, 10184.185265178916, 10367.84448233262, 10501.737456563647, 10585.629502967358, 10608.257421980386, 38565.80717096396, 39024.6097539021, 39771.204575907745, 40171.598284016785, 40248.597514025656, 52730.73634631802, 52734.472655272955, 52776.0, 53010.939781203386, 53337.19994200012, 12797.29614871191, 12855.100142990275, 12959.676008099874, 13063.0, 13434.731305374033, 14430.711385772436, 14510.274486275855, 15361.769573456631, 15732.370705171665, 15745.606359840638, 12786.041046921704, 13279.203247805162, 14341.498047568284, 14685.0, 14713.632159196106, 13537.729245415234, 13554.118982266122, 13605.727885442433, 13750.793738094137, 13796.0, 12839.614811555668, 13084.803727943898, 13150.671233219246, 13162.341882905625, 13395.062345635712, 11747.412629368668, 11911.642650720492, 11912.40437978115, 12048.819267710816, 12068.39658017078, 12127.211731237441, 12136.93931530372, 12155.623776737915, 12309.384530773246, 12356.938215309225], "value": 7433.223801992494, "unit": "score"}], "name": "dromaeo_css", "value": 7323.058970893854, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 103.0, 110.0, 110.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 124.0, 147.0, 206.0, 126.0, 158.0, 161.0, 127.0, 188.0, 128.0], "value": 152.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 162.0, 160.0, 161.0, 162.0, 162.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 152.0, 76.0, 74.0, 74.0, 74.0, 130.0, 75.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 145.0, 92.0, 92.0, 92.0, 120.0, 92.0, 93.0, 130.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 144.0, 145.0, 145.0, 144.0, 145.0], "value": 144.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 100.0, 96.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 54.0, 55.0, 55.0, 107.0, 53.0, 53.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 84.0, 77.0, 79.0, 78.0, 79.0, 79.0, 83.0, 79.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 124.0, 128.0, 121.0, 121.0, 117.0, 127.0, 124.0, 196.0, 121.0], "value": 130.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 161.0, 163.0, 163.0, 162.0, 159.0, 160.0, 161.0, 163.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 63.0, 63.0, 65.0, 62.0, 62.0, 63.0, 60.0, 61.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1524.4999999999998, "alertThreshold": 2.0}]} 01:44:14 INFO - Return code: 0 01:44:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:44:14 ERROR - # TBPL SUCCESS # 01:44:14 INFO - Running post-action listener: _resource_record_post_action 01:44:14 INFO - ##### 01:44:14 INFO - ##### Finished run-tests step (success) 01:44:14 INFO - ##### 01:44:14 INFO - Running post-run listener: _resource_record_post_run 01:44:14 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536666112; Read time: 4; Write time: 453160 01:44:14 INFO - TinderboxPrint: CPU usage
13.2% 01:44:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 01:44:14 INFO - TinderboxPrint: I/O write bytes / time
536,666,112 / 453,160 01:44:14 INFO - TinderboxPrint: CPU idle
8,265.4 (86.8%) 01:44:14 INFO - TinderboxPrint: CPU user
1,209.5 (12.7%) 01:44:14 INFO - TinderboxPrint: Swap in / out
0 / 0 01:44:14 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246665216; Read time: 0; Write time: 267808 01:44:14 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290000896; Read time: 4; Write time: 185352 01:44:14 INFO - Running post-run listener: _upload_blobber_files 01:44:14 INFO - Blob upload gear active. 01:44:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:44:14 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 01:44:14 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:44:14 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:44:15 INFO - (blobuploader) - INFO - Open directory for files ... 01:44:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:44:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:44:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:44:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:44:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:44:15 INFO - (blobuploader) - INFO - Done attempting. 01:44:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:44:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:44:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:44:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:44:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:44:16 INFO - (blobuploader) - INFO - Done attempting. 01:44:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:44:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:44:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:44:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:44:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:44:20 INFO - (blobuploader) - INFO - Done attempting. 01:44:20 INFO - (blobuploader) - INFO - Iteration through files over. 01:44:20 INFO - Return code: 0 01:44:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:44:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:44:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f3b6b64d3d51833346c1526a951fb581b4ed540ba5e4e895c9f245f418271e34ed3e820c2ce1febdc9af6924572ac93b48fbceeaf98b4e1bfa33fd5be79b26bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df454973bbc8bd2ad4f94317298eb0000babf06127354106686a082d8bdb3c5e03fddca0fafb75a43d70854831a575da46a7739b258f80a4954380046421ac81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fd4f95e6a59a2d318ed21c4e2b10e623a08dd3f18ba2797c4aa86f057c93a91f22bd7f3fb33b75a5abbfeae391787697d382857f5d495e338b333f46cba290a0"} 01:44:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:44:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:44:20 INFO - Contents: 01:44:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f3b6b64d3d51833346c1526a951fb581b4ed540ba5e4e895c9f245f418271e34ed3e820c2ce1febdc9af6924572ac93b48fbceeaf98b4e1bfa33fd5be79b26bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df454973bbc8bd2ad4f94317298eb0000babf06127354106686a082d8bdb3c5e03fddca0fafb75a43d70854831a575da46a7739b258f80a4954380046421ac81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fd4f95e6a59a2d318ed21c4e2b10e623a08dd3f18ba2797c4aa86f057c93a91f22bd7f3fb33b75a5abbfeae391787697d382857f5d495e338b333f46cba290a0"} 01:44:20 INFO - Running post-run listener: copy_logs_to_upload_dir 01:44:20 INFO - Copying logs to upload dir... 01:44:20 INFO - mkdir: /builds/slave/test/build/upload/logs 01:44:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.672258 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-18 01:44:20.800711) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 01:44:20.803869) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f3b6b64d3d51833346c1526a951fb581b4ed540ba5e4e895c9f245f418271e34ed3e820c2ce1febdc9af6924572ac93b48fbceeaf98b4e1bfa33fd5be79b26bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df454973bbc8bd2ad4f94317298eb0000babf06127354106686a082d8bdb3c5e03fddca0fafb75a43d70854831a575da46a7739b258f80a4954380046421ac81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fd4f95e6a59a2d318ed21c4e2b10e623a08dd3f18ba2797c4aa86f057c93a91f22bd7f3fb33b75a5abbfeae391787697d382857f5d495e338b333f46cba290a0"} build_url:https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011450 build_url: 'https://queue.taskcluster.net/v1/task/I-SEj3maTjqcBtwSei5wSw/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/Mozilla-Inbound/sha512/f3b6b64d3d51833346c1526a951fb581b4ed540ba5e4e895c9f245f418271e34ed3e820c2ce1febdc9af6924572ac93b48fbceeaf98b4e1bfa33fd5be79b26bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df454973bbc8bd2ad4f94317298eb0000babf06127354106686a082d8bdb3c5e03fddca0fafb75a43d70854831a575da46a7739b258f80a4954380046421ac81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fd4f95e6a59a2d318ed21c4e2b10e623a08dd3f18ba2797c4aa86f057c93a91f22bd7f3fb33b75a5abbfeae391787697d382857f5d495e338b333f46cba290a0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 01:44:20.828508) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 01:44:20.828840) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463558183.985074-496874757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004419 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 01:44:20.911664) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 01:44:20.912021) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 01:44:20.912355) ========= ========= Total master_lag: 0.18 =========