builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1463181762.16 results: success (0) buildid: 20160513133456 builduid: 1a2214eb952a4737b8cb1ac452b25626 revision: 53ab4450aff98742ad34122c7d81b1678dc4d6a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.158471) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.158930) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.159432) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.177599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.177899) ========= 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=8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 16:22:42-- 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% 42.9M=0s 2016-05-13 16:22:42 (42.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105024 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.295669) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.295963) ========= 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=8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021014 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:42.344869) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 16:22:42.345174) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 53ab4450aff98742ad34122c7d81b1678dc4d6a4 --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 53ab4450aff98742ad34122c7d81b1678dc4d6a4 --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=8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 16:22:42,499 truncating revision to first 12 chars 2016-05-13 16:22:42,500 Setting DEBUG logging. 2016-05-13 16:22:42,500 attempt 1/10 2016-05-13 16:22:42,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/53ab4450aff9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 16:22:43,813 unpacking tar archive at: mozilla-inbound-53ab4450aff9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.598558 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 16:22:43.956460) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:43.956789) ========= script_repo_revision: 53ab4450aff98742ad34122c7d81b1678dc4d6a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:43.957164) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:43.957426) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 16:22:43.966666) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-13 16:22:43.966929) ========= /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=8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:22:44 INFO - MultiFileLogger online at 20160513 16:22:44 in /builds/slave/test 16:22:44 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 16:22:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:22:44 INFO - {'append_to_log': False, 16:22:44 INFO - 'base_work_dir': '/builds/slave/test', 16:22:44 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:22:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:22:44 INFO - 'branch': 'Mozilla-Inbound', 16:22:44 INFO - 'buildbot_json_path': 'buildprops.json', 16:22:44 INFO - 'config_files': ('talos/linux_config.py',), 16:22:44 INFO - 'default_actions': ('clobber', 16:22:44 INFO - 'read-buildbot-config', 16:22:44 INFO - 'download-and-extract', 16:22:44 INFO - 'populate-webroot', 16:22:44 INFO - 'create-virtualenv', 16:22:44 INFO - 'install', 16:22:44 INFO - 'run-tests'), 16:22:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:22:44 INFO - 'download_minidump_stackwalk': True, 16:22:44 INFO - 'download_symbols': 'ondemand', 16:22:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:22:44 INFO - 'tooltool.py': '/tools/tooltool.py', 16:22:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:22:44 INFO - '/tools/misc-python/virtualenv.py')}, 16:22:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:22:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:22:44 INFO - 'installer_path': 'installer.exe', 16:22:44 INFO - 'log_level': 'info', 16:22:44 INFO - 'log_name': 'talos', 16:22:44 INFO - 'log_to_console': True, 16:22:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:22:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:22:44 INFO - 'opt_config_files': (), 16:22:44 INFO - 'pip_index': False, 16:22:44 INFO - 'sps_profile': False, 16:22:44 INFO - 'sps_profile_interval': 0, 16:22:44 INFO - 'suite': 'dromaeojs', 16:22:44 INFO - 'system_bits': '32', 16:22:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:22:44 INFO - 'title': 'talos-linux64-ix-038', 16:22:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:22:44 INFO - 'use_talos_json': True, 16:22:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:22:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:22:44 INFO - 'work_dir': 'build'} 16:22:44 INFO - ##### 16:22:44 INFO - ##### Running clobber step. 16:22:44 INFO - ##### 16:22:44 INFO - Running pre-action listener: _resource_record_pre_action 16:22:44 INFO - Running main action method: clobber 16:22:44 INFO - rmtree: /builds/slave/test/build 16:22:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:22:45 INFO - Running post-action listener: _resource_record_post_action 16:22:45 INFO - ##### 16:22:45 INFO - ##### Finished clobber step (success) 16:22:45 INFO - ##### 16:22:45 INFO - ##### 16:22:45 INFO - ##### Running read-buildbot-config step. 16:22:45 INFO - ##### 16:22:45 INFO - Running pre-action listener: _resource_record_pre_action 16:22:45 INFO - Running main action method: read_buildbot_config 16:22:45 INFO - Using buildbot properties: 16:22:45 INFO - { 16:22:45 INFO - "project": "", 16:22:45 INFO - "product": "firefox", 16:22:45 INFO - "script_repo_revision": "production", 16:22:45 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:22:45 INFO - "repository": "", 16:22:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:22:45 INFO - "buildid": "20160513133456", 16:22:45 INFO - "pgo_build": "True", 16:22:45 INFO - "basedir": "/builds/slave/test", 16:22:45 INFO - "buildnumber": 909, 16:22:45 INFO - "slavename": "talos-linux64-ix-038", 16:22:45 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:22:45 INFO - "platform": "ubuntu64_hw", 16:22:45 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:22:45 INFO - "branch": "mozilla-inbound", 16:22:45 INFO - "repo_path": "integration/mozilla-inbound", 16:22:45 INFO - "revision": "53ab4450aff98742ad34122c7d81b1678dc4d6a4", 16:22:45 INFO - "stage_platform": "linux64-pgo", 16:22:45 INFO - "builduid": "1a2214eb952a4737b8cb1ac452b25626", 16:22:45 INFO - "slavebuilddir": "test" 16:22:45 INFO - } 16:22:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:22:45 INFO - Running post-action listener: _resource_record_post_action 16:22:45 INFO - ##### 16:22:45 INFO - ##### Finished read-buildbot-config step (success) 16:22:45 INFO - ##### 16:22:45 INFO - ##### 16:22:45 INFO - ##### Running download-and-extract step. 16:22:45 INFO - ##### 16:22:45 INFO - Running pre-action listener: _resource_record_pre_action 16:22:45 INFO - Running main action method: download_and_extract 16:22:45 INFO - mkdir: /builds/slave/test/build/tests 16:22:45 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')]} 16:22:45 INFO - https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:22:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:22:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:22:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:46 INFO - Downloaded 1361 bytes. 16:22:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:22:46 INFO - Using the following test package requirements: 16:22:46 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:22:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:22:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'jsshell-linux-x86_64.zip'], 16:22:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:22:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:22:46 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:22:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:22:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:22:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:46 INFO - https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:22:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:22:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:22:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:47 INFO - Downloaded 24022158 bytes. 16:22:48 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'] 16:22:48 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 16:22:49 INFO - Return code: 0 16:22:49 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 16:22:49 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')]} 16:22:49 INFO - https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:22:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:22:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:22:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:50 INFO - Downloaded 24022158 bytes. 16:22:50 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'] 16:22:50 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 16:22:52 INFO - Return code: 0 16:22: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')]} 16:22:52 INFO - https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:22:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:53 INFO - Downloaded 10840194 bytes. 16:22: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'] 16:22: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 16:22:53 INFO - Return code: 0 16:22:53 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')]} 16:22:53 INFO - https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:22:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:22:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:22:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PAC6ogm6TeWJUHWBedZaMA/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 16:22:55 INFO - Downloaded 58373882 bytes. 16:22:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:22:55 INFO - mkdir: /builds/slave/test/properties 16:22:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:22:55 INFO - Writing to file /builds/slave/test/properties/build_url 16:22:55 INFO - Contents: 16:22:55 INFO - build_url:https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:22:56 INFO - Running post-action listener: _resource_record_post_action 16:22:56 INFO - Running post-action listener: set_extra_try_arguments 16:22:56 INFO - ##### 16:22:56 INFO - ##### Finished download-and-extract step (success) 16:22:56 INFO - ##### 16:22:56 INFO - ##### 16:22:56 INFO - ##### Running populate-webroot step. 16:22:56 INFO - ##### 16:22:56 INFO - Running pre-action listener: _resource_record_pre_action 16:22:56 INFO - Running main action method: populate_webroot 16:22:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:22:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:22:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:22:56 INFO - '--tppagecycles', 16:22:56 INFO - '7'], 16:22:56 INFO - 'tests': ['tsvgm']}}, 16:22:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:22:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:22:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:22:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:22:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['damp', 'tps']}, 16:22:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'tests': ['damp', 'tps']}, 16:22:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['dromaeo_dom']}, 16:22:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:22:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['a11yr', 16:22:56 INFO - 'ts_paint', 16:22:56 INFO - 'tpaint', 16:22:56 INFO - 'sessionrestore', 16:22:56 INFO - 'sessionrestore_no_auto_restore', 16:22:56 INFO - 'tabpaint']}, 16:22:56 INFO - 'other-e10s': {'tests': ['a11yr', 16:22:56 INFO - 'ts_paint', 16:22:56 INFO - 'tpaint', 16:22:56 INFO - 'sessionrestore', 16:22:56 INFO - 'sessionrestore_no_auto_restore', 16:22:56 INFO - 'tabpaint']}, 16:22:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:22:56 INFO - 'ts_paint', 16:22:56 INFO - 'tpaint', 16:22:56 INFO - 'sessionrestore', 16:22:56 INFO - 'sessionrestore_no_auto_restore', 16:22:56 INFO - 'tabpaint']}, 16:22:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:22:56 INFO - 'ts_paint', 16:22:56 INFO - 'tpaint', 16:22:56 INFO - 'sessionrestore', 16:22:56 INFO - 'sessionrestore_no_auto_restore', 16:22:56 INFO - 'tabpaint']}, 16:22:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['a11yr', 16:22:56 INFO - 'ts_paint', 16:22:56 INFO - 'tpaint', 16:22:56 INFO - 'sessionrestore', 16:22:56 INFO - 'sessionrestore_no_auto_restore', 16:22:56 INFO - 'tabpaint']}, 16:22:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['a11yr', 16:22:56 INFO - 'ts_paint', 16:22:56 INFO - 'tpaint', 16:22:56 INFO - 'sessionrestore', 16:22:56 INFO - 'sessionrestore_no_auto_restore', 16:22:56 INFO - 'tabpaint']}, 16:22:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['tsvgx', 16:22:56 INFO - 'tsvgr_opacity', 16:22:56 INFO - 'tart', 16:22:56 INFO - 'tscrollx', 16:22:56 INFO - 'cart']}, 16:22:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:22:56 INFO - 'tsvgr_opacity', 16:22:56 INFO - 'tart', 16:22:56 INFO - 'tscrollx', 16:22:56 INFO - 'cart']}, 16:22:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'talos_options': ['--disable-e10s'], 16:22:56 INFO - 'tests': ['tp5o']}, 16:22:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'tests': ['tp5o']}, 16:22:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'talos_options': ['--disable-e10s', 16:22:56 INFO - '--xperf_path', 16:22:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:56 INFO - 'tests': ['tp5n']}, 16:22:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:56 INFO - 'talos_options': ['--xperf_path', 16:22:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:56 INFO - 'tests': ['tp5n']}}, 16:22:56 INFO - 'talos.zip': {'path': '', 16:22:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:22:56 INFO - Running post-action listener: _resource_record_post_action 16:22:56 INFO - ##### 16:22:56 INFO - ##### Finished populate-webroot step (success) 16:22:56 INFO - ##### 16:22:56 INFO - ##### 16:22:56 INFO - ##### Running create-virtualenv step. 16:22:56 INFO - ##### 16:22:56 INFO - Running pre-action listener: _resource_record_pre_action 16:22:56 INFO - Running main action method: create_virtualenv 16:22:56 INFO - Creating virtualenv /builds/slave/test/build/venv 16:22: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 16:22:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:22:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:22:56 INFO - Using real prefix '/usr' 16:22:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:22:56 INFO - Installing distribute.............................................................................................................................................................................................done. 16:22:58 INFO - Installing pip.................done. 16:22:58 INFO - Return code: 0 16:22:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:22: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 16:22: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 16:22:58 INFO - Using env: {'DISPLAY': ':0', 16:22:58 INFO - 'HOME': '/home/cltbld', 16:22:58 INFO - 'LANG': 'en_US.UTF-8', 16:22:58 INFO - 'LANGUAGE': 'en_US:en', 16:22:58 INFO - 'LOGNAME': 'cltbld', 16:22:58 INFO - 'MAIL': '/var/mail/cltbld', 16:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:58 INFO - 'MOZ_NO_REMOTE': '1', 16:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:58 INFO - 'NO_EM_RESTART': '1', 16:22:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:58 INFO - 'PWD': '/builds/slave/test', 16:22:58 INFO - 'SHELL': '/bin/bash', 16:22:58 INFO - 'SHLVL': '1', 16:22:58 INFO - 'TERM': 'linux', 16:22:58 INFO - 'TMOUT': '86400', 16:22:58 INFO - 'USER': 'cltbld', 16:22:58 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:58 INFO - '_': '/tools/buildbot/bin/python'} 16:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22: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 16:22:58 INFO - Cleaning up... 16:22:58 INFO - Return code: 0 16:22:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:22: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 16:22: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 16:22:58 INFO - Using env: {'DISPLAY': ':0', 16:22:58 INFO - 'HOME': '/home/cltbld', 16:22:58 INFO - 'LANG': 'en_US.UTF-8', 16:22:58 INFO - 'LANGUAGE': 'en_US:en', 16:22:58 INFO - 'LOGNAME': 'cltbld', 16:22:58 INFO - 'MAIL': '/var/mail/cltbld', 16:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:58 INFO - 'MOZ_NO_REMOTE': '1', 16:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:58 INFO - 'NO_EM_RESTART': '1', 16:22:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:58 INFO - 'PWD': '/builds/slave/test', 16:22:58 INFO - 'SHELL': '/bin/bash', 16:22:58 INFO - 'SHLVL': '1', 16:22:58 INFO - 'TERM': 'linux', 16:22:58 INFO - 'TMOUT': '86400', 16:22:58 INFO - 'USER': 'cltbld', 16:22:58 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:58 INFO - '_': '/tools/buildbot/bin/python'} 16:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:59 INFO - Downloading/unpacking psutil>=3.1.1 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:23:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:23:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:23:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:23:01 INFO - Installing collected packages: psutil 16:23:01 INFO - Running setup.py install for psutil 16:23:01 INFO - building 'psutil._psutil_linux' extension 16:23: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 16:23: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 16:23:01 INFO - building 'psutil._psutil_posix' extension 16:23: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 16:23: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 16:23:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:23:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:23:01 INFO - Successfully installed psutil 16:23:01 INFO - Cleaning up... 16:23:01 INFO - Return code: 0 16:23:01 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:23: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.1'] in /builds/slave/test/build 16:23: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.1 16:23:01 INFO - Using env: {'DISPLAY': ':0', 16:23:01 INFO - 'HOME': '/home/cltbld', 16:23:01 INFO - 'LANG': 'en_US.UTF-8', 16:23:01 INFO - 'LANGUAGE': 'en_US:en', 16:23:01 INFO - 'LOGNAME': 'cltbld', 16:23:01 INFO - 'MAIL': '/var/mail/cltbld', 16:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:01 INFO - 'MOZ_NO_REMOTE': '1', 16:23:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:01 INFO - 'NO_EM_RESTART': '1', 16:23:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:01 INFO - 'PWD': '/builds/slave/test', 16:23:01 INFO - 'SHELL': '/bin/bash', 16:23:01 INFO - 'SHLVL': '1', 16:23:01 INFO - 'TERM': 'linux', 16:23:01 INFO - 'TMOUT': '86400', 16:23:01 INFO - 'USER': 'cltbld', 16:23:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:01 INFO - '_': '/tools/buildbot/bin/python'} 16:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:01 INFO - Downloading/unpacking mozsystemmonitor==0.1 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:03 INFO - Downloading mozsystemmonitor-0.1.tar.gz 16:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 16:23:03 INFO - Installing collected packages: mozsystemmonitor 16:23:03 INFO - Running setup.py install for mozsystemmonitor 16:23:03 INFO - Successfully installed mozsystemmonitor 16:23:03 INFO - Cleaning up... 16:23:03 INFO - Return code: 0 16:23:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:23: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 16:23: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 16:23:03 INFO - Using env: {'DISPLAY': ':0', 16:23:03 INFO - 'HOME': '/home/cltbld', 16:23:03 INFO - 'LANG': 'en_US.UTF-8', 16:23:03 INFO - 'LANGUAGE': 'en_US:en', 16:23:03 INFO - 'LOGNAME': 'cltbld', 16:23:03 INFO - 'MAIL': '/var/mail/cltbld', 16:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:03 INFO - 'MOZ_NO_REMOTE': '1', 16:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:03 INFO - 'NO_EM_RESTART': '1', 16:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:03 INFO - 'PWD': '/builds/slave/test', 16:23:03 INFO - 'SHELL': '/bin/bash', 16:23:03 INFO - 'SHLVL': '1', 16:23:03 INFO - 'TERM': 'linux', 16:23:03 INFO - 'TMOUT': '86400', 16:23:03 INFO - 'USER': 'cltbld', 16:23:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:03 INFO - '_': '/tools/buildbot/bin/python'} 16:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:06 INFO - Downloading blobuploader-1.2.4.tar.gz 16:23:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:23:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:23:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:06 INFO - Downloading docopt-0.6.1.tar.gz 16:23:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:23:06 INFO - Installing collected packages: blobuploader, requests, docopt 16:23:06 INFO - Running setup.py install for blobuploader 16:23:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:23:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:23:06 INFO - Running setup.py install for requests 16:23:07 INFO - Running setup.py install for docopt 16:23:07 INFO - Successfully installed blobuploader requests docopt 16:23:07 INFO - Cleaning up... 16:23:07 INFO - Return code: 0 16:23:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:23: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 16:23: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 16:23:07 INFO - Using env: {'DISPLAY': ':0', 16:23:07 INFO - 'HOME': '/home/cltbld', 16:23:07 INFO - 'LANG': 'en_US.UTF-8', 16:23:07 INFO - 'LANGUAGE': 'en_US:en', 16:23:07 INFO - 'LOGNAME': 'cltbld', 16:23:07 INFO - 'MAIL': '/var/mail/cltbld', 16:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:07 INFO - 'MOZ_NO_REMOTE': '1', 16:23:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:07 INFO - 'NO_EM_RESTART': '1', 16:23:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:07 INFO - 'PWD': '/builds/slave/test', 16:23:07 INFO - 'SHELL': '/bin/bash', 16:23:07 INFO - 'SHLVL': '1', 16:23:07 INFO - 'TERM': 'linux', 16:23:07 INFO - 'TMOUT': '86400', 16:23:07 INFO - 'USER': 'cltbld', 16:23:07 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:07 INFO - '_': '/tools/buildbot/bin/python'} 16:23:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:23:07 INFO - Running setup.py (path:/tmp/pip-IVHGre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:23:07 INFO - Running setup.py (path:/tmp/pip-lXfSid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:23:07 INFO - Running setup.py (path:/tmp/pip-mA3ve1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:23:07 INFO - Running setup.py (path:/tmp/pip-gnc4Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:23:07 INFO - Running setup.py (path:/tmp/pip-rk6JL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:23:08 INFO - Running setup.py (path:/tmp/pip-UQyPjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:23:08 INFO - Running setup.py (path:/tmp/pip-PKp5wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:23:08 INFO - Running setup.py (path:/tmp/pip-imIgci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:23:08 INFO - Running setup.py (path:/tmp/pip-EHJfZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:23:08 INFO - Running setup.py (path:/tmp/pip-fvmEq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:23:08 INFO - Running setup.py (path:/tmp/pip-fxZ6K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:23:08 INFO - Running setup.py (path:/tmp/pip-I2VNBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:23:08 INFO - Running setup.py (path:/tmp/pip-FG3ZlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:23:08 INFO - Running setup.py (path:/tmp/pip-xrjBK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:23:08 INFO - Running setup.py (path:/tmp/pip-4wpW_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:23:08 INFO - Running setup.py (path:/tmp/pip-AXujf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:23:08 INFO - Running setup.py (path:/tmp/pip-eokQm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:23:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:23:08 INFO - Running setup.py install for manifestparser 16:23:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:23:08 INFO - Running setup.py install for mozcrash 16:23:09 INFO - Running setup.py install for mozdebug 16:23:09 INFO - Running setup.py install for mozdevice 16:23:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Running setup.py install for mozfile 16:23:09 INFO - Running setup.py install for mozhttpd 16:23:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Running setup.py install for mozinfo 16:23:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Running setup.py install for mozInstall 16:23:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Running setup.py install for mozleak 16:23:09 INFO - Running setup.py install for mozlog 16:23:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Running setup.py install for moznetwork 16:23:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:23:09 INFO - Running setup.py install for mozprocess 16:23:10 INFO - Running setup.py install for mozprofile 16:23:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:23:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:23:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:23:10 INFO - Running setup.py install for mozrunner 16:23:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:23:10 INFO - Running setup.py install for mozscreenshot 16:23:10 INFO - Running setup.py install for moztest 16:23:10 INFO - Running setup.py install for mozversion 16:23:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:23:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:23:10 INFO - Cleaning up... 16:23:10 INFO - Return code: 0 16:23:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:23: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 16:23: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 16:23:10 INFO - Using env: {'DISPLAY': ':0', 16:23:10 INFO - 'HOME': '/home/cltbld', 16:23:10 INFO - 'LANG': 'en_US.UTF-8', 16:23:10 INFO - 'LANGUAGE': 'en_US:en', 16:23:10 INFO - 'LOGNAME': 'cltbld', 16:23:10 INFO - 'MAIL': '/var/mail/cltbld', 16:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:10 INFO - 'MOZ_NO_REMOTE': '1', 16:23:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:10 INFO - 'NO_EM_RESTART': '1', 16:23:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:10 INFO - 'PWD': '/builds/slave/test', 16:23:10 INFO - 'SHELL': '/bin/bash', 16:23:10 INFO - 'SHLVL': '1', 16:23:10 INFO - 'TERM': 'linux', 16:23:10 INFO - 'TMOUT': '86400', 16:23:10 INFO - 'USER': 'cltbld', 16:23:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:10 INFO - '_': '/tools/buildbot/bin/python'} 16:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:23:10 INFO - Running setup.py (path:/tmp/pip-8v4JuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:23: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)) 16:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:23:10 INFO - Running setup.py (path:/tmp/pip-M9LO9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:23:11 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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:23:11 INFO - Running setup.py (path:/tmp/pip-8Bl0Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:23:11 INFO - Running setup.py (path:/tmp/pip-NOTYsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:23:11 INFO - Running setup.py (path:/tmp/pip-Chw4Y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:23:11 INFO - Running setup.py (path:/tmp/pip-_FqJ9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:23:11 INFO - Running setup.py (path:/tmp/pip-wXQ37b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:23:11 INFO - Running setup.py (path:/tmp/pip-9uDZO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:23:11 INFO - Running setup.py (path:/tmp/pip-lSEqa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:23:11 INFO - Running setup.py (path:/tmp/pip-YslJXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:23:11 INFO - Running setup.py (path:/tmp/pip-yXUrIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:23:11 INFO - Running setup.py (path:/tmp/pip-sS_PDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:23:11 INFO - Running setup.py (path:/tmp/pip-QVKhSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:23:11 INFO - Running setup.py (path:/tmp/pip-EShVP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:23:11 INFO - Running setup.py (path:/tmp/pip-GAwadU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:23: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)) 16:23:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:23:11 INFO - Running setup.py (path:/tmp/pip-z895QY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:23: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)) 16:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:23:12 INFO - Running setup.py (path:/tmp/pip-yHL7TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:13 INFO - Downloading blessings-1.6.tar.gz 16:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:23:14 INFO - Installing collected packages: blessings 16:23:14 INFO - Running setup.py install for blessings 16:23:14 INFO - Successfully installed blessings 16:23:14 INFO - Cleaning up... 16:23:14 INFO - Return code: 0 16:23:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:23:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:23:14 INFO - Reading from file tmpfile_stdout 16:23:14 INFO - Current package versions: 16:23:14 INFO - argparse == 1.2.1 16:23:14 INFO - blessings == 1.6 16:23:14 INFO - blobuploader == 1.2.4 16:23:14 INFO - docopt == 0.6.1 16:23:14 INFO - manifestparser == 1.1 16:23:14 INFO - mozInstall == 1.12 16:23:14 INFO - mozcrash == 0.17 16:23:14 INFO - mozdebug == 0.1 16:23:14 INFO - mozdevice == 0.48 16:23:14 INFO - mozfile == 1.2 16:23:14 INFO - mozhttpd == 0.7 16:23:14 INFO - mozinfo == 0.9 16:23:14 INFO - mozleak == 0.1 16:23:14 INFO - mozlog == 3.2 16:23:14 INFO - moznetwork == 0.27 16:23:14 INFO - mozprocess == 0.22 16:23:14 INFO - mozprofile == 0.28 16:23:14 INFO - mozrunner == 6.11 16:23:14 INFO - mozscreenshot == 0.1 16:23:14 INFO - mozsystemmonitor == 0.1 16:23:14 INFO - moztest == 0.7 16:23:14 INFO - mozversion == 1.4 16:23:14 INFO - psutil == 3.1.1 16:23:14 INFO - requests == 1.2.3 16:23:14 INFO - wsgiref == 0.1.2 16:23:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:23: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 16:23: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 16:23:14 INFO - Using env: {'DISPLAY': ':0', 16:23:14 INFO - 'HOME': '/home/cltbld', 16:23:14 INFO - 'LANG': 'en_US.UTF-8', 16:23:14 INFO - 'LANGUAGE': 'en_US:en', 16:23:14 INFO - 'LOGNAME': 'cltbld', 16:23:14 INFO - 'MAIL': '/var/mail/cltbld', 16:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:14 INFO - 'MOZ_NO_REMOTE': '1', 16:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:14 INFO - 'NO_EM_RESTART': '1', 16:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:14 INFO - 'PWD': '/builds/slave/test', 16:23:14 INFO - 'SHELL': '/bin/bash', 16:23:14 INFO - 'SHLVL': '1', 16:23:14 INFO - 'TERM': 'linux', 16:23:14 INFO - 'TMOUT': '86400', 16:23:14 INFO - 'USER': 'cltbld', 16:23:14 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:14 INFO - '_': '/tools/buildbot/bin/python'} 16:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23: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)) 16:23:14 INFO - Cleaning up... 16:23:14 INFO - Return code: 0 16:23:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:23: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 16:23: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 16:23:14 INFO - Using env: {'DISPLAY': ':0', 16:23:14 INFO - 'HOME': '/home/cltbld', 16:23:14 INFO - 'LANG': 'en_US.UTF-8', 16:23:14 INFO - 'LANGUAGE': 'en_US:en', 16:23:14 INFO - 'LOGNAME': 'cltbld', 16:23:14 INFO - 'MAIL': '/var/mail/cltbld', 16:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:14 INFO - 'MOZ_NO_REMOTE': '1', 16:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:14 INFO - 'NO_EM_RESTART': '1', 16:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:14 INFO - 'PWD': '/builds/slave/test', 16:23:14 INFO - 'SHELL': '/bin/bash', 16:23:14 INFO - 'SHLVL': '1', 16:23:14 INFO - 'TERM': 'linux', 16:23:14 INFO - 'TMOUT': '86400', 16:23:14 INFO - 'USER': 'cltbld', 16:23:14 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:14 INFO - '_': '/tools/buildbot/bin/python'} 16:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:14 INFO - Downloading/unpacking jsonschema 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:23:16 INFO - Downloading/unpacking functools32 (from jsonschema) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:16 INFO - Downloading functools32-3.2.3-2.tar.gz 16:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:23:17 INFO - warning: no files found matching '*.txt' 16:23:17 INFO - no previously-included directories found matching 'build' 16:23:17 INFO - no previously-included directories found matching 'dist' 16:23:17 INFO - no previously-included directories found matching '.git*' 16:23:17 INFO - Installing collected packages: jsonschema, functools32 16:23:17 INFO - Running setup.py install for functools32 16:23:17 INFO - warning: no files found matching '*.txt' 16:23:17 INFO - no previously-included directories found matching 'build' 16:23:17 INFO - no previously-included directories found matching 'dist' 16:23:17 INFO - no previously-included directories found matching '.git*' 16:23:17 INFO - Successfully installed jsonschema functools32 16:23:17 INFO - Cleaning up... 16:23:17 INFO - Return code: 0 16:23:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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 0x7f6726dd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2adb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, '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': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', '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 16:23: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 16:23: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 16:23:17 INFO - Using env: {'DISPLAY': ':0', 16:23:17 INFO - 'HOME': '/home/cltbld', 16:23:17 INFO - 'LANG': 'en_US.UTF-8', 16:23:17 INFO - 'LANGUAGE': 'en_US:en', 16:23:17 INFO - 'LOGNAME': 'cltbld', 16:23:17 INFO - 'MAIL': '/var/mail/cltbld', 16:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:17 INFO - 'MOZ_NO_REMOTE': '1', 16:23:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:17 INFO - 'NO_EM_RESTART': '1', 16:23:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:17 INFO - 'PWD': '/builds/slave/test', 16:23:17 INFO - 'SHELL': '/bin/bash', 16:23:17 INFO - 'SHLVL': '1', 16:23:17 INFO - 'TERM': 'linux', 16:23:17 INFO - 'TMOUT': '86400', 16:23:17 INFO - 'USER': 'cltbld', 16:23:17 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:17 INFO - '_': '/tools/buildbot/bin/python'} 16:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:17 INFO - Downloading/unpacking flake8 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:23:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:23:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23: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 16:23:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:23:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:23:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:23:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:23:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:23:20 INFO - no previously-included directories found matching 'docs/_build' 16:23:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:23:20 INFO - Running setup.py install for pep8 16:23:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:23:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:23:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:23:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:23:20 INFO - no previously-included directories found matching 'docs/_build' 16:23:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:23:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:23:20 INFO - Cleaning up... 16:23:20 INFO - Return code: 0 16:23:20 INFO - Running post-action listener: _resource_record_post_action 16:23:20 INFO - Running post-action listener: _start_resource_monitoring 16:23:20 INFO - Starting resource monitoring. 16:23:20 INFO - ##### 16:23:20 INFO - ##### Finished create-virtualenv step (success) 16:23:20 INFO - ##### 16:23:20 INFO - ##### 16:23:20 INFO - ##### Running install step. 16:23:20 INFO - ##### 16:23:20 INFO - Running pre-action listener: _resource_record_pre_action 16:23:20 INFO - Running main action method: install 16:23:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:23:20 INFO - Reading from file tmpfile_stdout 16:23:20 INFO - Detecting whether we're running mozinstall >=1.0... 16:23:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:23:20 INFO - Reading from file tmpfile_stdout 16:23:20 INFO - Output received: 16:23:20 INFO - Usage: mozinstall [options] installer 16:23:20 INFO - Options: 16:23:20 INFO - -h, --help show this help message and exit 16:23:20 INFO - -d DEST, --destination=DEST 16:23:20 INFO - Directory to install application into. [default: 16:23:20 INFO - "/builds/slave/test"] 16:23:20 INFO - --app=APP Application being installed. [default: firefox] 16:23:20 INFO - mkdir: /builds/slave/test/build/application 16:23: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'] 16:23: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 16:23:41 INFO - Reading from file tmpfile_stdout 16:23:41 INFO - Output received: 16:23:41 INFO - /builds/slave/test/build/application/firefox/firefox 16:23:41 INFO - Running post-action listener: _resource_record_post_action 16:23:41 INFO - ##### 16:23:41 INFO - ##### Finished install step (success) 16:23:41 INFO - ##### 16:23:41 INFO - ##### 16:23:41 INFO - ##### Running run-tests step. 16:23:41 INFO - ##### 16:23:41 INFO - Running pre-action listener: _resource_record_pre_action 16:23:41 INFO - Running main action method: run_tests 16:23:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:23:41 INFO - Python 2.7.3 16:23:41 INFO - Return code: 0 16:23:41 INFO - grabbing minidump binary from tooltool 16:23:41 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')]} 16:23:41 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 0x2b6a9c0>, '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 0x2b6b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b6bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:23:41 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 16:23:41 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 16:23:41 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 16:23:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:23:42 INFO - Return code: 0 16:23:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:23:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:23:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:23:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:23:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:23:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:23:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:23:42 INFO - Return code: 0 16:23:42 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/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 16:23:42 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/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 16:23:42 INFO - Using env: {'DISPLAY': ':0', 16:23:42 INFO - 'HOME': '/home/cltbld', 16:23:42 INFO - 'LANG': 'en_US.UTF-8', 16:23:42 INFO - 'LANGUAGE': 'en_US:en', 16:23:42 INFO - 'LOGNAME': 'cltbld', 16:23:42 INFO - 'MAIL': '/var/mail/cltbld', 16:23:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:23:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:42 INFO - 'MOZ_NO_REMOTE': '1', 16:23:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:23:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:42 INFO - 'NO_EM_RESTART': '1', 16:23:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:42 INFO - 'PWD': '/builds/slave/test', 16:23:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:23:42 INFO - 'SHELL': '/bin/bash', 16:23:42 INFO - 'SHLVL': '1', 16:23:42 INFO - 'TERM': 'linux', 16:23:42 INFO - 'TMOUT': '86400', 16:23:42 INFO - 'USER': 'cltbld', 16:23:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919', 16:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:42 INFO - '_': '/tools/buildbot/bin/python'} 16:23:42 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/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 16:23:43 INFO - mozversion application_buildid: 20160513133456 16:23:43 INFO - mozversion application_changeset: 53ab4450aff98742ad34122c7d81b1678dc4d6a4 16:23:43 INFO - mozversion application_display_name: Nightly 16:23:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:23:43 INFO - mozversion application_name: Firefox 16:23:43 INFO - mozversion application_remotingname: firefox 16:23:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:23:43 INFO - mozversion application_vendor: Mozilla 16:23:43 INFO - mozversion application_version: 49.0a1 16:23:43 INFO - mozversion platform_buildid: 20160513133456 16:23:43 INFO - mozversion platform_changeset: 53ab4450aff98742ad34122c7d81b1678dc4d6a4 16:23:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:23:43 INFO - mozversion platform_version: 49.0a1 16:23:43 INFO - using testdate: 1463181823 16:23:43 INFO - actual date: 1463181823 16:23:43 INFO - starting webserver on 'localhost:47333' 16:23:43 INFO - SUITE-START | Running 2 tests 16:23:43 INFO - TEST-START | dromaeo_css 16:23:43 INFO - operating with platform_type : linux_ 16:23:43 INFO - Initialising browser for dromaeo_css test... 16:23:43 INFO - TEST-INFO | started process 26756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLsLT41/profile http://localhost:47333/getInfo.html) 16:23:48 INFO - PROCESS | 26756 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1336: ReferenceError: E10SUINotification is not defined 16:23:48 INFO - PROCESS | 26756 | __metrics Screen width/height:1600/1200 16:23:48 INFO - PROCESS | 26756 | colorDepth:24 16:23:48 INFO - PROCESS | 26756 | Browser inner width/height: 1024/697 16:23:48 INFO - PROCESS | 26756 | __metrics 16:23:49 ERROR - PROCESS | 26756 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:23:50 INFO - TEST-INFO | 26756: exit 0 16:23:50 INFO - Browser initialized. 16:23:50 INFO - Running cycle 1/1 for dromaeo_css test... 16:23:50 INFO - TEST-INFO | started process 26837 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLsLT41/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:24:02 INFO - PROCESS | 26837 | RSS: Main: 152821760 16:24:02 INFO - PROCESS | 26837 | 16:24:53 INFO - PROCESS | 26837 | 1463181893983 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] 16:27:03 INFO - PROCESS | 26837 | Cycle 1(1): loaded http://localhost:47333/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47333/tests/dromaeo/cssquery-ext.html) 16:27:04 INFO - PROCESS | 26837 | RSS: Main: 260358144 16:27:04 INFO - PROCESS | 26837 | 16:30:06 INFO - PROCESS | 26837 | Cycle 1(1): loaded http://localhost:47333/tests/dromaeo/cssquery-ext.html (next: http://localhost:47333/tests/dromaeo/cssquery-jquery.html) 16:30:07 INFO - PROCESS | 26837 | RSS: Main: 236318720 16:30:07 INFO - PROCESS | 26837 | 16:33:09 INFO - PROCESS | 26837 | Cycle 1(1): loaded http://localhost:47333/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47333/tests/dromaeo/cssquery-mootools.html) 16:33:09 INFO - PROCESS | 26837 | RSS: Main: 234119168 16:33:09 INFO - PROCESS | 26837 | 16:36:11 INFO - PROCESS | 26837 | Cycle 1(1): loaded http://localhost:47333/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47333/tests/dromaeo/cssquery-prototype.html) 16:36:12 INFO - PROCESS | 26837 | RSS: Main: 232566784 16:36:12 INFO - PROCESS | 26837 | 16:39:14 INFO - PROCESS | 26837 | Cycle 1(1): loaded http://localhost:47333/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47333/tests/dromaeo/cssquery-yui.html) 16:39:15 INFO - PROCESS | 26837 | RSS: Main: 217309184 16:39:15 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | Cycle 1(1): loaded http://localhost:47333/tests/dromaeo/cssquery-yui.html (next: http://localhost:47333/tests/dromaeo/cssquery-dojo.html) 16:42:18 INFO - PROCESS | 26837 | RSS: Main: 265949184 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | __start_tp_report 16:42:18 INFO - PROCESS | 26837 | _x_x_mozilla_page_load 16:42:18 INFO - PROCESS | 26837 | _x_x_mozilla_page_load_details 16:42:18 INFO - PROCESS | 26837 | |i|pagename|runs| 16:42:18 INFO - PROCESS | 26837 | |0;dojo.html;3110.3001824589455;3110.7666924980626;3143.5127555228946;3147.984260078699;3164.7309978651815;5188.325517682696;5190.8442746717565;5193.3248677671945;5292.523672869441;5304.283921670573;5195.454477279888;5282.577393808496;5290.418054014055;5292.735363231833;5351.839444816652;4845.563899249067;4852.199387101124;4888.535589119028;4947.653664243504;4993.500649935014;4715.929261061086;4888.804447822101;4915.852524424273;4960.206366981283;4984.675996060245;4799.496052914428;4804.375431193957;4812.639052071074;4849.418069831608;4871.902561948759;4800.663953523254;4819.036292680012;4858.927116093261;4863.586595139417;4903.65674402792;4754.310624959396;4811;4848.806047758086;4879.682820616684;4911.607071434312;5250.9737451312885;5298.417174110845;5336.226247194174;5361.731913404353;5364.22218778275;5684.403137670568;5770.451807078362;5782.727799884061;5791.449812267828;5791.710414479301;5509.338879334506;5693.259876216107;5720.3135623725;5781.19063331134;5828;5370.758315875756;5397;5405.054115529845;5412.539934105605;5413.566914646779;5891.617044892068;5891.911621325684;5898.852528686732;5899.233099697098;5899.616524925866;5399.325084364455;5463.399026107125;5498.065328894098;5529.529989950899;5608.915866262009;5598.692071936082;5604.579656525777;5605.7197140142825;5608.747606357723;5609.5792815539;5590.496855282962;5591.10542313228;5594.496495315441;5596.664200147923;5597.048501754712;5532.3914369419335;5666.263385759908;5714.314282286152;5723.713814309352;5736.942630573641;4367.487149317247;4370.278903980871;4370.847020354272;4371.125774845043;4411.514733379326;3818.675412589961;3899.8245078971513;3918.529776426847;3951.8616848410165;3976;5390.9191362129595;5424.511793938485;5435.374931882805;5440.319960004999;5445.700486473282;5693.658380497183;5708.686022268815;5714.942850571524;5729.88540229193;5734.426557344233;5093.617978651616;5339.5728341732565;5450.182472629137;5471.23402723619;5522.337319521683;4729.101470720552;4735.550122738335;4750.477447480775;4751.857444276678;4795.95204047962;5832.854178645568;5843.505305707473;5846.970765146232;5853.385394533512;5891.882162356729;5501.972490137524;5659.660420374791;5678.119891416837;5688.687122208318;5689;5874.735636896265;5896.410358964069;5898.056310990392;5898.999999999828;5935.2877654681715;5643.407442218508;5644.040513112698;5646.2942132233475;5647.124695672013;5649.745761440663;5526.088195447867;5593.860153496195;5639.661620302795;5645.266115404971;5708.885822283614;5524.8342549723575;5528.143137813645;5529.806456773994;5533.7786488541615;5536.4186760389575;5511.283533140666;5512.035393806148;5514.558835293247;5515.669059856422;5517.944820551743;5271.683698978073;5498.477644623755;5547;5552.111662134039;5570.582206334622;5645.887082258414;5682.971585142048;5712.257406537124;5714.114312281603;5721.599488035964;5543.944560554504;5548.029094908295;5552.333719953632;5562.415946325577;5569.721513924369 16:42:18 INFO - PROCESS | 26837 | |1;ext.html;605.515587529976;625.4152367536354;637.9170707807984;644.5681393466377;656.998077931155;44283.55716442835;45713.31430028552;45713.77143114284;49324;49407;47075;47477.28784068242;47519;47594.76202618991;47631;8066.55633940136;9057.230135438496;9194.218491428202;10603.310784799001;10878.836817447745;10728.892711072867;10783.299085559414;10785.514651840647;10803.567857285738;10841.265397536396;10837.458127093652;10869.83695244572;10883.510242039127;10894.618688345909;10895.455227238606;8148.429609927308;10599.417032063271;10878;10897.128229741602;10901.945490272577;10499.94750026245;10568.841467377995;10575.20685948557;10857.728556786065;10890.509927053301;42827.78586107049;43346.78326608378;43363;43380;43524.56475435269;3451.775634583744;3714.5542534895985;3798.0125167456385;4093.099518106012;4330.631896288819;33673.158171045674;33966.49050264248;34022.82988585041;34154.82922585396;34485.310293793984;4393.29707246839;4448.5773851484055;4469.597736203725;4486.327050942352;4506.662000349987;347579;351567;352332;352571;354132;36487.08782280411;37305.25389492195;65630.67184664046;66842.66578667075;66910.66544667246;39319.60680393217;39454.60545394567;39462.40806387906;39564.20871582552;39575.208495829924;12624.116311858266;14000.369983350774;14161.362738676784;14163.433462661584;14271.215083170526;10740.838887416696;10997.945010274898;11000.064994475559;11662.067034637208;11706.70733231676;8861.202491775772;8865.468071915706;8870.379073464796;8910.529762589229;8931.821363572692;3414.180596656834;3418.5555877735737;3433.914152146216;3467.930641387158;3509.5613048368955;1026.3995562595817;1061.766411389493;1119.6137332620233;1128.210252823027;1186.003756844255;32566;33179.17052073718;33392.66607333944;33475.49786753152;33720.83139584286;1136.6135513925306;1159.2464897816487;1221.5663439478858;1248.5255602871032;1255.5605538061604;17745.911270443565;17790.91104544469;17880.28478860831;17934.5516362092;18000.369987050653;501101;503362;506085;506235;509888;59486;59561.40438595558;59914.70042649759;59962.40037599568;60004.69997650071;1276.1194775604804;1342.2885870488462;1382.3710211853584;1394.588596364075;1414.0808474491662;31098.378032439676;34588.48117278293;34637.30725385428;34769.30461390707;35018.824905875306;51603.22595161;52104.00803907448;52104.47895520996;52111.21833172581;52184.21723674072;374558;383127;383596;384118;385246;26207.344816379744;28631.284217893884;28837.71162288434;29383.265418363855;29676;48245.5175448241;48271.27593086177;48734.512654873;48984.510154897995;49477.75261123671;40480.797596011835;41653.166936661706;41707.37438938479;41726.58273417227;42561.574384255764;40563.18873622452;41307.38038929358;41520.79239603903;41749.791251044764;41764.791176043924;41510.58489415067;42300.78849605855;42460.150796984504;42628.573714262464;42745.572544275405;36095;36858.63141368659;37929;38057.80971095127;38503.61496385001;29229.7077029227;29251;29616.407671846875;29760.851195744748;29848 16:42:18 INFO - PROCESS | 26837 | |2;jquery.html;408.895731588445;415.74223981131695;415.9688023398244;418.2095838864545;422.6745406037351;18054.458366249;18098.366557170502;18868.528286792804;19894.801051989492;19927.501812454695;11882.465289061991;11953.223040502362;12182.086343524226;12476.688082797935;12554.874451255484;5202.167653175493;5285.445028272034;5296.549793267558;5313.149896016638;5318.175682769177;3868.574456809749;5572.3034620672415;5644.548436125121;5881.67650779207;5925.2889653241455;5894.263217097863;5979.850503737398;5988.850278743044;6008.218931538916;6012.458878700916;5798.855028624276;5931.08068249422;5933.970330148321;5990.790322338719;6010.879782404371;5554.86112847178;5664.376918538957;5667.971660141673;5729.512991395736;5733.111367738007;13610.795838062339;13857.4457021719;13863.168209907439;14136.434542618283;14155.575332740034;2295.2425699519285;2322.1408079010876;2323.5352929414184;2330.102910379499;2372.750861159571;10024.849627255597;10400.427976461291;10545.420001899893;10640.255182137253;14328.92835535826;1985.4837742186992;2021.4036859126593;2039.2454791727007;2054.8869812160324;2058.5882823435372;71896.28103718949;73658;73778;76175.23824761737;76452.61773691152;10369.688909332732;10539.42033188182;10880.564777408816;11038.282511636718;11127.054200393057;15035.398584056735;15084.547463576111;15090;15100.169490677898;15108.622284442758;7272.854542909112;7410.5183163094225;7462.626868656545;7567.962160189164;7571.697132114764;6924.376806087476;6946.097007389007;7033.9296607033275;7053.118360204974;7054.365107140382;4658.394408726844;4706.294055891575;4782.736949467812;4788.281757736298;4805.903881922342;1811.2573844723863;1824.3341180469033;1945.4066509714673;1968.8523360748002;2000.6798912173983;932.5523748600709;1009.6365308488938;1049.41757324684;1104.618906477264;1128.6049882541045;18266.543336416697;18543.90728046351;18959.43121706351;19314.227430902607;19409.514762130777;976.5410257179115;995.746084748399;1039.7868436970402;1047.1465755409317;1067.5783065689009;10163.898361016443;10770.676879693621;10852.782944341068;10902.563897444172;10918.672439826818;465050;465836;469220;473013;481711;54128;56073;89896.55051724831;91122.54438727764;91365;976.3019441099506;1006.9748256293651;1007.3452256033637;1020.8519764634235;1022.0954455307107;16162.626176775106;16601.83398166051;16672.83327166713;16798.748018779974;16883.40908068212;32407.351852963282;39403.071602420256;39601;40567.79716101401;41977.37033944432;52460.21309680434;52665.7366713164;54633.453665464425;56186.7190664044;56668.71665641645;7394.112706475257;8904.599293031959;9028.503432311287;9206.677766278148;9294.442333460014;7811.414143939341;11532.36571988515;11610.535578576762;13344.132631378929;13596.524121655695;11694.415279236175;11896.345700986529;11967.581134660246;11978.940105299418;11979.520819167135;11816.46825892854;11829;11905.94047029794;11961.461734221806;12024.33866137371;12016.158868879094;12154.392280386122;12233.3271670059;12603.18079324841;12863.163894347212;7298.124225093023;7593.202713714981;7691.923080769121;7869.134395216636;7907.288344049063;10180.389176649425;11724.648260552198;12182.75634487323;12255;12476.376181190722 16:42:18 INFO - PROCESS | 26837 | |3;mootools.html;1377.5247539598836;1464.341046529062;1541.9171368949492;1658.8921720088194;1852.83324500795;8899.599518021689;9159.313051521129;9193.678221262257;9511.571979260932;9528.237740980721;9213.355065145442;9409.29430292729;9412.717618471439;9418.340716149873;9418.481983490905;2009.176237742526;2279.076973825603;2340.3681006128336;2351.376885125443;2377.6552399902002;2356.45801465663;2365.27859003004;2371.881405929705;2375.7505461926507;2377.0254195779735;2276.521930394619;2316.5830150572974;2318.4783423729696;2322.094383190549;2322.326525307659;1972.063269946771;2281.1445707859552;2339.7309309429293;2339.7426283108844;2366.928992130239;2278.97721022791;2286.4283929017747;2287.977120228793;2291.2667946257206;2317.1310758465575;9333;9404.858927116098;9407.388519746195;9413.48225847585;9419.717408477756;5233.607479439019;5358.651687640291;5364.597655175878;5369.516743493065;5370.301860758115;3505.0886769439876;3571.8571257149683;3594.8382322795273;3640.872569460056;3651.087228192952;1168.1647621950262;1198.3648666206925;1216.4100411300512;1220.6094049904034;1250.956216532417;13187.076904616584;13195.538156164488;13204.801927970992;13232.603021909357;13234.801477977839;4542.84100056503;4617.884552886205;4721.5278472153195;4744.4069491313585;4747.382840230748;11034.337939723642;11034.503447344889;11038.944805275922;11044.502997365138;11053.502592383362;4705.929411058837;5350.812721554805;5352;5353.812616558402;5357.598180136428;4696.671233013724;4803.855884323406;4806.423229212517;4851.442084160296;4853.927191092136;4018.6537509933914;4048.1903619276263;4065.6747460203114;4074.246264441088;4088.8773336799945;1200.9459574319176;1204.3015051270243;1224.9448774805155;1242.7887259165964;1271.167385362589;1044.368157264859;1052.410650035967;1063.8085144673878;1066.1896958311738;1076.4671487613525;6199.628022318675;6231.283402408691;6391.680415979183;6439.5170362223025;6457.321981192001;429.3623968406913;429.6240789309354;430.0195554136511;437.3963929776888;437.8117409513876;4454;4507.459104907432;4511.436070491189;4539.500654927955;4542.795574199169;11214;11620.941895290638;11636.3600001999;11701.824472632918;11706.71719014784;11128.833067503994;11525.135614828921;11549.191556590958;11566;11583.536658533732;8556.27271681914;8661.480311181349;8696.304295656333;8724.563771811385;8732.912670873337;6268.216472940882;6464;6524.543281970216;6528.37980391862;6539.901901471387;10062.899371006197;10338.844917326096;10346;10358.637447689596;10451.68644940653;10435.791284174122;10511.894881051092;10590.682279531626;10620.256582039492;10628.309159904582;7383.963080184565;7472.252774722484;7550.320471157622;8193.426460147972;8303.792405189919;8700.216980471789;8971.102889710977;8974.68588599422;9199.494027828267;9208.401453905475;7930.722424715107;7941;7944.443888927951;8183.058948221031;8393.328533717166;7677.347425468898;7823.882641760264;7899.328557072713;8019.9198008019175;8231.670733170606;6864.141982252218;6993.335633114846;7047.647617619201;7102.644867756695;7111.9288807113335;5376.435474275301;5382.058139825436;5422.267993820784;5500.339959204921;5511.173324001432;5438.782448702008;5447.972760136174;5484.424135465719;5584.441555844545;5629.915551266816 16:42:18 INFO - PROCESS | 26837 | |4;prototype.html;322.096519263466;327.58560421067347;334.6553050358131;336.17804468075565;336.39448991814726;5359.249705041292;5381.515663590276;5434.945650543494;5435.42927992561;5438.347398312203;5283.150897185983;5323.095073837448;5348.705821179834;5357.812476563321;5366.892662146754;3976;4059.8579049733266;4078.5717499662555;4079.6940229482757;4081.3673880548413;4112.136451345206;4152.837205582427;4166.10428757817;4166.854115118352;4172.4784401949755;4124.896877578055;4135.338345864662;4137.27597670408;4153.501579810412;4159.896002599929;3955.72309938303;4052.5339585947404;4134.958650413518;4139.834406623731;4160;3631.78335257689;3639.3085313790293;3655.780653160792;3659.9085022874374;3662.121090938182;5108.6168537359845;5114.744262786846;5122.462141475166;5146.536811686966;5150.459201783834;4630.9536904631195;4708.411448568894;4721.197396442613;4726.5746082852365;4738.739369334685;5529;5581.246531718207;5584.553235741131;5597;5608.523275521586;4051.6150965658217;4058;4062.309407400749;4066.515721761565;4077.938830917539;5772.15291106846;5806.274215723035;5817.272840894931;5825.155352473867;5917.289925209003;4122.34042553196;4136.834526618902;4140.503139623264;4146.129312844337;4147.875563733093;4189.601987811153;4193.1404062167185;4200.22295875258;4203;4207.957920420818;4375.606195442426;4691.67761957833;4756.548127927841;4780.95219047812;4784.593309568656;5542.501174894279;5581.860453488695;5618.269624948731;5632.352279487829;5648.237487939075;1387.7016441465134;1407.8803301719263;1413.7031223443191;1415.469199050356;1415.794709767078;846.817934144162;894.4186278918625;895.9507227102572;903.076447167529;904.3985749476656;5328.8401347959625;5385.838424847261;5420.945790542123;5425;5438.45615438453;5756.942430575725;5760.740766665426;5774.913376299359;5775.653460792366;5786.71066446676;5216.26972223889;5229.98645521477;5359.410464848864;5394.460553944574;5436.456354364611;3010.232390740347;3021.9244518886762;3041.391721655678;3054.938901221963;3057.1898446911587;5946.048632218824;5947.316058653224;5956.791512297049;5961.403859614091;5967.671778052249;5594.860128496739;5815.360310365941;5829.7085145743395;5831.533477321804;5855.268091488564;5871;5886.735096920649;5932.940670593239;5953.184278794923;6038.969805150946;5637.787875606764;5699.458551437607;5819.5344372450945;5827.1841942127285;5829.358770535323;5663.093904975184;5693.601447898607;5729.507664166101;5733.512651424676;5767.279090113736;5883.529317654663;5949.167116603766;5949.762009519571;5974.253218347707;6023.3374328824675;3982.123932734794;4102.487189101363;4104.261232977973;4129.67850287917;4132.834686612502;5712.514436272797;5738.655680659173;5755.97122014404;5797.739101740516;5827.155062515996;5403.297571315704;5683.261176047087;5699.287589051369;5701.344345400332;5707.571932105026;5421.566274697936;5631.324241091095;5636.577256705681;5650.773969041193;5667.744951477276;5323.946760532345;5429.348478182644;5442;5508.779648814003;5551.639143455663;5519.316608434466;5572.721363931706;5623.634463759842;5650.463205995424;5655.321361436654;5557.138643510262;5566.137248726453;5577.693226872561;5615.831525054255;5619.578531610064 16:42:18 INFO - PROCESS | 26837 | |5;yui.html;694.4270976444432;722.9842071888994;761.8247803005311;783.2559068884559;798.2177466083234;13295.734085318289;15418.92290538547;15594.142322172276;15611.751059915208;15628.687426251483;14244.643883902907;14372;14908.62728431787;14915.92542037291;14927.179005154712;694.1947341084343;810.5055915891302;830.9335253179731;841.3311417423133;846.5344060766585;845.4208866926144;851.940364174508;853.0786750309651;853.2022558907411;853.3471894001094;873.4540911930044;877.6796469288728;878.5738916625431;878.6704985630389;879.0901417033372;722.4545468171523;845.298402326074;867.5228624256633;873.3537182484947;873.9169778870997;635.0474288567149;635.9427651511386;636.0395802338502;636.5162476517834;636.8630744389978;14264.429422823074;14314.785278220836;14317.212553309564;14325.426982920671;14326;14097;14295.356708948122;14416;14444.638884027878;14452.638684032983;12291.631251062483;12411.937940310241;12493.625191244277;12545.184563003375;12776.6805829855;4539.296409056601;4579.885502862422;4592.977035114836;4602.3786788783755;4607.3319368691355;57588.424115758724;57776.71111644457;59991.100133497595;60192.09711854326;60515;11202.327860328243;11347.37589432589;11348.886511134948;11421.657350279505;11476.713082173012;20827.895860520905;20912.790872091387;21134.154633814476;21187.36437906865;21263.36209913705;8242.340612750966;8288.70989515364;8346.248837604644;8398.706045288385;8496.150384961455;4771.045790841846;4833.202521583969;4849.660523763303;4887.340209071801;4934.358533390637;3425.9314813703586;3494.4583589543663;3505.9123521911647;3507.7018453431233;3510.210202704371;491.7664109547936;652.9640869752209;660.5343233020805;661.9503537234631;663.5156335874742;673.5857447669648;758.8102974256436;761.5735188294573;764.3425321068231;801.6472751989106;9077.636894524145;9450.810983780286;9455.338126331228;9530.552010210688;9581.377210481296;732.8570928668859;735.1692587376338;749.8500299940034;755.1429127939828;785.6111224943576;4436.401085853368;4601.194790911644;4685.465947957948;4714.245720684674;4782.402199725035;67376.32623673798;68292;68576;68808.6559567199;71047;46952;47029.2970702927;64740;65163.674181629736;66244;57286.42713572894;58696.11955820666;58717.41282587119;59223.703881480316;59665.40334596772;8852.3803333766;8882.422642528474;8916.73249802507;8956.68651597191;8972.237359824489;37152.81423592865;37316.06709832276;37595.436068458446;37838.810805945795;38107.809460952514;46283;47734.522654774395;47820.2826957589;48186.759066204446;49677.50322496729;12736.490540378652;12777.297248651414;12832.294223817406;12855.357232138544;13335.533256335983;12992.285424301754;13634.181949083088;14096.154230746188;14427.639309017359;14623.34194961208;13349.599512014654;13434.395452204479;13708.17750934947;13839.446422143;13883;10778.784424311312;11083.224174307721;11211.71970700739;13246.86753132495;13270.1374410663;12105.878941210827;12262;12321.93839030799;12370;12438.440270187682;9355.251579873458;10034.147097496503;10294;10386.844197337197;10436.425996570262;10139.79720405573;10191.643292484727;10230;10315.27793054479;10610.257281990187 16:42:18 INFO - PROCESS | 26837 | __end_tp_report 16:42:18 INFO - PROCESS | 26837 | __start_cc_report 16:42:18 INFO - PROCESS | 26837 | _x_x_mozilla_cycle_collect,922 16:42:18 INFO - PROCESS | 26837 | __end_cc_report 16:42:18 INFO - PROCESS | 26837 | __startTimestamp1463182938301__endTimestamp 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | ------- Summary: start ------- 16:42:18 INFO - PROCESS | 26837 | Number of tests: 6 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | [#0] dojo.html Cycles:165 Average:5286.19 Median:5512.04 stddev:591.66 (10.7%) stddev-sans-first:568.32 16:42:18 INFO - PROCESS | 26837 | Values: 3110.3 3110.8 3143.5 3148.0 3164.7 5188.3 5190.8 5193.3 5292.5 5304.3 5195.5 5282.6 5290.4 5292.7 5351.8 4845.6 4852.2 4888.5 4947.7 4993.5 4715.9 4888.8 4915.9 4960.2 4984.7 4799.5 4804.4 4812.6 4849.4 4871.9 4800.7 4819.0 4858.9 4863.6 4903.7 4754.3 4811.0 4848.8 4879.7 4911.6 5251.0 5298.4 5336.2 5361.7 5364.2 5684.4 5770.5 5782.7 5791.4 5791.7 5509.3 5693.3 5720.3 5781.2 5828.0 5370.8 5397.0 5405.1 5412.5 5413.6 5891.6 5891.9 5898.9 5899.2 5899.6 5399.3 5463.4 5498.1 5529.5 5608.9 5598.7 5604.6 5605.7 5608.7 5609.6 5590.5 5591.1 5594.5 5596.7 5597.0 5532.4 5666.3 5714.3 5723.7 5736.9 4367.5 4370.3 4370.8 4371.1 4411.5 3818.7 3899.8 3918.5 3951.9 3976.0 5390.9 5424.5 5435.4 5440.3 5445.7 5693.7 5708.7 5714.9 5729.9 5734.4 5093.6 5339.6 5450.2 5471.2 5522.3 4729.1 4735.6 4750.5 4751.9 4796.0 5832.9 5843.5 5847.0 5853.4 5891.9 5502.0 5659.7 5678.1 5688.7 5689.0 5874.7 5896.4 5898.1 5899.0 5935.3 5643.4 5644.0 5646.3 5647.1 5649.7 5526.1 5593.9 5639.7 5645.3 5708.9 5524.8 5528.1 5529.8 5533.8 5536.4 5511.3 5512.0 5514.6 5515.7 5517.9 5271.7 5498.5 5547.0 5552.1 5570.6 5645.9 5683.0 5712.3 5714.1 5721.6 5543.9 5548.0 5552.3 5562.4 5569.7 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | [#1] ext.html Cycles:180 Average:57604.53 Median:20509.90 stddev:110663.20 (539.6%) stddev-sans-first:110890.89 16:42:18 INFO - PROCESS | 26837 | Values: 605.5 625.4 637.9 644.6 657.0 44283.6 45713.3 45713.8 49324.0 49407.0 47075.0 47477.3 47519.0 47594.8 47631.0 8066.6 9057.2 9194.2 10603.3 10878.8 10728.9 10783.3 10785.5 10803.6 10841.3 10837.5 10869.8 10883.5 10894.6 10895.5 8148.4 10599.4 10878.0 10897.1 10901.9 10499.9 10568.8 10575.2 10857.7 10890.5 42827.8 43346.8 43363.0 43380.0 43524.6 3451.8 3714.6 3798.0 4093.1 4330.6 33673.2 33966.5 34022.8 34154.8 34485.3 4393.3 4448.6 4469.6 4486.3 4506.7 347579.0 351567.0 352332.0 352571.0 354132.0 36487.1 37305.3 65630.7 66842.7 66910.7 39319.6 39454.6 39462.4 39564.2 39575.2 12624.1 14000.4 14161.4 14163.4 14271.2 10740.8 10997.9 11000.1 11662.1 11706.7 8861.2 8865.5 8870.4 8910.5 8931.8 3414.2 3418.6 3433.9 3467.9 3509.6 1026.4 1061.8 1119.6 1128.2 1186.0 32566.0 33179.2 33392.7 33475.5 33720.8 1136.6 1159.2 1221.6 1248.5 1255.6 17745.9 17790.9 17880.3 17934.6 18000.4 501101.0 503362.0 506085.0 506235.0 509888.0 59486.0 59561.4 59914.7 59962.4 60004.7 1276.1 1342.3 1382.4 1394.6 1414.1 31098.4 34588.5 34637.3 34769.3 35018.8 51603.2 52104.0 52104.5 52111.2 52184.2 374558.0 383127.0 383596.0 384118.0 385246.0 26207.3 28631.3 28837.7 29383.3 29676.0 48245.5 48271.3 48734.5 48984.5 49477.8 40480.8 41653.2 41707.4 41726.6 42561.6 40563.2 41307.4 41520.8 41749.8 41764.8 41510.6 42300.8 42460.2 42628.6 42745.6 36095.0 36858.6 37929.0 38057.8 38503.6 29229.7 29251.0 29616.4 29760.9 29848.0 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | [#2] jquery.html Cycles:180 Average:27132.73 Median:2308.69 stddev:77425.72 (3353.7%) stddev-sans-first:77616.92 16:42:18 INFO - PROCESS | 26837 | Values: 408.9 415.7 416.0 418.2 422.7 18054.5 18098.4 18868.5 19894.8 19927.5 11882.5 11953.2 12182.1 12476.7 12554.9 5202.2 5285.4 5296.5 5313.1 5318.2 3868.6 5572.3 5644.5 5881.7 5925.3 5894.3 5979.9 5988.9 6008.2 6012.5 5798.9 5931.1 5934.0 5990.8 6010.9 5554.9 5664.4 5668.0 5729.5 5733.1 13610.8 13857.4 13863.2 14136.4 14155.6 2295.2 2322.1 2323.5 2330.1 2372.8 10024.8 10400.4 10545.4 10640.3 14328.9 1985.5 2021.4 2039.2 2054.9 2058.6 71896.3 73658.0 73778.0 76175.2 76452.6 10369.7 10539.4 10880.6 11038.3 11127.1 15035.4 15084.5 15090.0 15100.2 15108.6 7272.9 7410.5 7462.6 7568.0 7571.7 6924.4 6946.1 7033.9 7053.1 7054.4 4658.4 4706.3 4782.7 4788.3 4805.9 1811.3 1824.3 1945.4 1968.9 2000.7 932.6 1009.6 1049.4 1104.6 1128.6 18266.5 18543.9 18959.4 19314.2 19409.5 976.5 995.7 1039.8 1047.1 1067.6 10163.9 10770.7 10852.8 10902.6 10918.7 465050.0 465836.0 469220.0 473013.0 481711.0 54128.0 56073.0 89896.6 91122.5 91365.0 976.3 1007.0 1007.3 1020.9 1022.1 16162.6 16601.8 16672.8 16798.7 16883.4 32407.4 39403.1 39601.0 40567.8 41977.4 52460.2 52665.7 54633.5 56186.7 56668.7 7394.1 8904.6 9028.5 9206.7 9294.4 7811.4 11532.4 11610.5 13344.1 13596.5 11694.4 11896.3 11967.6 11978.9 11979.5 11816.5 11829.0 11905.9 11961.5 12024.3 12016.2 12154.4 12233.3 12603.2 12863.2 7298.1 7593.2 7691.9 7869.1 7907.3 10180.4 11724.6 12182.8 12255.0 12476.4 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | [#3] mootools.html Cycles:180 Average:6046.66 Median:4480.73 stddev:3530.71 (78.8%) stddev-sans-first:3523.18 16:42:18 INFO - PROCESS | 26837 | Values: 1377.5 1464.3 1541.9 1658.9 1852.8 8899.6 9159.3 9193.7 9511.6 9528.2 9213.4 9409.3 9412.7 9418.3 9418.5 2009.2 2279.1 2340.4 2351.4 2377.7 2356.5 2365.3 2371.9 2375.8 2377.0 2276.5 2316.6 2318.5 2322.1 2322.3 1972.1 2281.1 2339.7 2339.7 2366.9 2279.0 2286.4 2288.0 2291.3 2317.1 9333.0 9404.9 9407.4 9413.5 9419.7 5233.6 5358.7 5364.6 5369.5 5370.3 3505.1 3571.9 3594.8 3640.9 3651.1 1168.2 1198.4 1216.4 1220.6 1251.0 13187.1 13195.5 13204.8 13232.6 13234.8 4542.8 4617.9 4721.5 4744.4 4747.4 11034.3 11034.5 11038.9 11044.5 11053.5 4705.9 5350.8 5352.0 5353.8 5357.6 4696.7 4803.9 4806.4 4851.4 4853.9 4018.7 4048.2 4065.7 4074.2 4088.9 1200.9 1204.3 1224.9 1242.8 1271.2 1044.4 1052.4 1063.8 1066.2 1076.5 6199.6 6231.3 6391.7 6439.5 6457.3 429.4 429.6 430.0 437.4 437.8 4454.0 4507.5 4511.4 4539.5 4542.8 11214.0 11620.9 11636.4 11701.8 11706.7 11128.8 11525.1 11549.2 11566.0 11583.5 8556.3 8661.5 8696.3 8724.6 8732.9 6268.2 6464.0 6524.5 6528.4 6539.9 10062.9 10338.8 10346.0 10358.6 10451.7 10435.8 10511.9 10590.7 10620.3 10628.3 7384.0 7472.3 7550.3 8193.4 8303.8 8700.2 8971.1 8974.7 9199.5 9208.4 7930.7 7941.0 7944.4 8183.1 8393.3 7677.3 7823.9 7899.3 8019.9 8231.7 6864.1 6993.3 7047.6 7102.6 7111.9 5376.4 5382.1 5422.3 5500.3 5511.2 5438.8 5448.0 5484.4 5584.4 5629.9 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | [#4] prototype.html Cycles:180 Average:4716.05 Median:5421.26 stddev:1400.03 (25.8%) stddev-sans-first:1364.56 16:42:18 INFO - PROCESS | 26837 | Values: 322.1 327.6 334.7 336.2 336.4 5359.2 5381.5 5434.9 5435.4 5438.3 5283.2 5323.1 5348.7 5357.8 5366.9 3976.0 4059.9 4078.6 4079.7 4081.4 4112.1 4152.8 4166.1 4166.9 4172.5 4124.9 4135.3 4137.3 4153.5 4159.9 3955.7 4052.5 4135.0 4139.8 4160.0 3631.8 3639.3 3655.8 3659.9 3662.1 5108.6 5114.7 5122.5 5146.5 5150.5 4631.0 4708.4 4721.2 4726.6 4738.7 5529.0 5581.2 5584.6 5597.0 5608.5 4051.6 4058.0 4062.3 4066.5 4077.9 5772.2 5806.3 5817.3 5825.2 5917.3 4122.3 4136.8 4140.5 4146.1 4147.9 4189.6 4193.1 4200.2 4203.0 4208.0 4375.6 4691.7 4756.5 4781.0 4784.6 5542.5 5581.9 5618.3 5632.4 5648.2 1387.7 1407.9 1413.7 1415.5 1415.8 846.8 894.4 896.0 903.1 904.4 5328.8 5385.8 5420.9 5425.0 5438.5 5756.9 5760.7 5774.9 5775.7 5786.7 5216.3 5230.0 5359.4 5394.5 5436.5 3010.2 3021.9 3041.4 3054.9 3057.2 5946.0 5947.3 5956.8 5961.4 5967.7 5594.9 5815.4 5829.7 5831.5 5855.3 5871.0 5886.7 5932.9 5953.2 6039.0 5637.8 5699.5 5819.5 5827.2 5829.4 5663.1 5693.6 5729.5 5733.5 5767.3 5883.5 5949.2 5949.8 5974.3 6023.3 3982.1 4102.5 4104.3 4129.7 4132.8 5712.5 5738.7 5756.0 5797.7 5827.2 5403.3 5683.3 5699.3 5701.3 5707.6 5421.6 5631.3 5636.6 5650.8 5667.7 5323.9 5429.3 5442.0 5508.8 5551.6 5519.3 5572.7 5623.6 5650.5 5655.3 5557.1 5566.1 5577.7 5615.8 5619.6 16:42:18 INFO - PROCESS | 26837 | 16:42:18 INFO - PROCESS | 26837 | [#5] yui.html Cycles:180 Average:15832.03 Median:4742.65 stddev:18900.56 (398.5%) stddev-sans-first:18919.39 16:42:18 INFO - PROCESS | 26837 | Values: 694.4 723.0 761.8 783.3 798.2 13295.7 15418.9 15594.1 15611.8 15628.7 14244.6 14372.0 14908.6 14915.9 14927.2 694.2 810.5 830.9 841.3 846.5 845.4 851.9 853.1 853.2 853.3 873.5 877.7 878.6 878.7 879.1 722.5 845.3 867.5 873.4 873.9 635.0 635.9 636.0 636.5 636.9 14264.4 14314.8 14317.2 14325.4 14326.0 14097.0 14295.4 14416.0 14444.6 14452.6 12291.6 12411.9 12493.6 12545.2 12776.7 4539.3 4579.9 4593.0 4602.4 4607.3 57588.4 57776.7 59991.1 60192.1 60515.0 11202.3 11347.4 11348.9 11421.7 11476.7 20827.9 20912.8 21134.2 21187.4 21263.4 8242.3 8288.7 8346.2 8398.7 8496.2 4771.0 4833.2 4849.7 4887.3 4934.4 3425.9 3494.5 3505.9 3507.7 3510.2 491.8 653.0 660.5 662.0 663.5 673.6 758.8 761.6 764.3 801.6 9077.6 9450.8 9455.3 9530.6 9581.4 732.9 735.2 749.9 755.1 785.6 4436.4 4601.2 4685.5 4714.2 4782.4 67376.3 68292.0 68576.0 68808.7 71047.0 46952.0 47029.3 64740.0 65163.7 66244.0 57286.4 58696.1 58717.4 59223.7 59665.4 8852.4 8882.4 8916.7 8956.7 8972.2 37152.8 37316.1 37595.4 37838.8 38107.8 46283.0 47734.5 47820.3 48186.8 49677.5 12736.5 12777.3 12832.3 12855.4 13335.5 12992.3 13634.2 14096.2 14427.6 14623.3 13349.6 13434.4 13708.2 13839.4 13883.0 10778.8 11083.2 11211.7 13246.9 13270.1 12105.9 12262.0 12321.9 12370.0 12438.4 9355.3 10034.1 10294.0 10386.8 10436.4 10139.8 10191.6 10230.0 10315.3 10610.3 16:42:18 INFO - PROCESS | 26837 | -------- Summary: end -------- 16:42:18 INFO - PROCESS | 26837 | 16:42:19 INFO - TEST-INFO | 26837: exit 0 16:42:19 INFO - TEST-OK | dromaeo_css | took 1115872ms 16:42:19 INFO - TEST-START | kraken 16:42:19 INFO - operating with platform_type : linux_ 16:42:19 INFO - Initialising browser for kraken test... 16:42:19 INFO - TEST-INFO | started process 27014 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOcDiHt/profile http://localhost:47333/getInfo.html) 16:42:23 INFO - PROCESS | 27014 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1336: ReferenceError: E10SUINotification is not defined 16:42:23 INFO - PROCESS | 27014 | __metrics Screen width/height:1600/1200 16:42:23 INFO - PROCESS | 27014 | colorDepth:24 16:42:23 INFO - PROCESS | 27014 | Browser inner width/height: 1024/697 16:42:23 INFO - PROCESS | 27014 | __metrics 16:42:25 ERROR - PROCESS | 27014 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:42:26 INFO - TEST-INFO | 27014: exit 0 16:42:26 INFO - Browser initialized. 16:42:26 INFO - Running cycle 1/1 for kraken test... 16:42:26 INFO - TEST-INFO | started process 27098 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOcDiHt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:42:37 INFO - PROCESS | 27098 | RSS: Main: 152383488 16:42:37 INFO - PROCESS | 27098 | 16:42:40 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47333/tests/kraken/driver.html?testName=audio-beat-detection) 16:42:41 INFO - PROCESS | 27098 | RSS: Main: 209047552 16:42:41 INFO - PROCESS | 27098 | 16:42:44 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47333/tests/kraken/driver.html?testName=audio-dft) 16:42:44 INFO - PROCESS | 27098 | RSS: Main: 198176768 16:42:44 INFO - PROCESS | 27098 | 16:42:47 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47333/tests/kraken/driver.html?testName=audio-fft) 16:42:48 INFO - PROCESS | 27098 | RSS: Main: 264949760 16:42:48 INFO - PROCESS | 27098 | 16:42:50 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47333/tests/kraken/driver.html?testName=audio-oscillator) 16:42:51 INFO - PROCESS | 27098 | RSS: Main: 174366720 16:42:51 INFO - PROCESS | 27098 | 16:42:53 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47333/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:42:54 INFO - PROCESS | 27098 | RSS: Main: 178798592 16:42:54 INFO - PROCESS | 27098 | 16:42:57 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47333/tests/kraken/driver.html?testName=imaging-darkroom) 16:42:57 INFO - PROCESS | 27098 | RSS: Main: 215035904 16:42:57 INFO - PROCESS | 27098 | 16:43:01 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47333/tests/kraken/driver.html?testName=imaging-desaturate) 16:43:02 INFO - PROCESS | 27098 | RSS: Main: 212779008 16:43:02 INFO - PROCESS | 27098 | 16:43:04 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47333/tests/kraken/driver.html?testName=json-parse-financial) 16:43:05 INFO - PROCESS | 27098 | RSS: Main: 213929984 16:43:05 INFO - PROCESS | 27098 | 16:43:07 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47333/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:43:07 INFO - PROCESS | 27098 | RSS: Main: 201318400 16:43:07 INFO - PROCESS | 27098 | 16:43:09 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:43:10 INFO - PROCESS | 27098 | RSS: Main: 206798848 16:43:10 INFO - PROCESS | 27098 | 16:43:12 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:43:12 INFO - PROCESS | 27098 | RSS: Main: 217182208 16:43:12 INFO - PROCESS | 27098 | 16:43:15 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:43:16 INFO - PROCESS | 27098 | RSS: Main: 222068736 16:43:16 INFO - PROCESS | 27098 | 16:43:18 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:43:19 INFO - PROCESS | 27098 | RSS: Main: 218763264 16:43:19 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | Cycle 1(1): loaded http://localhost:47333/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47333/tests/kraken/driver.html?testName=ai-astar) 16:43:21 INFO - PROCESS | 27098 | RSS: Main: 264060928 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | __start_tp_report 16:43:21 INFO - PROCESS | 27098 | _x_x_mozilla_page_load 16:43:21 INFO - PROCESS | 27098 | _x_x_mozilla_page_load_details 16:43:21 INFO - PROCESS | 27098 | |i|pagename|runs| 16:43:21 INFO - PROCESS | 27098 | |0;ai-astar;110;103;109;117;108;107;109;110;108;110 16:43:21 INFO - PROCESS | 27098 | |1;audio-beat-detection;154;124;148;209;125;155;153;130;185;124 16:43:21 INFO - PROCESS | 27098 | |2;audio-dft;161;161;162;160;161;161;160;161;161;162 16:43:21 INFO - PROCESS | 27098 | |3;audio-fft;76;76;75;150;75;74;74;74;129;77 16:43:21 INFO - PROCESS | 27098 | |4;audio-oscillator;93;91;152;91;90;92;129;92;90;126 16:43:21 INFO - PROCESS | 27098 | |5;imaging-gaussian-blur;116;117;116;117;116;116;117;117;116;117 16:43:21 INFO - PROCESS | 27098 | |6;imaging-darkroom;150;145;145;146;145;145;146;146;146;146 16:43:21 INFO - PROCESS | 27098 | |7;imaging-desaturate;95;95;96;96;95;94;95;95;96;94 16:43:21 INFO - PROCESS | 27098 | |8;json-parse-financial;55;55;55;54;56;53;54;106;52;51 16:43:21 INFO - PROCESS | 27098 | |9;json-stringify-tinderbox;59;59;58;58;58;59;59;61;60;60 16:43:21 INFO - PROCESS | 27098 | |10;stanford-crypto-aes;79;79;84;77;78;85;82;78;86;79 16:43:21 INFO - PROCESS | 27098 | |11;stanford-crypto-ccm;128;122;119;130;120;132;121;123;193;131 16:43:21 INFO - PROCESS | 27098 | |12;stanford-crypto-pbkdf2;165;159;159;158;158;161;157;161;164;164 16:43:21 INFO - PROCESS | 27098 | |13;stanford-crypto-sha256-iterative;61;63;61;65;63;62;61;61;61;60 16:43:21 INFO - PROCESS | 27098 | __end_tp_report 16:43:21 INFO - PROCESS | 27098 | __start_cc_report 16:43:21 INFO - PROCESS | 27098 | _x_x_mozilla_cycle_collect,1865 16:43:21 INFO - PROCESS | 27098 | __end_cc_report 16:43:21 INFO - PROCESS | 27098 | __startTimestamp1463183001520__endTimestamp 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | ------- Summary: start ------- 16:43:21 INFO - PROCESS | 27098 | Number of tests: 14 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:3.48 (3.2%) stddev-sans-first:3.67 16:43:21 INFO - PROCESS | 27098 | Values: 110.0 103.0 109.0 117.0 108.0 107.0 109.0 110.0 108.0 110.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#1] audio-beat-detection Cycles:10 Average:150.70 Median:153.50 stddev:28.15 (18.3%) stddev-sans-first:29.83 16:43:21 INFO - PROCESS | 27098 | Values: 154.0 124.0 148.0 209.0 125.0 155.0 153.0 130.0 185.0 124.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 16:43:21 INFO - PROCESS | 27098 | Values: 161.0 161.0 162.0 160.0 161.0 161.0 160.0 161.0 161.0 162.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#3] audio-fft Cycles:10 Average:88.00 Median:75.00 stddev:27.61 (36.8%) stddev-sans-first:28.94 16:43:21 INFO - PROCESS | 27098 | Values: 76.0 76.0 75.0 150.0 75.0 74.0 74.0 74.0 129.0 77.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#4] audio-oscillator Cycles:10 Average:104.60 Median:91.00 stddev:22.48 (24.7%) stddev-sans-first:23.45 16:43:21 INFO - PROCESS | 27098 | Values: 93.0 91.0 152.0 91.0 90.0 92.0 129.0 92.0 90.0 126.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:43:21 INFO - PROCESS | 27098 | Values: 116.0 117.0 116.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.49 (1.0%) stddev-sans-first:0.53 16:43:21 INFO - PROCESS | 27098 | Values: 150.0 145.0 145.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 16:43:21 INFO - PROCESS | 27098 | Values: 95.0 95.0 96.0 96.0 95.0 94.0 95.0 95.0 96.0 94.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:54.50 stddev:16.55 (30.4%) stddev-sans-first:17.49 16:43:21 INFO - PROCESS | 27098 | Values: 55.0 55.0 55.0 54.0 56.0 53.0 54.0 106.0 52.0 51.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.99 (1.7%) stddev-sans-first:1.05 16:43:21 INFO - PROCESS | 27098 | Values: 59.0 59.0 58.0 58.0 58.0 59.0 59.0 61.0 60.0 60.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.50 stddev:3.27 (4.1%) stddev-sans-first:3.41 16:43:21 INFO - PROCESS | 27098 | Values: 79.0 79.0 84.0 77.0 78.0 85.0 82.0 78.0 86.0 79.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:129.00 stddev:22.00 (17.1%) stddev-sans-first:23.29 16:43:21 INFO - PROCESS | 27098 | Values: 128.0 122.0 119.0 130.0 120.0 132.0 121.0 123.0 193.0 131.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.60 Median:161.00 stddev:2.88 (1.8%) stddev-sans-first:2.57 16:43:21 INFO - PROCESS | 27098 | Values: 165.0 159.0 159.0 158.0 158.0 161.0 157.0 161.0 164.0 164.0 16:43:21 INFO - PROCESS | 27098 | 16:43:21 INFO - PROCESS | 27098 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:1.48 (2.4%) stddev-sans-first:1.54 16:43:21 INFO - PROCESS | 27098 | Values: 61.0 63.0 61.0 65.0 63.0 62.0 61.0 61.0 61.0 60.0 16:43:21 INFO - PROCESS | 27098 | -------- Summary: end -------- 16:43:21 INFO - PROCESS | 27098 | 16:43:22 INFO - TEST-INFO | 27098: exit 0 16:43:22 INFO - TEST-OK | kraken | took 63465ms 16:43:22 INFO - SUITE-END | took 1179s 16:43:22 INFO - Completed test suite (00:19:39) 16:43:22 INFO - javascript benchmark 16:43:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3110.3001824589455, 3110.7666924980626, 3143.5127555228946, 3147.984260078699, 3164.7309978651815, 5188.325517682696, 5190.8442746717565, 5193.3248677671945, 5292.523672869441, 5304.283921670573, 5195.454477279888, 5282.577393808496, 5290.418054014055, 5292.735363231833, 5351.839444816652, 4845.563899249067, 4852.199387101124, 4888.535589119028, 4947.653664243504, 4993.500649935014, 4715.929261061086, 4888.804447822101, 4915.852524424273, 4960.206366981283, 4984.675996060245, 4799.496052914428, 4804.375431193957, 4812.639052071074, 4849.418069831608, 4871.902561948759, 4800.663953523254, 4819.036292680012, 4858.927116093261, 4863.586595139417, 4903.65674402792, 4754.310624959396, 4811.0, 4848.806047758086, 4879.682820616684, 4911.607071434312, 5250.9737451312885, 5298.417174110845, 5336.226247194174, 5361.731913404353, 5364.22218778275, 5684.403137670568, 5770.451807078362, 5782.727799884061, 5791.449812267828, 5791.710414479301, 5509.338879334506, 5693.259876216107, 5720.3135623725, 5781.19063331134, 5828.0, 5370.758315875756, 5397.0, 5405.054115529845, 5412.539934105605, 5413.566914646779, 5891.617044892068, 5891.911621325684, 5898.852528686732, 5899.233099697098, 5899.616524925866, 5399.325084364455, 5463.399026107125, 5498.065328894098, 5529.529989950899, 5608.915866262009, 5598.692071936082, 5604.579656525777, 5605.7197140142825, 5608.747606357723, 5609.5792815539, 5590.496855282962, 5591.10542313228, 5594.496495315441, 5596.664200147923, 5597.048501754712, 5532.3914369419335, 5666.263385759908, 5714.314282286152, 5723.713814309352, 5736.942630573641, 4367.487149317247, 4370.278903980871, 4370.847020354272, 4371.125774845043, 4411.514733379326, 3818.675412589961, 3899.8245078971513, 3918.529776426847, 3951.8616848410165, 3976.0, 5390.9191362129595, 5424.511793938485, 5435.374931882805, 5440.319960004999, 5445.700486473282, 5693.658380497183, 5708.686022268815, 5714.942850571524, 5729.88540229193, 5734.426557344233, 5093.617978651616, 5339.5728341732565, 5450.182472629137, 5471.23402723619, 5522.337319521683, 4729.101470720552, 4735.550122738335, 4750.477447480775, 4751.857444276678, 4795.95204047962, 5832.854178645568, 5843.505305707473, 5846.970765146232, 5853.385394533512, 5891.882162356729, 5501.972490137524, 5659.660420374791, 5678.119891416837, 5688.687122208318, 5689.0, 5874.735636896265, 5896.410358964069, 5898.056310990392, 5898.999999999828, 5935.2877654681715, 5643.407442218508, 5644.040513112698, 5646.2942132233475, 5647.124695672013, 5649.745761440663, 5526.088195447867, 5593.860153496195, 5639.661620302795, 5645.266115404971, 5708.885822283614, 5524.8342549723575, 5528.143137813645, 5529.806456773994, 5533.7786488541615, 5536.4186760389575, 5511.283533140666, 5512.035393806148, 5514.558835293247, 5515.669059856422, 5517.944820551743, 5271.683698978073, 5498.477644623755, 5547.0, 5552.111662134039, 5570.582206334622, 5645.887082258414, 5682.971585142048, 5712.257406537124, 5714.114312281603, 5721.599488035964, 5543.944560554504, 5548.029094908295, 5552.333719953632, 5562.415946325577, 5569.721513924369], "value": 5247.238540181319, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.515587529976, 625.4152367536354, 637.9170707807984, 644.5681393466377, 656.998077931155, 44283.55716442835, 45713.31430028552, 45713.77143114284, 49324.0, 49407.0, 47075.0, 47477.28784068242, 47519.0, 47594.76202618991, 47631.0, 8066.55633940136, 9057.230135438496, 9194.218491428202, 10603.310784799001, 10878.836817447745, 10728.892711072867, 10783.299085559414, 10785.514651840647, 10803.567857285738, 10841.265397536396, 10837.458127093652, 10869.83695244572, 10883.510242039127, 10894.618688345909, 10895.455227238606, 8148.429609927308, 10599.417032063271, 10878.0, 10897.128229741602, 10901.945490272577, 10499.94750026245, 10568.841467377995, 10575.20685948557, 10857.728556786065, 10890.509927053301, 42827.78586107049, 43346.78326608378, 43363.0, 43380.0, 43524.56475435269, 3451.775634583744, 3714.5542534895985, 3798.0125167456385, 4093.099518106012, 4330.631896288819, 33673.158171045674, 33966.49050264248, 34022.82988585041, 34154.82922585396, 34485.310293793984, 4393.29707246839, 4448.5773851484055, 4469.597736203725, 4486.327050942352, 4506.662000349987, 347579.0, 351567.0, 352332.0, 352571.0, 354132.0, 36487.08782280411, 37305.25389492195, 65630.67184664046, 66842.66578667075, 66910.66544667246, 39319.60680393217, 39454.60545394567, 39462.40806387906, 39564.20871582552, 39575.208495829924, 12624.116311858266, 14000.369983350774, 14161.362738676784, 14163.433462661584, 14271.215083170526, 10740.838887416696, 10997.945010274898, 11000.064994475559, 11662.067034637208, 11706.70733231676, 8861.202491775772, 8865.468071915706, 8870.379073464796, 8910.529762589229, 8931.821363572692, 3414.180596656834, 3418.5555877735737, 3433.914152146216, 3467.930641387158, 3509.5613048368955, 1026.3995562595817, 1061.766411389493, 1119.6137332620233, 1128.210252823027, 1186.003756844255, 32566.0, 33179.17052073718, 33392.66607333944, 33475.49786753152, 33720.83139584286, 1136.6135513925306, 1159.2464897816487, 1221.5663439478858, 1248.5255602871032, 1255.5605538061604, 17745.911270443565, 17790.91104544469, 17880.28478860831, 17934.5516362092, 18000.369987050653, 501101.0, 503362.0, 506085.0, 506235.0, 509888.0, 59486.0, 59561.40438595558, 59914.70042649759, 59962.40037599568, 60004.69997650071, 1276.1194775604804, 1342.2885870488462, 1382.3710211853584, 1394.588596364075, 1414.0808474491662, 31098.378032439676, 34588.48117278293, 34637.30725385428, 34769.30461390707, 35018.824905875306, 51603.22595161, 52104.00803907448, 52104.47895520996, 52111.21833172581, 52184.21723674072, 374558.0, 383127.0, 383596.0, 384118.0, 385246.0, 26207.344816379744, 28631.284217893884, 28837.71162288434, 29383.265418363855, 29676.0, 48245.5175448241, 48271.27593086177, 48734.512654873, 48984.510154897995, 49477.75261123671, 40480.797596011835, 41653.166936661706, 41707.37438938479, 41726.58273417227, 42561.574384255764, 40563.18873622452, 41307.38038929358, 41520.79239603903, 41749.791251044764, 41764.791176043924, 41510.58489415067, 42300.78849605855, 42460.150796984504, 42628.573714262464, 42745.572544275405, 36095.0, 36858.63141368659, 37929.0, 38057.80971095127, 38503.61496385001, 29229.7077029227, 29251.0, 29616.407671846875, 29760.851195744748, 29848.0], "value": 19698.20189068972, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.895731588445, 415.74223981131695, 415.9688023398244, 418.2095838864545, 422.6745406037351, 18054.458366249, 18098.366557170502, 18868.528286792804, 19894.801051989492, 19927.501812454695, 11882.465289061991, 11953.223040502362, 12182.086343524226, 12476.688082797935, 12554.874451255484, 5202.167653175493, 5285.445028272034, 5296.549793267558, 5313.149896016638, 5318.175682769177, 3868.574456809749, 5572.3034620672415, 5644.548436125121, 5881.67650779207, 5925.2889653241455, 5894.263217097863, 5979.850503737398, 5988.850278743044, 6008.218931538916, 6012.458878700916, 5798.855028624276, 5931.08068249422, 5933.970330148321, 5990.790322338719, 6010.879782404371, 5554.86112847178, 5664.376918538957, 5667.971660141673, 5729.512991395736, 5733.111367738007, 13610.795838062339, 13857.4457021719, 13863.168209907439, 14136.434542618283, 14155.575332740034, 2295.2425699519285, 2322.1408079010876, 2323.5352929414184, 2330.102910379499, 2372.750861159571, 10024.849627255597, 10400.427976461291, 10545.420001899893, 10640.255182137253, 14328.92835535826, 1985.4837742186992, 2021.4036859126593, 2039.2454791727007, 2054.8869812160324, 2058.5882823435372, 71896.28103718949, 73658.0, 73778.0, 76175.23824761737, 76452.61773691152, 10369.688909332732, 10539.42033188182, 10880.564777408816, 11038.282511636718, 11127.054200393057, 15035.398584056735, 15084.547463576111, 15090.0, 15100.169490677898, 15108.622284442758, 7272.854542909112, 7410.5183163094225, 7462.626868656545, 7567.962160189164, 7571.697132114764, 6924.376806087476, 6946.097007389007, 7033.9296607033275, 7053.118360204974, 7054.365107140382, 4658.394408726844, 4706.294055891575, 4782.736949467812, 4788.281757736298, 4805.903881922342, 1811.2573844723863, 1824.3341180469033, 1945.4066509714673, 1968.8523360748002, 2000.6798912173983, 932.5523748600709, 1009.6365308488938, 1049.41757324684, 1104.618906477264, 1128.6049882541045, 18266.543336416697, 18543.90728046351, 18959.43121706351, 19314.227430902607, 19409.514762130777, 976.5410257179115, 995.746084748399, 1039.7868436970402, 1047.1465755409317, 1067.5783065689009, 10163.898361016443, 10770.676879693621, 10852.782944341068, 10902.563897444172, 10918.672439826818, 465050.0, 465836.0, 469220.0, 473013.0, 481711.0, 54128.0, 56073.0, 89896.55051724831, 91122.54438727764, 91365.0, 976.3019441099506, 1006.9748256293651, 1007.3452256033637, 1020.8519764634235, 1022.0954455307107, 16162.626176775106, 16601.83398166051, 16672.83327166713, 16798.748018779974, 16883.40908068212, 32407.351852963282, 39403.071602420256, 39601.0, 40567.79716101401, 41977.37033944432, 52460.21309680434, 52665.7366713164, 54633.453665464425, 56186.7190664044, 56668.71665641645, 7394.112706475257, 8904.599293031959, 9028.503432311287, 9206.677766278148, 9294.442333460014, 7811.414143939341, 11532.36571988515, 11610.535578576762, 13344.132631378929, 13596.524121655695, 11694.415279236175, 11896.345700986529, 11967.581134660246, 11978.940105299418, 11979.520819167135, 11816.46825892854, 11829.0, 11905.94047029794, 11961.461734221806, 12024.33866137371, 12016.158868879094, 12154.392280386122, 12233.3271670059, 12603.18079324841, 12863.163894347212, 7298.124225093023, 7593.202713714981, 7691.923080769121, 7869.134395216636, 7907.288344049063, 10180.389176649425, 11724.648260552198, 12182.75634487323, 12255.0, 12476.376181190722], "value": 8809.228053041994, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.5247539598836, 1464.341046529062, 1541.9171368949492, 1658.8921720088194, 1852.83324500795, 8899.599518021689, 9159.313051521129, 9193.678221262257, 9511.571979260932, 9528.237740980721, 9213.355065145442, 9409.29430292729, 9412.717618471439, 9418.340716149873, 9418.481983490905, 2009.176237742526, 2279.076973825603, 2340.3681006128336, 2351.376885125443, 2377.6552399902002, 2356.45801465663, 2365.27859003004, 2371.881405929705, 2375.7505461926507, 2377.0254195779735, 2276.521930394619, 2316.5830150572974, 2318.4783423729696, 2322.094383190549, 2322.326525307659, 1972.063269946771, 2281.1445707859552, 2339.7309309429293, 2339.7426283108844, 2366.928992130239, 2278.97721022791, 2286.4283929017747, 2287.977120228793, 2291.2667946257206, 2317.1310758465575, 9333.0, 9404.858927116098, 9407.388519746195, 9413.48225847585, 9419.717408477756, 5233.607479439019, 5358.651687640291, 5364.597655175878, 5369.516743493065, 5370.301860758115, 3505.0886769439876, 3571.8571257149683, 3594.8382322795273, 3640.872569460056, 3651.087228192952, 1168.1647621950262, 1198.3648666206925, 1216.4100411300512, 1220.6094049904034, 1250.956216532417, 13187.076904616584, 13195.538156164488, 13204.801927970992, 13232.603021909357, 13234.801477977839, 4542.84100056503, 4617.884552886205, 4721.5278472153195, 4744.4069491313585, 4747.382840230748, 11034.337939723642, 11034.503447344889, 11038.944805275922, 11044.502997365138, 11053.502592383362, 4705.929411058837, 5350.812721554805, 5352.0, 5353.812616558402, 5357.598180136428, 4696.671233013724, 4803.855884323406, 4806.423229212517, 4851.442084160296, 4853.927191092136, 4018.6537509933914, 4048.1903619276263, 4065.6747460203114, 4074.246264441088, 4088.8773336799945, 1200.9459574319176, 1204.3015051270243, 1224.9448774805155, 1242.7887259165964, 1271.167385362589, 1044.368157264859, 1052.410650035967, 1063.8085144673878, 1066.1896958311738, 1076.4671487613525, 6199.628022318675, 6231.283402408691, 6391.680415979183, 6439.5170362223025, 6457.321981192001, 429.3623968406913, 429.6240789309354, 430.0195554136511, 437.3963929776888, 437.8117409513876, 4454.0, 4507.459104907432, 4511.436070491189, 4539.500654927955, 4542.795574199169, 11214.0, 11620.941895290638, 11636.3600001999, 11701.824472632918, 11706.71719014784, 11128.833067503994, 11525.135614828921, 11549.191556590958, 11566.0, 11583.536658533732, 8556.27271681914, 8661.480311181349, 8696.304295656333, 8724.563771811385, 8732.912670873337, 6268.216472940882, 6464.0, 6524.543281970216, 6528.37980391862, 6539.901901471387, 10062.899371006197, 10338.844917326096, 10346.0, 10358.637447689596, 10451.68644940653, 10435.791284174122, 10511.894881051092, 10590.682279531626, 10620.256582039492, 10628.309159904582, 7383.963080184565, 7472.252774722484, 7550.320471157622, 8193.426460147972, 8303.792405189919, 8700.216980471789, 8971.102889710977, 8974.68588599422, 9199.494027828267, 9208.401453905475, 7930.722424715107, 7941.0, 7944.443888927951, 8183.058948221031, 8393.328533717166, 7677.347425468898, 7823.882641760264, 7899.328557072713, 8019.9198008019175, 8231.670733170606, 6864.141982252218, 6993.335633114846, 7047.647617619201, 7102.644867756695, 7111.9288807113335, 5376.435474275301, 5382.058139825436, 5422.267993820784, 5500.339959204921, 5511.173324001432, 5438.782448702008, 5447.972760136174, 5484.424135465719, 5584.441555844545, 5629.915551266816], "value": 4707.763016233346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.096519263466, 327.58560421067347, 334.6553050358131, 336.17804468075565, 336.39448991814726, 5359.249705041292, 5381.515663590276, 5434.945650543494, 5435.42927992561, 5438.347398312203, 5283.150897185983, 5323.095073837448, 5348.705821179834, 5357.812476563321, 5366.892662146754, 3976.0, 4059.8579049733266, 4078.5717499662555, 4079.6940229482757, 4081.3673880548413, 4112.136451345206, 4152.837205582427, 4166.10428757817, 4166.854115118352, 4172.4784401949755, 4124.896877578055, 4135.338345864662, 4137.27597670408, 4153.501579810412, 4159.896002599929, 3955.72309938303, 4052.5339585947404, 4134.958650413518, 4139.834406623731, 4160.0, 3631.78335257689, 3639.3085313790293, 3655.780653160792, 3659.9085022874374, 3662.121090938182, 5108.6168537359845, 5114.744262786846, 5122.462141475166, 5146.536811686966, 5150.459201783834, 4630.9536904631195, 4708.411448568894, 4721.197396442613, 4726.5746082852365, 4738.739369334685, 5529.0, 5581.246531718207, 5584.553235741131, 5597.0, 5608.523275521586, 4051.6150965658217, 4058.0, 4062.309407400749, 4066.515721761565, 4077.938830917539, 5772.15291106846, 5806.274215723035, 5817.272840894931, 5825.155352473867, 5917.289925209003, 4122.34042553196, 4136.834526618902, 4140.503139623264, 4146.129312844337, 4147.875563733093, 4189.601987811153, 4193.1404062167185, 4200.22295875258, 4203.0, 4207.957920420818, 4375.606195442426, 4691.67761957833, 4756.548127927841, 4780.95219047812, 4784.593309568656, 5542.501174894279, 5581.860453488695, 5618.269624948731, 5632.352279487829, 5648.237487939075, 1387.7016441465134, 1407.8803301719263, 1413.7031223443191, 1415.469199050356, 1415.794709767078, 846.817934144162, 894.4186278918625, 895.9507227102572, 903.076447167529, 904.3985749476656, 5328.8401347959625, 5385.838424847261, 5420.945790542123, 5425.0, 5438.45615438453, 5756.942430575725, 5760.740766665426, 5774.913376299359, 5775.653460792366, 5786.71066446676, 5216.26972223889, 5229.98645521477, 5359.410464848864, 5394.460553944574, 5436.456354364611, 3010.232390740347, 3021.9244518886762, 3041.391721655678, 3054.938901221963, 3057.1898446911587, 5946.048632218824, 5947.316058653224, 5956.791512297049, 5961.403859614091, 5967.671778052249, 5594.860128496739, 5815.360310365941, 5829.7085145743395, 5831.533477321804, 5855.268091488564, 5871.0, 5886.735096920649, 5932.940670593239, 5953.184278794923, 6038.969805150946, 5637.787875606764, 5699.458551437607, 5819.5344372450945, 5827.1841942127285, 5829.358770535323, 5663.093904975184, 5693.601447898607, 5729.507664166101, 5733.512651424676, 5767.279090113736, 5883.529317654663, 5949.167116603766, 5949.762009519571, 5974.253218347707, 6023.3374328824675, 3982.123932734794, 4102.487189101363, 4104.261232977973, 4129.67850287917, 4132.834686612502, 5712.514436272797, 5738.655680659173, 5755.97122014404, 5797.739101740516, 5827.155062515996, 5403.297571315704, 5683.261176047087, 5699.287589051369, 5701.344345400332, 5707.571932105026, 5421.566274697936, 5631.324241091095, 5636.577256705681, 5650.773969041193, 5667.744951477276, 5323.946760532345, 5429.348478182644, 5442.0, 5508.779648814003, 5551.639143455663, 5519.316608434466, 5572.721363931706, 5623.634463759842, 5650.463205995424, 5655.321361436654, 5557.138643510262, 5566.137248726453, 5577.693226872561, 5615.831525054255, 5619.578531610064], "value": 4263.030947756866, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.4270976444432, 722.9842071888994, 761.8247803005311, 783.2559068884559, 798.2177466083234, 13295.734085318289, 15418.92290538547, 15594.142322172276, 15611.751059915208, 15628.687426251483, 14244.643883902907, 14372.0, 14908.62728431787, 14915.92542037291, 14927.179005154712, 694.1947341084343, 810.5055915891302, 830.9335253179731, 841.3311417423133, 846.5344060766585, 845.4208866926144, 851.940364174508, 853.0786750309651, 853.2022558907411, 853.3471894001094, 873.4540911930044, 877.6796469288728, 878.5738916625431, 878.6704985630389, 879.0901417033372, 722.4545468171523, 845.298402326074, 867.5228624256633, 873.3537182484947, 873.9169778870997, 635.0474288567149, 635.9427651511386, 636.0395802338502, 636.5162476517834, 636.8630744389978, 14264.429422823074, 14314.785278220836, 14317.212553309564, 14325.426982920671, 14326.0, 14097.0, 14295.356708948122, 14416.0, 14444.638884027878, 14452.638684032983, 12291.631251062483, 12411.937940310241, 12493.625191244277, 12545.184563003375, 12776.6805829855, 4539.296409056601, 4579.885502862422, 4592.977035114836, 4602.3786788783755, 4607.3319368691355, 57588.424115758724, 57776.71111644457, 59991.100133497595, 60192.09711854326, 60515.0, 11202.327860328243, 11347.37589432589, 11348.886511134948, 11421.657350279505, 11476.713082173012, 20827.895860520905, 20912.790872091387, 21134.154633814476, 21187.36437906865, 21263.36209913705, 8242.340612750966, 8288.70989515364, 8346.248837604644, 8398.706045288385, 8496.150384961455, 4771.045790841846, 4833.202521583969, 4849.660523763303, 4887.340209071801, 4934.358533390637, 3425.9314813703586, 3494.4583589543663, 3505.9123521911647, 3507.7018453431233, 3510.210202704371, 491.7664109547936, 652.9640869752209, 660.5343233020805, 661.9503537234631, 663.5156335874742, 673.5857447669648, 758.8102974256436, 761.5735188294573, 764.3425321068231, 801.6472751989106, 9077.636894524145, 9450.810983780286, 9455.338126331228, 9530.552010210688, 9581.377210481296, 732.8570928668859, 735.1692587376338, 749.8500299940034, 755.1429127939828, 785.6111224943576, 4436.401085853368, 4601.194790911644, 4685.465947957948, 4714.245720684674, 4782.402199725035, 67376.32623673798, 68292.0, 68576.0, 68808.6559567199, 71047.0, 46952.0, 47029.2970702927, 64740.0, 65163.674181629736, 66244.0, 57286.42713572894, 58696.11955820666, 58717.41282587119, 59223.703881480316, 59665.40334596772, 8852.3803333766, 8882.422642528474, 8916.73249802507, 8956.68651597191, 8972.237359824489, 37152.81423592865, 37316.06709832276, 37595.436068458446, 37838.810805945795, 38107.809460952514, 46283.0, 47734.522654774395, 47820.2826957589, 48186.759066204446, 49677.50322496729, 12736.490540378652, 12777.297248651414, 12832.294223817406, 12855.357232138544, 13335.533256335983, 12992.285424301754, 13634.181949083088, 14096.154230746188, 14427.639309017359, 14623.34194961208, 13349.599512014654, 13434.395452204479, 13708.17750934947, 13839.446422143, 13883.0, 10778.784424311312, 11083.224174307721, 11211.71970700739, 13246.86753132495, 13270.1374410663, 12105.878941210827, 12262.0, 12321.93839030799, 12370.0, 12438.440270187682, 9355.251579873458, 10034.147097496503, 10294.0, 10386.844197337197, 10436.425996570262, 10139.79720405573, 10191.643292484727, 10230.0, 10315.27793054479, 10610.257281990187], "value": 7008.936663037037, "unit": "score"}], "name": "dromaeo_css", "value": 7099.927914376011, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 109.0, 117.0, 108.0, 107.0, 109.0, 110.0, 108.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 148.0, 209.0, 125.0, 155.0, 153.0, 130.0, 185.0, 124.0], "value": 150.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 161.0, 161.0, 160.0, 161.0, 161.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 150.0, 75.0, 74.0, 74.0, 74.0, 129.0, 77.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 152.0, 91.0, 90.0, 92.0, 129.0, 92.0, 90.0, 126.0], "value": 104.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 94.0, 95.0, 95.0, 96.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 56.0, 53.0, 54.0, 106.0, 52.0, 51.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 58.0, 58.0, 59.0, 59.0, 61.0, 60.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 84.0, 77.0, 78.0, 85.0, 82.0, 78.0, 86.0, 79.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 122.0, 119.0, 130.0, 120.0, 132.0, 121.0, 123.0, 193.0, 131.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 159.0, 158.0, 158.0, 161.0, 157.0, 161.0, 164.0, 164.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 65.0, 63.0, 62.0, 61.0, 61.0, 61.0, 60.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1524.1999999999998, "alertThreshold": 2.0}]} 16:43:22 INFO - Return code: 0 16:43:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:43:22 ERROR - # TBPL SUCCESS # 16:43:22 INFO - Running post-action listener: _resource_record_post_action 16:43:22 INFO - ##### 16:43:22 INFO - ##### Finished run-tests step (success) 16:43:22 INFO - ##### 16:43:22 INFO - Running post-run listener: _resource_record_post_run 16:43:23 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 12288; Write bytes: 548741120; Read time: 1580; Write time: 2693888 16:43:23 INFO - TinderboxPrint: CPU usage
13.3% 16:43:23 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 1,580 16:43:23 INFO - TinderboxPrint: I/O write bytes / time
548,741,120 / 2,693,888 16:43:23 INFO - TinderboxPrint: CPU idle
8,314.7 (86.7%) 16:43:23 INFO - TinderboxPrint: CPU user
1,201.5 (12.5%) 16:43:23 INFO - TinderboxPrint: Swap in / out
0 / 0 16:43:23 INFO - install - Wall time: 22s; CPU: 22.0%; Read bytes: 0; Write bytes: 264474624; Read time: 0; Write time: 1781456 16:43:23 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 12288; Write bytes: 278990848; Read time: 1580; Write time: 911692 16:43:23 INFO - Running post-run listener: _upload_blobber_files 16:43:23 INFO - Blob upload gear active. 16:43:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:43:23 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 16:43:23 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'] 16:43:23 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 16:43:23 INFO - (blobuploader) - INFO - Open directory for files ... 16:43:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:43:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:43:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:43:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:24 INFO - (blobuploader) - INFO - Done attempting. 16:43:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:43:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:43:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:43:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:28 INFO - (blobuploader) - INFO - Done attempting. 16:43:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:43:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:43:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:29 INFO - (blobuploader) - INFO - Done attempting. 16:43:29 INFO - (blobuploader) - INFO - Iteration through files over. 16:43:29 INFO - Return code: 0 16:43:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:43:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:43:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8866ceaa599af36fe58c92dc93439ba241fc44401312fc05b837107d73c902cd296ab83c9128d7d68e378453a9cc7204222fb0c37ff3da2b3844a98956c71dd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a14c4dc5f983f7cdaf053583b03dde2ac69a48f3e4f55b948cad3dc968831f77fede1c163bebaceba04beb65db7785ab10b69ad8f60e680f3d89075582b22fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b03331a88637103150bf040d2779348e5fb356b74663dc3c3a204c7cb9fea0a532b1eec163fb446b6532e26a1c6dbd28fe22c027f3baa65145a3e105eefba92d"} 16:43:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:43:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:43:29 INFO - Contents: 16:43:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8866ceaa599af36fe58c92dc93439ba241fc44401312fc05b837107d73c902cd296ab83c9128d7d68e378453a9cc7204222fb0c37ff3da2b3844a98956c71dd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a14c4dc5f983f7cdaf053583b03dde2ac69a48f3e4f55b948cad3dc968831f77fede1c163bebaceba04beb65db7785ab10b69ad8f60e680f3d89075582b22fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b03331a88637103150bf040d2779348e5fb356b74663dc3c3a204c7cb9fea0a532b1eec163fb446b6532e26a1c6dbd28fe22c027f3baa65145a3e105eefba92d"} 16:43:29 INFO - Running post-run listener: copy_logs_to_upload_dir 16:43:29 INFO - Copying logs to upload dir... 16:43:29 INFO - mkdir: /builds/slave/test/build/upload/logs 16:43:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.246914 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-13 16:43:29.231343) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 16:43:29.234928) ========= 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=8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919 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/8866ceaa599af36fe58c92dc93439ba241fc44401312fc05b837107d73c902cd296ab83c9128d7d68e378453a9cc7204222fb0c37ff3da2b3844a98956c71dd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a14c4dc5f983f7cdaf053583b03dde2ac69a48f3e4f55b948cad3dc968831f77fede1c163bebaceba04beb65db7785ab10b69ad8f60e680f3d89075582b22fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b03331a88637103150bf040d2779348e5fb356b74663dc3c3a204c7cb9fea0a532b1eec163fb446b6532e26a1c6dbd28fe22c027f3baa65145a3e105eefba92d"} build_url:https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/PAC6ogm6TeWJUHWBedZaMA/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/8866ceaa599af36fe58c92dc93439ba241fc44401312fc05b837107d73c902cd296ab83c9128d7d68e378453a9cc7204222fb0c37ff3da2b3844a98956c71dd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a14c4dc5f983f7cdaf053583b03dde2ac69a48f3e4f55b948cad3dc968831f77fede1c163bebaceba04beb65db7785ab10b69ad8f60e680f3d89075582b22fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b03331a88637103150bf040d2779348e5fb356b74663dc3c3a204c7cb9fea0a532b1eec163fb446b6532e26a1c6dbd28fe22c027f3baa65145a3e105eefba92d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 16:43:29.259384) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:43:29.259718) ========= 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=8783db0840cc980e80bb0bd400000236-1463179844.252178-1121181919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:43:29.342482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 16:43:29.342847) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 16:43:29.343148) ========= ========= Total master_lag: 0.16 =========