builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1464833353.94 results: success (0) buildid: 20160601163037 builduid: 4f596a0e8a4e45f5a0d86f7b4fd53175 revision: 23fde8ecfa91aeaa071bb19165a999b8606008f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:13.937393) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:13.937854) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:13.938141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:13.953339) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:13.953671) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 19:09:14-- 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% 66.8M=0s 2016-06-01 19:09:14 (66.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114736 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.082072) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.082420) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019798 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.135255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.138654) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 23fde8ecfa91aeaa071bb19165a999b8606008f1 --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 23fde8ecfa91aeaa071bb19165a999b8606008f1 --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=78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 19:09:14,374 truncating revision to first 12 chars 2016-06-01 19:09:14,374 Setting DEBUG logging. 2016-06-01 19:09:14,374 attempt 1/10 2016-06-01 19:09:14,374 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/23fde8ecfa91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 19:09:14,837 unpacking tar archive at: mozilla-inbound-23fde8ecfa91/testing/mozharness/ program finished with exit code 0 elapsedTime=0.793311 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.944860) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.945184) ========= script_repo_revision: 23fde8ecfa91aeaa071bb19165a999b8606008f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.945577) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.945959) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:09:14.957182) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-01 19:09:14.957460) ========= /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=78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:09:15 INFO - MultiFileLogger online at 20160601 19:09:15 in /builds/slave/test 19:09:15 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 19:09:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:09:15 INFO - {'append_to_log': False, 19:09:15 INFO - 'base_work_dir': '/builds/slave/test', 19:09:15 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:09:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:09:15 INFO - 'branch': 'Mozilla-Inbound', 19:09:15 INFO - 'buildbot_json_path': 'buildprops.json', 19:09:15 INFO - 'config_files': ('talos/linux_config.py',), 19:09:15 INFO - 'default_actions': ('clobber', 19:09:15 INFO - 'read-buildbot-config', 19:09:15 INFO - 'download-and-extract', 19:09:15 INFO - 'populate-webroot', 19:09:15 INFO - 'create-virtualenv', 19:09:15 INFO - 'install', 19:09:15 INFO - 'run-tests'), 19:09:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:09:15 INFO - 'download_minidump_stackwalk': True, 19:09:15 INFO - 'download_symbols': 'ondemand', 19:09:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:09:15 INFO - 'tooltool.py': '/tools/tooltool.py', 19:09:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:09:15 INFO - '/tools/misc-python/virtualenv.py')}, 19:09:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:09:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:09:15 INFO - 'installer_path': 'installer.exe', 19:09:15 INFO - 'log_level': 'info', 19:09:15 INFO - 'log_name': 'talos', 19:09:15 INFO - 'log_to_console': True, 19:09:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:09:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:09:15 INFO - 'opt_config_files': (), 19:09:15 INFO - 'pip_index': False, 19:09:15 INFO - 'sps_profile': False, 19:09:15 INFO - 'sps_profile_interval': 0, 19:09:15 INFO - 'suite': 'dromaeojs', 19:09:15 INFO - 'system_bits': '32', 19:09:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:09:15 INFO - 'title': 'talos-linux64-ix-019', 19:09:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:09:15 INFO - 'use_talos_json': True, 19:09:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:09:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:09:15 INFO - 'work_dir': 'build'} 19:09:15 INFO - ##### 19:09:15 INFO - ##### Running clobber step. 19:09:15 INFO - ##### 19:09:15 INFO - Running pre-action listener: _resource_record_pre_action 19:09:15 INFO - Running main action method: clobber 19:09:15 INFO - rmtree: /builds/slave/test/build 19:09:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:09:15 INFO - Running post-action listener: _resource_record_post_action 19:09:15 INFO - ##### 19:09:15 INFO - ##### Finished clobber step (success) 19:09:15 INFO - ##### 19:09:15 INFO - ##### 19:09:15 INFO - ##### Running read-buildbot-config step. 19:09:15 INFO - ##### 19:09:15 INFO - Running pre-action listener: _resource_record_pre_action 19:09:15 INFO - Running main action method: read_buildbot_config 19:09:15 INFO - Using buildbot properties: 19:09:15 INFO - { 19:09:15 INFO - "project": "", 19:09:15 INFO - "product": "firefox", 19:09:15 INFO - "script_repo_revision": "production", 19:09:15 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 19:09:15 INFO - "repository": "", 19:09:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 19:09:15 INFO - "buildid": "20160601163037", 19:09:15 INFO - "pgo_build": "True", 19:09:15 INFO - "basedir": "/builds/slave/test", 19:09:15 INFO - "buildnumber": 1105, 19:09:15 INFO - "slavename": "talos-linux64-ix-019", 19:09:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:09:15 INFO - "platform": "ubuntu64_hw", 19:09:15 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 19:09:15 INFO - "branch": "mozilla-inbound", 19:09:15 INFO - "repo_path": "integration/mozilla-inbound", 19:09:15 INFO - "revision": "23fde8ecfa91aeaa071bb19165a999b8606008f1", 19:09:15 INFO - "stage_platform": "linux64-pgo", 19:09:15 INFO - "builduid": "4f596a0e8a4e45f5a0d86f7b4fd53175", 19:09:15 INFO - "slavebuilddir": "test" 19:09:15 INFO - } 19:09:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:09:15 INFO - Running post-action listener: _resource_record_post_action 19:09:15 INFO - ##### 19:09:15 INFO - ##### Finished read-buildbot-config step (success) 19:09:15 INFO - ##### 19:09:15 INFO - ##### 19:09:15 INFO - ##### Running download-and-extract step. 19:09:15 INFO - ##### 19:09:15 INFO - Running pre-action listener: _resource_record_pre_action 19:09:15 INFO - Running main action method: download_and_extract 19:09:15 INFO - mkdir: /builds/slave/test/build/tests 19:09:15 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')]} 19:09:15 INFO - https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:09:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:09:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:09:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:16 INFO - Downloaded 1361 bytes. 19:09:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:09:16 INFO - Using the following test package requirements: 19:09:16 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:09:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:09:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'jsshell-linux-x86_64.zip'], 19:09:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:09:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:09:16 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:09:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:09:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:09:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:09:16 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')]} 19:09:16 INFO - https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:09:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:09:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:17 INFO - Downloaded 24423222 bytes. 19:09:17 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'] 19:09:17 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 19:09:19 INFO - Return code: 0 19:09:19 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 19:09:19 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')]} 19:09:19 INFO - https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:20 INFO - Downloaded 24423222 bytes. 19:09:20 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'] 19:09:20 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 19:09:22 INFO - Return code: 0 19:09:22 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')]} 19:09:22 INFO - https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:09:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:23 INFO - Downloaded 10834055 bytes. 19:09:23 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'] 19:09:23 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 19:09:23 INFO - Return code: 0 19:09:23 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')]} 19:09:23 INFO - https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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 19:09:24 INFO - Downloaded 59068233 bytes. 19:09:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:09:24 INFO - mkdir: /builds/slave/test/properties 19:09:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:09:24 INFO - Writing to file /builds/slave/test/properties/build_url 19:09:24 INFO - Contents: 19:09:24 INFO - build_url:https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:09:25 INFO - Running post-action listener: _resource_record_post_action 19:09:25 INFO - Running post-action listener: set_extra_try_arguments 19:09:25 INFO - ##### 19:09:25 INFO - ##### Finished download-and-extract step (success) 19:09:25 INFO - ##### 19:09:25 INFO - ##### 19:09:25 INFO - ##### Running populate-webroot step. 19:09:25 INFO - ##### 19:09:25 INFO - Running pre-action listener: _resource_record_pre_action 19:09:25 INFO - Running main action method: populate_webroot 19:09:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:09:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:09:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:09:25 INFO - '--tppagecycles', 19:09:25 INFO - '7'], 19:09:25 INFO - 'tests': ['tsvgm']}}, 19:09:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:09:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:09:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:09:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:09:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['damp', 'tps']}, 19:09:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'tests': ['damp', 'tps']}, 19:09:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['dromaeo_dom']}, 19:09:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:09:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['a11yr', 19:09:25 INFO - 'ts_paint', 19:09:25 INFO - 'tpaint', 19:09:25 INFO - 'sessionrestore', 19:09:25 INFO - 'sessionrestore_no_auto_restore', 19:09:25 INFO - 'tabpaint']}, 19:09:25 INFO - 'other-e10s': {'tests': ['a11yr', 19:09:25 INFO - 'ts_paint', 19:09:25 INFO - 'tpaint', 19:09:25 INFO - 'sessionrestore', 19:09:25 INFO - 'sessionrestore_no_auto_restore', 19:09:25 INFO - 'tabpaint']}, 19:09:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:09:25 INFO - 'ts_paint', 19:09:25 INFO - 'tpaint', 19:09:25 INFO - 'sessionrestore', 19:09:25 INFO - 'sessionrestore_no_auto_restore', 19:09:25 INFO - 'tabpaint']}, 19:09:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:09:25 INFO - 'ts_paint', 19:09:25 INFO - 'tpaint', 19:09:25 INFO - 'sessionrestore', 19:09:25 INFO - 'sessionrestore_no_auto_restore', 19:09:25 INFO - 'tabpaint']}, 19:09:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['a11yr', 19:09:25 INFO - 'ts_paint', 19:09:25 INFO - 'tpaint', 19:09:25 INFO - 'sessionrestore', 19:09:25 INFO - 'sessionrestore_no_auto_restore', 19:09:25 INFO - 'tabpaint']}, 19:09:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['a11yr', 19:09:25 INFO - 'ts_paint', 19:09:25 INFO - 'tpaint', 19:09:25 INFO - 'sessionrestore', 19:09:25 INFO - 'sessionrestore_no_auto_restore', 19:09:25 INFO - 'tabpaint']}, 19:09:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['tsvgx', 19:09:25 INFO - 'tsvgr_opacity', 19:09:25 INFO - 'tart', 19:09:25 INFO - 'tscrollx', 19:09:25 INFO - 'cart']}, 19:09:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:09:25 INFO - 'tsvgr_opacity', 19:09:25 INFO - 'tart', 19:09:25 INFO - 'tscrollx', 19:09:25 INFO - 'cart']}, 19:09:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'talos_options': ['--disable-e10s'], 19:09:25 INFO - 'tests': ['tp5o']}, 19:09:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'tests': ['tp5o']}, 19:09:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'talos_options': ['--disable-e10s', 19:09:25 INFO - '--xperf_path', 19:09:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:25 INFO - 'tests': ['tp5n']}, 19:09:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:25 INFO - 'talos_options': ['--xperf_path', 19:09:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:25 INFO - 'tests': ['tp5n']}}, 19:09:25 INFO - 'talos.zip': {'path': '', 19:09:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:09:25 INFO - Running post-action listener: _resource_record_post_action 19:09:25 INFO - ##### 19:09:25 INFO - ##### Finished populate-webroot step (success) 19:09:25 INFO - ##### 19:09:25 INFO - ##### 19:09:25 INFO - ##### Running create-virtualenv step. 19:09:25 INFO - ##### 19:09:25 INFO - Running pre-action listener: _resource_record_pre_action 19:09:25 INFO - Running main action method: create_virtualenv 19:09:25 INFO - Creating virtualenv /builds/slave/test/build/venv 19:09:25 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 19:09:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:09:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:09:25 INFO - Using real prefix '/usr' 19:09:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:09:26 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:28 INFO - Installing pip.................done. 19:09:28 INFO - Return code: 0 19:09:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:09:28 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')]} 19:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:28 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')]} 19:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:28 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:28 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 19:09:28 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 19:09:28 INFO - Using env: {'DISPLAY': ':0', 19:09:28 INFO - 'HOME': '/home/cltbld', 19:09:28 INFO - 'LANG': 'en_US.UTF-8', 19:09:28 INFO - 'LANGUAGE': 'en_US:en', 19:09:28 INFO - 'LOGNAME': 'cltbld', 19:09:28 INFO - 'MAIL': '/var/mail/cltbld', 19:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:28 INFO - 'MOZ_NO_REMOTE': '1', 19:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:28 INFO - 'NO_EM_RESTART': '1', 19:09:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:28 INFO - 'PWD': '/builds/slave/test', 19:09:28 INFO - 'SHELL': '/bin/bash', 19:09:28 INFO - 'SHLVL': '1', 19:09:28 INFO - 'TERM': 'linux', 19:09:28 INFO - 'TMOUT': '86400', 19:09:28 INFO - 'USER': 'cltbld', 19:09:28 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:28 INFO - '_': '/tools/buildbot/bin/python'} 19:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:28 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 19:09:28 INFO - Cleaning up... 19:09:28 INFO - Return code: 0 19:09:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:09:28 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')]} 19:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:28 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')]} 19:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:28 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:28 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 19:09:28 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 19:09:28 INFO - Using env: {'DISPLAY': ':0', 19:09:28 INFO - 'HOME': '/home/cltbld', 19:09:28 INFO - 'LANG': 'en_US.UTF-8', 19:09:28 INFO - 'LANGUAGE': 'en_US:en', 19:09:28 INFO - 'LOGNAME': 'cltbld', 19:09:28 INFO - 'MAIL': '/var/mail/cltbld', 19:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:28 INFO - 'MOZ_NO_REMOTE': '1', 19:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:28 INFO - 'NO_EM_RESTART': '1', 19:09:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:28 INFO - 'PWD': '/builds/slave/test', 19:09:28 INFO - 'SHELL': '/bin/bash', 19:09:28 INFO - 'SHLVL': '1', 19:09:28 INFO - 'TERM': 'linux', 19:09:28 INFO - 'TMOUT': '86400', 19:09:28 INFO - 'USER': 'cltbld', 19:09:28 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:28 INFO - '_': '/tools/buildbot/bin/python'} 19:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:28 INFO - Downloading/unpacking psutil>=3.1.1 19:09:28 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 19:09:28 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 19:09:28 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 19:09:28 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 19:09:28 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 19:09:28 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 19:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:30 INFO - Installing collected packages: psutil 19:09:30 INFO - Running setup.py install for psutil 19:09:30 INFO - building 'psutil._psutil_linux' extension 19:09:30 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 19:09:30 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 19:09:30 INFO - building 'psutil._psutil_posix' extension 19:09:30 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 19:09:31 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 19:09:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:31 INFO - Successfully installed psutil 19:09:31 INFO - Cleaning up... 19:09:31 INFO - Return code: 0 19:09:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:09:31 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')]} 19:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:31 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')]} 19:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:09:31 INFO - Using env: {'DISPLAY': ':0', 19:09:31 INFO - 'HOME': '/home/cltbld', 19:09:31 INFO - 'LANG': 'en_US.UTF-8', 19:09:31 INFO - 'LANGUAGE': 'en_US:en', 19:09:31 INFO - 'LOGNAME': 'cltbld', 19:09:31 INFO - 'MAIL': '/var/mail/cltbld', 19:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:31 INFO - 'MOZ_NO_REMOTE': '1', 19:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:31 INFO - 'NO_EM_RESTART': '1', 19:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:31 INFO - 'PWD': '/builds/slave/test', 19:09:31 INFO - 'SHELL': '/bin/bash', 19:09:31 INFO - 'SHLVL': '1', 19:09:31 INFO - 'TERM': 'linux', 19:09:31 INFO - 'TMOUT': '86400', 19:09:31 INFO - 'USER': 'cltbld', 19:09:31 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:31 INFO - '_': '/tools/buildbot/bin/python'} 19:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:09:31 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 19:09:31 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 19:09:31 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 19:09:31 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 19:09:31 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 19:09:31 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 19:09:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:09:33 INFO - Installing collected packages: mozsystemmonitor 19:09:33 INFO - Running setup.py install for mozsystemmonitor 19:09:33 INFO - Successfully installed mozsystemmonitor 19:09:33 INFO - Cleaning up... 19:09:33 INFO - Return code: 0 19:09:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:33 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:33 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 19:09:33 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 19:09:33 INFO - Using env: {'DISPLAY': ':0', 19:09:33 INFO - 'HOME': '/home/cltbld', 19:09:33 INFO - 'LANG': 'en_US.UTF-8', 19:09:33 INFO - 'LANGUAGE': 'en_US:en', 19:09:33 INFO - 'LOGNAME': 'cltbld', 19:09:33 INFO - 'MAIL': '/var/mail/cltbld', 19:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:33 INFO - 'MOZ_NO_REMOTE': '1', 19:09:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:33 INFO - 'NO_EM_RESTART': '1', 19:09:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:33 INFO - 'PWD': '/builds/slave/test', 19:09:33 INFO - 'SHELL': '/bin/bash', 19:09:33 INFO - 'SHLVL': '1', 19:09:33 INFO - 'TERM': 'linux', 19:09:33 INFO - 'TMOUT': '86400', 19:09:33 INFO - 'USER': 'cltbld', 19:09:33 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:33 INFO - '_': '/tools/buildbot/bin/python'} 19:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:33 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:33 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 19:09:33 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 19:09:33 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 19:09:33 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 19:09:33 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 19:09:33 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 19:09:35 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:35 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 19:09:35 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 19:09:35 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 19:09:35 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 19:09:35 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 19:09:35 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 19:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:36 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 19:09:36 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 19:09:36 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 19:09:36 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 19:09:36 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 19:09:36 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 19:09:36 INFO - Downloading docopt-0.6.1.tar.gz 19:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:36 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:36 INFO - Running setup.py install for blobuploader 19:09:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:36 INFO - Running setup.py install for requests 19:09:36 INFO - Running setup.py install for docopt 19:09:36 INFO - Successfully installed blobuploader requests docopt 19:09:36 INFO - Cleaning up... 19:09:37 INFO - Return code: 0 19:09:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:37 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 19:09:37 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 19:09:37 INFO - Using env: {'DISPLAY': ':0', 19:09:37 INFO - 'HOME': '/home/cltbld', 19:09:37 INFO - 'LANG': 'en_US.UTF-8', 19:09:37 INFO - 'LANGUAGE': 'en_US:en', 19:09:37 INFO - 'LOGNAME': 'cltbld', 19:09:37 INFO - 'MAIL': '/var/mail/cltbld', 19:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:37 INFO - 'MOZ_NO_REMOTE': '1', 19:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:37 INFO - 'NO_EM_RESTART': '1', 19:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:37 INFO - 'PWD': '/builds/slave/test', 19:09:37 INFO - 'SHELL': '/bin/bash', 19:09:37 INFO - 'SHLVL': '1', 19:09:37 INFO - 'TERM': 'linux', 19:09:37 INFO - 'TMOUT': '86400', 19:09:37 INFO - 'USER': 'cltbld', 19:09:37 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:37 INFO - '_': '/tools/buildbot/bin/python'} 19:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:37 INFO - Running setup.py (path:/tmp/pip-9AwB1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:37 INFO - Running setup.py (path:/tmp/pip-eA_Jng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:37 INFO - Running setup.py (path:/tmp/pip-S_zQEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:37 INFO - Running setup.py (path:/tmp/pip-mBTdIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:37 INFO - Running setup.py (path:/tmp/pip-hiSKYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:37 INFO - Running setup.py (path:/tmp/pip-NnvFcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:37 INFO - Running setup.py (path:/tmp/pip-KVF7R5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:37 INFO - Running setup.py (path:/tmp/pip-2uANAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:37 INFO - Running setup.py (path:/tmp/pip-USd6rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:37 INFO - Running setup.py (path:/tmp/pip-J7S_ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:37 INFO - Running setup.py (path:/tmp/pip-OBQinB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:38 INFO - Running setup.py (path:/tmp/pip-V8BIRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:38 INFO - Running setup.py (path:/tmp/pip-TAXbIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:38 INFO - Running setup.py (path:/tmp/pip-6Ovh3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:38 INFO - Running setup.py (path:/tmp/pip-8hfQvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:38 INFO - Running setup.py (path:/tmp/pip-ZRj6Xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:38 INFO - Running setup.py (path:/tmp/pip-zwqTtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:38 INFO - Running setup.py install for manifestparser 19:09:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Running setup.py install for mozcrash 19:09:38 INFO - Running setup.py install for mozdebug 19:09:38 INFO - Running setup.py install for mozdevice 19:09:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Running setup.py install for mozfile 19:09:38 INFO - Running setup.py install for mozhttpd 19:09:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Running setup.py install for mozinfo 19:09:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Running setup.py install for mozInstall 19:09:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Running setup.py install for mozleak 19:09:39 INFO - Running setup.py install for mozlog 19:09:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Running setup.py install for moznetwork 19:09:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Running setup.py install for mozprocess 19:09:39 INFO - Running setup.py install for mozprofile 19:09:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Running setup.py install for mozrunner 19:09:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:39 INFO - Running setup.py install for mozscreenshot 19:09:40 INFO - Running setup.py install for moztest 19:09:40 INFO - Running setup.py install for mozversion 19:09:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:40 INFO - Cleaning up... 19:09:40 INFO - Return code: 0 19:09:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:40 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')]} 19:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:40 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')]} 19:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:40 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:40 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 19:09:40 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 19:09:40 INFO - Using env: {'DISPLAY': ':0', 19:09:40 INFO - 'HOME': '/home/cltbld', 19:09:40 INFO - 'LANG': 'en_US.UTF-8', 19:09:40 INFO - 'LANGUAGE': 'en_US:en', 19:09:40 INFO - 'LOGNAME': 'cltbld', 19:09:40 INFO - 'MAIL': '/var/mail/cltbld', 19:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:40 INFO - 'MOZ_NO_REMOTE': '1', 19:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:40 INFO - 'NO_EM_RESTART': '1', 19:09:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:40 INFO - 'PWD': '/builds/slave/test', 19:09:40 INFO - 'SHELL': '/bin/bash', 19:09:40 INFO - 'SHLVL': '1', 19:09:40 INFO - 'TERM': 'linux', 19:09:40 INFO - 'TMOUT': '86400', 19:09:40 INFO - 'USER': 'cltbld', 19:09:40 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:40 INFO - '_': '/tools/buildbot/bin/python'} 19:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:40 INFO - Running setup.py (path:/tmp/pip-jNRkQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:40 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)) 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:40 INFO - Running setup.py (path:/tmp/pip-NcpoEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:40 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)) 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:40 INFO - Running setup.py (path:/tmp/pip-OWuaWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:40 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)) 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:40 INFO - Running setup.py (path:/tmp/pip-5tT52H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:40 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)) 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:40 INFO - Running setup.py (path:/tmp/pip-HukydL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:40 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)) 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:40 INFO - Running setup.py (path:/tmp/pip-vJd0r3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:40 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)) 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:40 INFO - Running setup.py (path:/tmp/pip-E_A1p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:40 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)) 19:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:40 INFO - Running setup.py (path:/tmp/pip-IefQzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:41 INFO - Running setup.py (path:/tmp/pip-6zRMJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:41 INFO - Running setup.py (path:/tmp/pip-0jhL5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:41 INFO - Running setup.py (path:/tmp/pip-xT0Tyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:41 INFO - Running setup.py (path:/tmp/pip-_8Az1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:41 INFO - Running setup.py (path:/tmp/pip-oOxD6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:41 INFO - Running setup.py (path:/tmp/pip-1xvi9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:41 INFO - Running setup.py (path:/tmp/pip-1TKF7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:41 INFO - Running setup.py (path:/tmp/pip-HqvuJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:41 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)) 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:41 INFO - Running setup.py (path:/tmp/pip-ZPnqxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:41 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)) 19:09:41 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)) 19:09:41 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)) 19:09:41 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)) 19:09:41 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)) 19:09:41 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)) 19:09:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:41 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 19:09:41 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 19:09:41 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 19:09:41 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 19:09:41 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 19:09:41 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 19:09:43 INFO - Downloading blessings-1.6.tar.gz 19:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:43 INFO - Installing collected packages: blessings 19:09:43 INFO - Running setup.py install for blessings 19:09:43 INFO - Successfully installed blessings 19:09:43 INFO - Cleaning up... 19:09:43 INFO - Return code: 0 19:09:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:44 INFO - Reading from file tmpfile_stdout 19:09:44 INFO - Current package versions: 19:09:44 INFO - argparse == 1.2.1 19:09:44 INFO - blessings == 1.6 19:09:44 INFO - blobuploader == 1.2.4 19:09:44 INFO - docopt == 0.6.1 19:09:44 INFO - manifestparser == 1.1 19:09:44 INFO - mozInstall == 1.12 19:09:44 INFO - mozcrash == 0.17 19:09:44 INFO - mozdebug == 0.1 19:09:44 INFO - mozdevice == 0.48 19:09:44 INFO - mozfile == 1.2 19:09:44 INFO - mozhttpd == 0.7 19:09:44 INFO - mozinfo == 0.9 19:09:44 INFO - mozleak == 0.1 19:09:44 INFO - mozlog == 3.2 19:09:44 INFO - moznetwork == 0.27 19:09:44 INFO - mozprocess == 0.22 19:09:44 INFO - mozprofile == 0.28 19:09:44 INFO - mozrunner == 6.11 19:09:44 INFO - mozscreenshot == 0.1 19:09:44 INFO - mozsystemmonitor == 0.3 19:09:44 INFO - moztest == 0.7 19:09:44 INFO - mozversion == 1.4 19:09:44 INFO - psutil == 3.1.1 19:09:44 INFO - requests == 1.2.3 19:09:44 INFO - wsgiref == 0.1.2 19:09:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:44 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')]} 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:44 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')]} 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:44 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:44 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 19:09:44 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 19:09:44 INFO - Using env: {'DISPLAY': ':0', 19:09:44 INFO - 'HOME': '/home/cltbld', 19:09:44 INFO - 'LANG': 'en_US.UTF-8', 19:09:44 INFO - 'LANGUAGE': 'en_US:en', 19:09:44 INFO - 'LOGNAME': 'cltbld', 19:09:44 INFO - 'MAIL': '/var/mail/cltbld', 19:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:44 INFO - 'MOZ_NO_REMOTE': '1', 19:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:44 INFO - 'NO_EM_RESTART': '1', 19:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:44 INFO - 'PWD': '/builds/slave/test', 19:09:44 INFO - 'SHELL': '/bin/bash', 19:09:44 INFO - 'SHLVL': '1', 19:09:44 INFO - 'TERM': 'linux', 19:09:44 INFO - 'TMOUT': '86400', 19:09:44 INFO - 'USER': 'cltbld', 19:09:44 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:44 INFO - '_': '/tools/buildbot/bin/python'} 19:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 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)) 19:09:44 INFO - Cleaning up... 19:09:44 INFO - Return code: 0 19:09:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:44 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')]} 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:44 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')]} 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:44 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:44 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 19:09:44 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 19:09:44 INFO - Using env: {'DISPLAY': ':0', 19:09:44 INFO - 'HOME': '/home/cltbld', 19:09:44 INFO - 'LANG': 'en_US.UTF-8', 19:09:44 INFO - 'LANGUAGE': 'en_US:en', 19:09:44 INFO - 'LOGNAME': 'cltbld', 19:09:44 INFO - 'MAIL': '/var/mail/cltbld', 19:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:44 INFO - 'MOZ_NO_REMOTE': '1', 19:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:44 INFO - 'NO_EM_RESTART': '1', 19:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:44 INFO - 'PWD': '/builds/slave/test', 19:09:44 INFO - 'SHELL': '/bin/bash', 19:09:44 INFO - 'SHLVL': '1', 19:09:44 INFO - 'TERM': 'linux', 19:09:44 INFO - 'TMOUT': '86400', 19:09:44 INFO - 'USER': 'cltbld', 19:09:44 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:44 INFO - '_': '/tools/buildbot/bin/python'} 19:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:44 INFO - Downloading/unpacking jsonschema 19:09:44 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 19:09:44 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 19:09:44 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 19:09:44 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 19:09:44 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 19:09:44 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 19:09:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:46 INFO - Downloading/unpacking functools32 (from jsonschema) 19:09:46 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 19:09:46 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 19:09:46 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 19:09:46 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 19:09:46 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 19:09:46 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 19:09:46 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:46 INFO - warning: no files found matching '*.txt' 19:09:46 INFO - no previously-included directories found matching 'build' 19:09:46 INFO - no previously-included directories found matching 'dist' 19:09:46 INFO - no previously-included directories found matching '.git*' 19:09:46 INFO - Installing collected packages: jsonschema, functools32 19:09:46 INFO - Running setup.py install for functools32 19:09:46 INFO - warning: no files found matching '*.txt' 19:09:46 INFO - no previously-included directories found matching 'build' 19:09:46 INFO - no previously-included directories found matching 'dist' 19:09:46 INFO - no previously-included directories found matching '.git*' 19:09:46 INFO - Successfully installed jsonschema functools32 19:09:46 INFO - Cleaning up... 19:09:46 INFO - Return code: 0 19:09:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:46 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 0x7fcdfd085f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', '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 19:09:46 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 19:09:46 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 19:09:46 INFO - Using env: {'DISPLAY': ':0', 19:09:46 INFO - 'HOME': '/home/cltbld', 19:09:46 INFO - 'LANG': 'en_US.UTF-8', 19:09:46 INFO - 'LANGUAGE': 'en_US:en', 19:09:46 INFO - 'LOGNAME': 'cltbld', 19:09:46 INFO - 'MAIL': '/var/mail/cltbld', 19:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:46 INFO - 'MOZ_NO_REMOTE': '1', 19:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:46 INFO - 'NO_EM_RESTART': '1', 19:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:46 INFO - 'PWD': '/builds/slave/test', 19:09:46 INFO - 'SHELL': '/bin/bash', 19:09:46 INFO - 'SHLVL': '1', 19:09:46 INFO - 'TERM': 'linux', 19:09:46 INFO - 'TMOUT': '86400', 19:09:46 INFO - 'USER': 'cltbld', 19:09:46 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:46 INFO - '_': '/tools/buildbot/bin/python'} 19:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:47 INFO - Downloading/unpacking flake8 19:09:47 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 19:09:47 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 19:09:47 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 19:09:47 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 19:09:47 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 19:09:47 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 19:09:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:09:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:09:48 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 19:09:48 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 19:09:48 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 19:09:48 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 19:09:48 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 19:09:48 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 19:09:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:09:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:09:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:49 INFO - no previously-included directories found matching 'docs/_build' 19:09:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:09:49 INFO - Running setup.py install for pep8 19:09:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:49 INFO - no previously-included directories found matching 'docs/_build' 19:09:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:09:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:09:49 INFO - Cleaning up... 19:09:49 INFO - Return code: 0 19:09:49 INFO - Running post-action listener: _resource_record_post_action 19:09:49 INFO - Running post-action listener: _start_resource_monitoring 19:09:49 INFO - Starting resource monitoring. 19:09:49 INFO - ##### 19:09:49 INFO - ##### Finished create-virtualenv step (success) 19:09:49 INFO - ##### 19:09:49 INFO - ##### 19:09:49 INFO - ##### Running install step. 19:09:49 INFO - ##### 19:09:49 INFO - Running pre-action listener: _resource_record_pre_action 19:09:49 INFO - Running main action method: install 19:09:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:50 INFO - Reading from file tmpfile_stdout 19:09:50 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:50 INFO - Reading from file tmpfile_stdout 19:09:50 INFO - Output received: 19:09:50 INFO - Usage: mozinstall [options] installer 19:09:50 INFO - Options: 19:09:50 INFO - -h, --help show this help message and exit 19:09:50 INFO - -d DEST, --destination=DEST 19:09:50 INFO - Directory to install application into. [default: 19:09:50 INFO - "/builds/slave/test"] 19:09:50 INFO - --app=APP Application being installed. [default: firefox] 19:09:50 INFO - mkdir: /builds/slave/test/build/application 19:09:50 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'] 19:09:50 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 19:10:06 INFO - Reading from file tmpfile_stdout 19:10:06 INFO - Output received: 19:10:06 INFO - /builds/slave/test/build/application/firefox/firefox 19:10:06 INFO - Running post-action listener: _resource_record_post_action 19:10:06 INFO - ##### 19:10:06 INFO - ##### Finished install step (success) 19:10:06 INFO - ##### 19:10:06 INFO - ##### 19:10:06 INFO - ##### Running run-tests step. 19:10:06 INFO - ##### 19:10:06 INFO - Running pre-action listener: _resource_record_pre_action 19:10:06 INFO - Running main action method: run_tests 19:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:10:06 INFO - Python 2.7.3 19:10:06 INFO - Return code: 0 19:10:06 INFO - grabbing minidump binary from tooltool 19:10:06 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')]} 19:10:06 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 0x1e299c0>, '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 0x1e2a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e2ad50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:10:06 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 19:10:06 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 19:10:06 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 19:10:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:10:07 INFO - Return code: 0 19:10:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:10:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:10:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:10:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:10:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:10:07 INFO - Return code: 0 19:10:07 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/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 19:10:07 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/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 19:10:07 INFO - Using env: {'DISPLAY': ':0', 19:10:07 INFO - 'HOME': '/home/cltbld', 19:10:07 INFO - 'LANG': 'en_US.UTF-8', 19:10:07 INFO - 'LANGUAGE': 'en_US:en', 19:10:07 INFO - 'LOGNAME': 'cltbld', 19:10:07 INFO - 'MAIL': '/var/mail/cltbld', 19:10:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:10:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:07 INFO - 'MOZ_NO_REMOTE': '1', 19:10:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:07 INFO - 'NO_EM_RESTART': '1', 19:10:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:07 INFO - 'PWD': '/builds/slave/test', 19:10:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:10:07 INFO - 'SHELL': '/bin/bash', 19:10:07 INFO - 'SHLVL': '1', 19:10:07 INFO - 'TERM': 'linux', 19:10:07 INFO - 'TMOUT': '86400', 19:10:07 INFO - 'USER': 'cltbld', 19:10:07 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689', 19:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:07 INFO - '_': '/tools/buildbot/bin/python'} 19:10:07 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/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 19:10:08 INFO - mozversion application_buildid: 20160601163037 19:10:08 INFO - mozversion application_changeset: 23fde8ecfa91aeaa071bb19165a999b8606008f1 19:10:08 INFO - mozversion application_display_name: Nightly 19:10:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:10:08 INFO - mozversion application_name: Firefox 19:10:08 INFO - mozversion application_remotingname: firefox 19:10:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:10:08 INFO - mozversion application_vendor: Mozilla 19:10:08 INFO - mozversion application_version: 49.0a1 19:10:08 INFO - mozversion platform_buildid: 20160601163037 19:10:08 INFO - mozversion platform_changeset: 23fde8ecfa91aeaa071bb19165a999b8606008f1 19:10:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:10:08 INFO - mozversion platform_version: 49.0a1 19:10:08 INFO - using testdate: 1464833408 19:10:08 INFO - actual date: 1464833408 19:10:08 INFO - starting webserver on 'localhost:45175' 19:10:08 INFO - SUITE-START | Running 2 tests 19:10:08 INFO - TEST-START | dromaeo_css 19:10:08 INFO - operating with platform_type : linux_ 19:10:08 INFO - Initialising browser for dromaeo_css test... 19:10:08 INFO - TEST-INFO | started process 3555 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0yQpof/profile http://localhost:45175/getInfo.html) 19:10:12 INFO - PROCESS | 3555 | __metrics Screen width/height:1600/1200 19:10:12 INFO - PROCESS | 3555 | colorDepth:24 19:10:12 INFO - PROCESS | 3555 | Browser inner width/height: 1024/697 19:10:12 INFO - PROCESS | 3555 | __metrics 19:10:13 ERROR - PROCESS | 3555 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:10:14 INFO - TEST-INFO | 3555: exit 0 19:10:14 INFO - Browser initialized. 19:10:14 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:14 INFO - TEST-INFO | started process 3640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0yQpof/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:26 INFO - PROCESS | 3640 | RSS: Main: 155951104 19:10:26 INFO - PROCESS | 3640 | 19:11:17 INFO - PROCESS | 3640 | 1464833477621 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] 19:13:25 INFO - PROCESS | 3640 | Cycle 1(1): loaded http://localhost:45175/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45175/tests/dromaeo/cssquery-ext.html) 19:13:26 INFO - PROCESS | 3640 | RSS: Main: 289021952 19:13:26 INFO - PROCESS | 3640 | 19:16:29 INFO - PROCESS | 3640 | Cycle 1(1): loaded http://localhost:45175/tests/dromaeo/cssquery-ext.html (next: http://localhost:45175/tests/dromaeo/cssquery-jquery.html) 19:16:30 INFO - PROCESS | 3640 | RSS: Main: 240553984 19:16:30 INFO - PROCESS | 3640 | 19:19:32 INFO - PROCESS | 3640 | Cycle 1(1): loaded http://localhost:45175/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45175/tests/dromaeo/cssquery-mootools.html) 19:19:32 INFO - PROCESS | 3640 | RSS: Main: 245047296 19:19:32 INFO - PROCESS | 3640 | 19:22:34 INFO - PROCESS | 3640 | Cycle 1(1): loaded http://localhost:45175/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45175/tests/dromaeo/cssquery-prototype.html) 19:22:35 INFO - PROCESS | 3640 | RSS: Main: 235864064 19:22:35 INFO - PROCESS | 3640 | 19:25:37 INFO - PROCESS | 3640 | Cycle 1(1): loaded http://localhost:45175/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45175/tests/dromaeo/cssquery-yui.html) 19:25:38 INFO - PROCESS | 3640 | RSS: Main: 210051072 19:25:38 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | Cycle 1(1): loaded http://localhost:45175/tests/dromaeo/cssquery-yui.html (next: http://localhost:45175/tests/dromaeo/cssquery-dojo.html) 19:28:41 INFO - PROCESS | 3640 | RSS: Main: 290488320 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | __start_tp_report 19:28:41 INFO - PROCESS | 3640 | _x_x_mozilla_page_load 19:28:41 INFO - PROCESS | 3640 | _x_x_mozilla_page_load_details 19:28:41 INFO - PROCESS | 3640 | |i|pagename|runs| 19:28:41 INFO - PROCESS | 3640 | |0;dojo.html;3132.8433578321087;3165.256164801273;3174.333389988102;3202.967970320296;3225.9193520161994;5355.732213389325;5424.864378390542;5448.182772584114;5556.472135147164;5583.07879199932;5439.755211015504;5454.072807622703;5501.5323697485765;5555.444455554442;5612.242347283126;4927.556519913207;4971.726555039471;4995.725235112068;5003.649744517885;5176.5341119299255;5066.949330506684;5113.181890897457;5136.922946155792;5147.974260128694;5160.741962901859;4861.465238823726;4934.358533390654;4937.111319962405;4993.076280888047;4995.925061124086;4937.23472861707;4970.254461830718;4971.353724015891;5012.398512178562;5021.799128034874;4922.680025798311;4938.4814594467425;4964.429090654548;5027.673201241908;5077.365329333833;5513.724313784295;5552.472515111058;5556.972215138939;5565.109582466826;5570.3315602127595;5667.603267771257;5869.031609784381;5923.289205295349;5928.7628494860155;6025.096235564656;6001.339852616209;6005.429484198994;6027.9095813562835;6046;6064.60580062299;5273.894522109613;5611.663300202001;5623;5626.409227031144;5630.718464076821;5975.342712301644;6022.819315420545;6027.849303767353;6101.267847858286;6111.022236442148;5860.267466566679;5888.057910734262;5900.822975310747;5909.379515150933;5912.142739302863;5701.800436984686;5837.649741015468;5906.055031195074;5952.434518720715;5966.940330596639;5853.912191317134;5908.734106965197;5911.4975227105315;5912.497437717842;5966.194563733927;5883.61756485827;5918.704064796743;5928.170056192136;5931.525477961753;5934.525237980951;4293.635041021485;4436.26801577736;4457.04173602677;4465.910681786346;4477.731336119843;3998.8400463981116;4012.4181993611346;4020.2763502569233;4052.149048699806;4058.127502586899;5613.073842815971;5653.321601407857;5668.064769313017;5669;5723.284589426322;5777.220075289866;5810.4189581041555;5834.124881267759;5871.500922421642;6058.909116363258;5530.2810634618045;5552;5552.111662134039;5554.416786237468;5555.972220138873;4921.557059864629;4925.13810083234;4931.556159945622;4935.876603084952;4974.9751251243515;5873.794417195378;6095.451409373178;6131.877362452726;6133.764122814904;6140.846478838065;5984.102384642339;5990.430909063632;5997.760089596367;5999.820005399845;6037.9094313584455;5885.087811389241;6157.415045570664;6172.537059720449;6173.506119510339;6174.104754810617;5812.709364531841;5830.4461076197695;5856.355800861987;5872.911906321323;5881;5930;5975.073863551156;5983.670898100472;5985.311689155803;5990.460858522754;5525.64083334598;5761.107028410603;5850.005499065254;5876.08920617305;5879.353271140257;5778.479936805707;5815.2149459822385;5816.796412125555;5828.854278643068;5866.794662186803;5727.341355743976;5733.713314334183;5734.770609175753;5740;5749.683767392834;5618.88762224745;5782.161586570008;5810.186573879576;5832.708364581838;5895.734691938959;5439.836804895859;5475.09660905946;5628.718564071862;5691.914621280601;5722.2561067061015 19:28:41 INFO - PROCESS | 3640 | |1;ext.html;580.4775701868318;612.1857928954491;617.7837756785123;625.7434451874731;649.6329573790811;32101.839490802544;32624.836875815618;43599.34600980988;44625.553744462544;44684.776576117074;42653.14693706132;46716.29925551119;46776;46832.29751553721;46837;8626.482411055342;8795.208431241186;8799.120087991214;8802.295816334678;8802.911970880305;8631.95684021579;8751.431156974808;8773.254273386738;8775.912240877573;8782.077881822412;8778.034416214212;8792.384533082686;8824.955875220616;8831.911680883173;8838.513881736502;8741.30069594431;8765.430247033923;8778.078301778285;8782.077881822444;8787.0773568775;8794;8810.163034511712;8900.732978010605;8902.465852048897;8906.109389061106;42827.35758963587;43398.13203735939;43420.3486947696;43444;43529.564704353186;3437.1063523483826;3964.147708242742;4058.228936502055;4110.835566577333;4246.596573325529;32674.6732532674;32953.83523082369;33357.33285334304;33678.32643347119;33854.49218261728;3883.2233553289175;3896.5713771485116;3902.687784977195;3959.1487830116375;4025.8993525161814;262495;367304;369901;370626;371075;62046;62826.37173628296;62905;63081.68459157675;63106.68446657829;35880.820595896854;36537.81731091381;36583;65871.67064164649;65999.67000164969;17085.31658733637;17107.572310692332;17190.796644234782;17227.31090756381;17237.482875513753;12871.356432178354;12951.093423460266;12952.740945181233;12959.611211663665;12961.22232666043;20316.89841550783;20399.388018359474;20444.955504449732;20449.261812745786;20465.2837150702;7248.855022899513;7261.673224704795;7264.418846492268;7265.927340726631;7286.417086633057;1016.9338992965434;1098.7198264442516;1109.2346281066039;1127.4193790198055;1169.649105268433;32508.512372314435;32978.17554561107;33038.69613038677;33185.170370740445;33286;1127.6278827986744;1130.8982191602631;1175.3183153770792;1192.1396081695746;1211.9515219391126;17416.65166696659;17500.38486941742;17557.122143892753;17575.384861529787;17671.73492397615;592872;593711;596234;596654;600867;58741.11888321678;59279.70360148171;59344;59400.40599594035;59493.405065948784;1245.0973044542816;1349.230938365142;1353.7563238616947;1353.8375394952668;1384.1349156776814;32908.67091329056;33766.3246735059;34323.656763432045;34535;34628.30743385169;50337.244941326644;50357;50996;51033;51041.74479127581;436410;437400;439980;440769;440984;24533.386665332797;28008.29979250535;28473;29544.409111818073;29727.10818675443;49292;49348.75325623349;49763.75118124386;50637.493625063275;51234;41744.58255417407;42364.61083113282;42384.78807605942;43245.13509729725;43858.56141438545;41668;41888.371674425514;42134.36798448087;42338.5766142347;42763.78618107014;41613.1677366457;41629;42160.15679686328;42516.36225456559;42527;35309;36979.8151009254;37126.44310335293;37779;37842.918639475094;27652.723472765014;28157;28192.29519262035;28261.717382825907;28374.85812570924 19:28:41 INFO - PROCESS | 3640 | |2;jquery.html;376.3677022602029;387.95926427725095;394.23124906432463;403.4069917221684;411.19816358101707;17809.82190178098;18609.348672796456;20574.89712551437;20598.794012059876;20637.793622063775;17431.128443577803;17766.28934842608;18162.364317248866;18219.18013689384;18239.452816415527;5062.114130027249;5067.594592432597;5167.6641018333685;5173.793048278064;5222.817201397952;3776.8678096266635;5410.1073322901675;5610.382857885629;5610.859728506779;5729.684867332295;5630.29024325768;5690.118031705071;5696.629719068268;5706.1726049722765;5725.398833122525;6242.1261023456755;6420.325865784095;6429.935700643027;6432.00303952888;6440.066190402412;5485.588580856452;5530.861728456781;5555.083411237141;5691.203231547586;5729.197912292282;12421.689457763538;12705.491780328868;12848.35758212095;12910.935445322808;12926;2239.7984181423585;2285.4514916420108;2344.1481229828414;2405.7834794868545;2522.495500899809;5940.702964851784;5959.880802383928;5968.910466343008;9540.141387275178;9549.233391822536;1897.7153426985924;1907.160849226336;1944.8346890514324;1962.3622322745166;1977.0411350494999;79999.6000020002;80391.19608803895;82621;84200.57899710523;85814;10272.435016074187;10311.175105991653;10370.11853992404;10407.94796026015;10562.630307939186;13764.17414955106;13783.724325513434;13834.308284585932;13843.238621875693;13846.9614778892;7711.344535714415;7784.494007889469;7944.6027698614835;7972.123066462685;8102.513849169069;7878.369730421668;7999.28006479408;8033.7991550210545;8153.8776918346275;8172.141925097898;4189.413482112506;4289.806958686867;4336.457942757155;4541.3869127668;4564.109998550317;1710.61511159988;1826.7807863056519;1838.4629211687786;1925.9984807899814;1994.3717728915344;916.8991410944791;987;1032.7056788815153;1070.8661417322835;1100.5487750022453;18598.22132612024;18990.52523686891;19131.808681913004;19308;19648.410547683594;972.0619602084023;1001.8897921228659;1021.7190272674925;1033.927625066253;1038.600138946496;10395.16838652906;10876.564937402416;10894.782104357868;10927.562897484011;10937.781244375068;468314;483689;486418;486913;491414;85521;88656.55671721729;89680.5515972416;96554.51722741341;98571.50714246383;966.1015255812022;972.8589354543551;974.770928831724;976.0922342221802;983.257640481432;16673;16855.91572042132;16879.493615191564;17055.403060892808;17360;42615;42759.3586096215;42873.785631071645;42885.57114428816;42900.14199716051;53712.1943170845;54000;56176.15735763885;63969.68015159894;64331.67834160799;7511.737089201852;7595.696172153052;7646.847063058596;7742.8451430969935;7870.921290787248;11136.220464567727;13209.339533023503;13214.207147571176;13450.394732237268;13558.525451609146;6564.6389448580785;6662.000699895055;9846.359986600848;11968.46141923633;12288.078394120656;11085.612003580158;11516.654500365003;11593.130515211562;11652;11741;10867.076298514714;11644.301341919512;12168.391580421121;12306.569270075504;12407.317597532223;8398.370122240734;8533.871991920001;8542.487450752975;8604.526751028754;8653;10603.310784798963;11848.644540663794;12152.453139608915;12172.330521821385;12202 19:28:41 INFO - PROCESS | 3640 | |3;mootools.html;1296.8897643700298;1426.6861290516083;1486.8141482314709;1607.8552930236276;1791.0686443049606;8459.746207613774;8863.734087977364;8913.242374398184;9180.770480737985;9195.724128276146;8892.599833007514;9038.051004644516;9069.863952040709;9074.319426043057;9123.40697854639;2032.7865574114726;2265.546890621874;2323.9883800581015;2349.9295021149305;2361.3034154924253;2250.561140577588;2254.278630838124;2257;2260.9321720348416;2262.830287728427;2300.8389412741117;2319.373769082347;2331.9067237310487;2335.3344296875393;2335.5795956727784;1988.7613486381583;2094.769575346726;2158.093600687715;2158.57907707997;2257.345369842744;2299.3561802695103;2306.4003359126746;2322.036354912709;2328.125858683759;2330.9627215888936;9054.77363065922;9201.76995575116;9204.861927071031;9212.447253164832;9221.262299016129;4948.925766113512;5092.592592592584;5095.643304968654;5102.413222479426;5103.030424219423;3170.8573114209685;3266.852991615383;3310.2717402171484;3342.2145795737983;3377.696007359325;1049.3494033699078;1068.6206396729206;1073.8550295710056;1077.4289626497969;1094.6114129484;12693.11148219625;12696.301703406309;12718;12720.681982950407;12730.618081457478;4447.577480139382;4544.636429085665;4567.9086418271445;4585.72485650862;4690.038542098861;10420.426876521713;10436.947815261026;10446.320989135682;10446.373217606968;10451.529681164366;5188.169892817131;5233.842984710464;5234.371875374979;5299.046171689133;5305.257263983045;4281.571842815756;4527.547245275512;4589.380433641416;4623.260278355447;4687.835925742651;3858.575556688762;3956.9208615827524;3957.248122856648;3980.2636512245326;4037.7577345359373;1185.974132375489;1260.8085359335523;1268.8921441677376;1269.2130878855169;1288.8195652608642;954.289054654287;958.6644674364056;962.7929995050957;963.691618682029;985.6205360935948;6125.264968203844;6126.816195514141;6204.441600255908;6236.906924091278;6389.424951754364;433.34348462079976;438.64031494175106;443.29737366274173;447.8343013085179;448.3767563087311;4138.213739389506;4257.340112282601;4257.467816522934;4282.250606143913;4311;11095.167862410352;11421.600243991421;11427.942860285646;11441;11473.368964706855;10739.570417183382;11175.050120739828;11188.328700278009;11260.32438053703;11485.770284594262;8281.503109813431;8350.874736878952;8404.285635721033;8414.326853851677;8415.747527574182;5694.686792226467;5858.794942177004;5915.260592425947;5923.822285331447;5946.548771259018;10295.07344339013;10583.788324233317;10592.735181620214;10642.095421888916;10655.413952232702;10459.424731639832;10694.62568810088;10768.19238557127;10906.672799816019;10914.181436392144;7071.186813516306;7611.238876112345;7787.29914307715;7891.131975482577;8085.353171746128;8851.114888511098;8870.467771933705;8889.911100888909;8927.553622318988;8935.821283574422;7550.152882392292;7818.10091839423;7868.960655196687;7875.684972601031;7960.243776841191;7699.961500192463;7717.073951125901;7803.648835802516;7993;8016.6392512335915;7351.522151060164;7403.296686814744;7405.296496832792;7432.516886402366;7441.032665753418;5065.468125846733;5193.506616871391;5363.115086010765;5418.945810542002;5494.670319780826;5169.663971841818;5395.136778115482;5538.833834984957;5661.207430959751;5668.801591944427 19:28:41 INFO - PROCESS | 3640 | |4;prototype.html;326.7059646318313;330.540548637394;337.8817413905133;342.23168985627285;343.2928167973972;5318.9202161967605;5366.551031221567;5371.650842695222;5374.677519348842;5389.434109418514;5249.238860365244;5304;5306.336707911511;5320.90921361121;5330.653507522008;3932.7050471214775;4218.557051509593;4231.661467082641;4239;4239.851605193819;4152.750834949913;4158.688098392618;4168.916621667565;4172.958270417287;4207.242696314662;4122.876313710579;4134.607212314825;4141.565135660758;4143.917121657565;4163.458750362449;3986.282469155565;4037;4164.5215948338355;4181.832726690929;4214.747115173069;3688.7458264190036;3701.407774756026;3707.4809526666018;3714.981425092857;3719;5097.413797413271;5125.871853203663;5127.461616530248;5165.605286572604;5171.818986335498;4607.493175750665;4675.859724208279;4851.878703032417;4854.199057155565;4867.464578896319;5490.752916118784;5536.339098270502;5579.69311687857;5587.3015873015875;5595.63628364155;4046.150308435232;4046.846648705134;4066.8169932353117;4094.467719196485;4112.362583799516;5692.829215123553;5802.7794574011405;5803.187553742479;5813.1979086483325;5832.270966129234;4180.707350485498;4210.947063379026;4211.410402543646;4216.325387937915;4218.915621687549;4292.806823692878;4307.978460107679;4311.590398912098;4320.870373888788;4340.370646256339;4540.0023989444535;4710.175719249118;4768.451628062817;4771.117343291502;4780.378550788375;5598.86002849932;5599.356074051536;5608.298962629671;5629.029839556204;5662.5753068520025;1466.332818567549;1474.5060404764524;1493.11905975475;1495.7831114488338;1504.977425338599;962.6715133116255;966.6278482784179;966.8163049020663;969.345691658128;970.5098925042809;4676.392069031072;4831.396075490564;4853.815782897639;4883.316335713003;4888.9755551222015;5155.381354237516;5176.767045482887;5182.274481572583;5185.766640501187;5186.740662966837;4771.33201351811;4826.541478559531;4833.685810422311;5148.30497882781;5200.843974680765;3036.1802313375265;3065.831379274117;3066.923326916845;3071.43178511976;3093.7370323522305;5936.5844390892225;5960.5827592068135;5974.372690867396;5976.910346344808;5978.432048955342;5612.85967850807;5843.474087332161;5853.590248682552;5895.852603684943;5913.438223368773;5985.970070149709;6008.759649614054;6028.216331876828;6033.276006879115;6043.45608895184;5665.801696940752;5819.76720931175;5834.907499025523;5844.12338149281;5851.327097383855;5732.656040637575;5756.7409466574945;5762.625429347079;5767.279090113736;5782.739776710142;5930.34766175729;5967.283925928917;5970.373110823301;5978.432048955342;5994.100884867305;3828.5405751310022;4053.817578208929;4099.405586190045;4100.876973690794;4105.973506623344;5727.198192253005;5758.193852860687;5812.18629391877;5825.737841797214;5859.740155866509;5362.544183744425;5684.914726279192;5690.918725442153;5714.542746618994;5714.942850571608;5581.469760372758;5611.8877622448135;5613.522850557748;5613.747381367766;5625.943740562542;5495;5522.22688823573;5544.251526043932;5552.694601796778;5581.49766521015;5344.839654810362;5621.887562248814;5623.803166889139;5661;5690.743916523684;5485.6434331768305;5490.46267045228;5490.835274941598;5503.944960550503;5523.337199536081 19:28:41 INFO - PROCESS | 3640 | |5;yui.html;739.7965559471145;780.009388077142;797.8882956386103;819.5082950229861;870.3037569944044;15503.224838758058;15634.218289085542;15952.680946381066;15984.680306393866;16009.91995040026;14724.926375368135;14838.703225935475;14841;14852.48016319429;14854;672.8654269146166;795.3001358804273;810.5622963599678;812.305478815613;812.5409143833715;799.2447137455107;804.4127786715701;806.2783808491397;807.3460496997421;809.5709274084716;706.8868980963047;863.5639002303827;868.9739307820776;869.6521391443441;870.5342641686699;832.1137988042746;853.9957300213521;854.5086575219211;858.2018722587991;860.2601762484259;635.4693830651412;637.2957881540877;637.4804534304527;637.5186734015834;637.7257779154961;15017.24913754319;15036.097834129878;15052.623684407868;15082.849171508255;15144.621384465367;14025.228612426312;14242.632348989235;14296.213708246043;14315.212663303515;14391.856081439157;12639.178453400591;12918.224906505546;12944.223346599234;13122.60632181036;13146.145500542434;4459.130469558438;4468.5531446855375;4489.281714925629;4539.228331183674;4554.407926969506;70534.64732676354;71006.28993710049;71107.64446177788;71157.2884271161;71356.28643713548;11450.885491145149;11601.477933493014;11619.186656934102;11661.941690291495;11704.590339338083;21583.67624485634;21598.13607455684;21716.67424988595;21718.23986160477;21779.67330490044;8471.602185639424;8472.110428405053;8490;8513.404061715744;8720.30237580992;4487.41663583732;4529.682922195415;4693.319468677023;4756.9762151189025;4772.856814295576;3392.71161951232;3490.3891818931584;3494.423420135649;3495.685388315013;3503.8423270952867;533.6211289984077;623.1276213301323;643.5462998585986;662.9535932484777;677.1265068062243;633.7496688807895;680.6052489556046;760.4068826315482;775.6819703921365;779.672537534237;9578.56896439662;9767.609295628237;9796.461194634363;9852.113309802153;9887.159591434809;688.9965550172217;708.2987842036347;722.5700708078684;734.3024127079296;743.7768669399159;4560.179167749837;4675.228587283128;4705.153072446992;4790.616750659939;4842.83050093245;86158.56920715356;120652;121070;125374;126696;105511;105901;106244.46877765562;106715;108265;91476;92220;92412.53112282076;93406;97264;8995.865062023944;9225.169734723908;9235.445873247627;9241.260699143917;9283.39657922233;54876.45123548713;54951.17573236325;55435.722821385636;55641;57445;45880.77059614681;46097.30854037259;60730.39269607247;80970;83367.5831620838;12786.68033299175;12892.548760793326;12958.22250664963;13020.173083257427;13185.868141318464;13780;14129.858701412853;14177.22025288619;14560.641962525873;14689.853101468849;11848.899809226992;11951.163418560618;12071.456784444545;12393.876061239273;12395.070369722127;11503.53985840557;11579.189456738284;11874.287542747463;11958.880411195776;13423.664408389868;11830.46805127675;11874.228175168586;11946.522139114339;11951.521939122686;12128.696782580506;9703.757406064904;9748.463834489172;9753.634491171384;9776.951115244377;10041.49792510357;9354.204892584206;9706.272029597949;10439.425831579336;10460.91403036091;10516.421596812248 19:28:41 INFO - PROCESS | 3640 | __end_tp_report 19:28:41 INFO - PROCESS | 3640 | __start_cc_report 19:28:41 INFO - PROCESS | 3640 | _x_x_mozilla_cycle_collect,1129 19:28:41 INFO - PROCESS | 3640 | __end_cc_report 19:28:41 INFO - PROCESS | 3640 | __startTimestamp1464834521473__endTimestamp 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | ------- Summary: start ------- 19:28:41 INFO - PROCESS | 3640 | Number of tests: 6 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | [#0] dojo.html Cycles:165 Average:5487.51 Median:5722.26 stddev:642.88 (11.2%) stddev-sans-first:617.75 19:28:41 INFO - PROCESS | 3640 | Values: 3132.8 3165.3 3174.3 3203.0 3225.9 5355.7 5424.9 5448.2 5556.5 5583.1 5439.8 5454.1 5501.5 5555.4 5612.2 4927.6 4971.7 4995.7 5003.6 5176.5 5066.9 5113.2 5136.9 5148.0 5160.7 4861.5 4934.4 4937.1 4993.1 4995.9 4937.2 4970.3 4971.4 5012.4 5021.8 4922.7 4938.5 4964.4 5027.7 5077.4 5513.7 5552.5 5557.0 5565.1 5570.3 5667.6 5869.0 5923.3 5928.8 6025.1 6001.3 6005.4 6027.9 6046.0 6064.6 5273.9 5611.7 5623.0 5626.4 5630.7 5975.3 6022.8 6027.8 6101.3 6111.0 5860.3 5888.1 5900.8 5909.4 5912.1 5701.8 5837.6 5906.1 5952.4 5966.9 5853.9 5908.7 5911.5 5912.5 5966.2 5883.6 5918.7 5928.2 5931.5 5934.5 4293.6 4436.3 4457.0 4465.9 4477.7 3998.8 4012.4 4020.3 4052.1 4058.1 5613.1 5653.3 5668.1 5669.0 5723.3 5777.2 5810.4 5834.1 5871.5 6058.9 5530.3 5552.0 5552.1 5554.4 5556.0 4921.6 4925.1 4931.6 4935.9 4975.0 5873.8 6095.5 6131.9 6133.8 6140.8 5984.1 5990.4 5997.8 5999.8 6037.9 5885.1 6157.4 6172.5 6173.5 6174.1 5812.7 5830.4 5856.4 5872.9 5881.0 5930.0 5975.1 5983.7 5985.3 5990.5 5525.6 5761.1 5850.0 5876.1 5879.4 5778.5 5815.2 5816.8 5828.9 5866.8 5727.3 5733.7 5734.8 5740.0 5749.7 5618.9 5782.2 5810.2 5832.7 5895.7 5439.8 5475.1 5628.7 5691.9 5722.3 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | [#1] ext.html Cycles:180 Average:62089.03 Median:42249.37 stddev:125791.96 (297.7%) stddev-sans-first:126060.06 19:28:41 INFO - PROCESS | 3640 | Values: 580.5 612.2 617.8 625.7 649.6 32101.8 32624.8 43599.3 44625.6 44684.8 42653.1 46716.3 46776.0 46832.3 46837.0 8626.5 8795.2 8799.1 8802.3 8802.9 8632.0 8751.4 8773.3 8775.9 8782.1 8778.0 8792.4 8825.0 8831.9 8838.5 8741.3 8765.4 8778.1 8782.1 8787.1 8794.0 8810.2 8900.7 8902.5 8906.1 42827.4 43398.1 43420.3 43444.0 43529.6 3437.1 3964.1 4058.2 4110.8 4246.6 32674.7 32953.8 33357.3 33678.3 33854.5 3883.2 3896.6 3902.7 3959.1 4025.9 262495.0 367304.0 369901.0 370626.0 371075.0 62046.0 62826.4 62905.0 63081.7 63106.7 35880.8 36537.8 36583.0 65871.7 65999.7 17085.3 17107.6 17190.8 17227.3 17237.5 12871.4 12951.1 12952.7 12959.6 12961.2 20316.9 20399.4 20445.0 20449.3 20465.3 7248.9 7261.7 7264.4 7265.9 7286.4 1016.9 1098.7 1109.2 1127.4 1169.6 32508.5 32978.2 33038.7 33185.2 33286.0 1127.6 1130.9 1175.3 1192.1 1212.0 17416.7 17500.4 17557.1 17575.4 17671.7 592872.0 593711.0 596234.0 596654.0 600867.0 58741.1 59279.7 59344.0 59400.4 59493.4 1245.1 1349.2 1353.8 1353.8 1384.1 32908.7 33766.3 34323.7 34535.0 34628.3 50337.2 50357.0 50996.0 51033.0 51041.7 436410.0 437400.0 439980.0 440769.0 440984.0 24533.4 28008.3 28473.0 29544.4 29727.1 49292.0 49348.8 49763.8 50637.5 51234.0 41744.6 42364.6 42384.8 43245.1 43858.6 41668.0 41888.4 42134.4 42338.6 42763.8 41613.2 41629.0 42160.2 42516.4 42527.0 35309.0 36979.8 37126.4 37779.0 37842.9 27652.7 28157.0 28192.3 28261.7 28374.9 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | [#2] jquery.html Cycles:180 Average:28322.26 Median:23402.21 stddev:79890.36 (341.4%) stddev-sans-first:80086.92 19:28:41 INFO - PROCESS | 3640 | Values: 376.4 388.0 394.2 403.4 411.2 17809.8 18609.3 20574.9 20598.8 20637.8 17431.1 17766.3 18162.4 18219.2 18239.5 5062.1 5067.6 5167.7 5173.8 5222.8 3776.9 5410.1 5610.4 5610.9 5729.7 5630.3 5690.1 5696.6 5706.2 5725.4 6242.1 6420.3 6429.9 6432.0 6440.1 5485.6 5530.9 5555.1 5691.2 5729.2 12421.7 12705.5 12848.4 12910.9 12926.0 2239.8 2285.5 2344.1 2405.8 2522.5 5940.7 5959.9 5968.9 9540.1 9549.2 1897.7 1907.2 1944.8 1962.4 1977.0 79999.6 80391.2 82621.0 84200.6 85814.0 10272.4 10311.2 10370.1 10407.9 10562.6 13764.2 13783.7 13834.3 13843.2 13847.0 7711.3 7784.5 7944.6 7972.1 8102.5 7878.4 7999.3 8033.8 8153.9 8172.1 4189.4 4289.8 4336.5 4541.4 4564.1 1710.6 1826.8 1838.5 1926.0 1994.4 916.9 987.0 1032.7 1070.9 1100.5 18598.2 18990.5 19131.8 19308.0 19648.4 972.1 1001.9 1021.7 1033.9 1038.6 10395.2 10876.6 10894.8 10927.6 10937.8 468314.0 483689.0 486418.0 486913.0 491414.0 85521.0 88656.6 89680.6 96554.5 98571.5 966.1 972.9 974.8 976.1 983.3 16673.0 16855.9 16879.5 17055.4 17360.0 42615.0 42759.4 42873.8 42885.6 42900.1 53712.2 54000.0 56176.2 63969.7 64331.7 7511.7 7595.7 7646.8 7742.8 7870.9 11136.2 13209.3 13214.2 13450.4 13558.5 6564.6 6662.0 9846.4 11968.5 12288.1 11085.6 11516.7 11593.1 11652.0 11741.0 10867.1 11644.3 12168.4 12306.6 12407.3 8398.4 8533.9 8542.5 8604.5 8653.0 10603.3 11848.6 12152.5 12172.3 12202.0 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | [#3] mootools.html Cycles:180 Average:5903.48 Median:448.11 stddev:3471.01 (774.6%) stddev-sans-first:3463.49 19:28:41 INFO - PROCESS | 3640 | Values: 1296.9 1426.7 1486.8 1607.9 1791.1 8459.7 8863.7 8913.2 9180.8 9195.7 8892.6 9038.1 9069.9 9074.3 9123.4 2032.8 2265.5 2324.0 2349.9 2361.3 2250.6 2254.3 2257.0 2260.9 2262.8 2300.8 2319.4 2331.9 2335.3 2335.6 1988.8 2094.8 2158.1 2158.6 2257.3 2299.4 2306.4 2322.0 2328.1 2331.0 9054.8 9201.8 9204.9 9212.4 9221.3 4948.9 5092.6 5095.6 5102.4 5103.0 3170.9 3266.9 3310.3 3342.2 3377.7 1049.3 1068.6 1073.9 1077.4 1094.6 12693.1 12696.3 12718.0 12720.7 12730.6 4447.6 4544.6 4567.9 4585.7 4690.0 10420.4 10436.9 10446.3 10446.4 10451.5 5188.2 5233.8 5234.4 5299.0 5305.3 4281.6 4527.5 4589.4 4623.3 4687.8 3858.6 3956.9 3957.2 3980.3 4037.8 1186.0 1260.8 1268.9 1269.2 1288.8 954.3 958.7 962.8 963.7 985.6 6125.3 6126.8 6204.4 6236.9 6389.4 433.3 438.6 443.3 447.8 448.4 4138.2 4257.3 4257.5 4282.3 4311.0 11095.2 11421.6 11427.9 11441.0 11473.4 10739.6 11175.1 11188.3 11260.3 11485.8 8281.5 8350.9 8404.3 8414.3 8415.7 5694.7 5858.8 5915.3 5923.8 5946.5 10295.1 10583.8 10592.7 10642.1 10655.4 10459.4 10694.6 10768.2 10906.7 10914.2 7071.2 7611.2 7787.3 7891.1 8085.4 8851.1 8870.5 8889.9 8927.6 8935.8 7550.2 7818.1 7869.0 7875.7 7960.2 7700.0 7717.1 7803.6 7993.0 8016.6 7351.5 7403.3 7405.3 7432.5 7441.0 5065.5 5193.5 5363.1 5418.9 5494.7 5169.7 5395.1 5538.8 5661.2 5668.8 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | [#4] prototype.html Cycles:180 Average:4695.07 Median:5276.62 stddev:1370.17 (26.0%) stddev-sans-first:1334.20 19:28:41 INFO - PROCESS | 3640 | Values: 326.7 330.5 337.9 342.2 343.3 5318.9 5366.6 5371.7 5374.7 5389.4 5249.2 5304.0 5306.3 5320.9 5330.7 3932.7 4218.6 4231.7 4239.0 4239.9 4152.8 4158.7 4168.9 4173.0 4207.2 4122.9 4134.6 4141.6 4143.9 4163.5 3986.3 4037.0 4164.5 4181.8 4214.7 3688.7 3701.4 3707.5 3715.0 3719.0 5097.4 5125.9 5127.5 5165.6 5171.8 4607.5 4675.9 4851.9 4854.2 4867.5 5490.8 5536.3 5579.7 5587.3 5595.6 4046.2 4046.8 4066.8 4094.5 4112.4 5692.8 5802.8 5803.2 5813.2 5832.3 4180.7 4210.9 4211.4 4216.3 4218.9 4292.8 4308.0 4311.6 4320.9 4340.4 4540.0 4710.2 4768.5 4771.1 4780.4 5598.9 5599.4 5608.3 5629.0 5662.6 1466.3 1474.5 1493.1 1495.8 1505.0 962.7 966.6 966.8 969.3 970.5 4676.4 4831.4 4853.8 4883.3 4889.0 5155.4 5176.8 5182.3 5185.8 5186.7 4771.3 4826.5 4833.7 5148.3 5200.8 3036.2 3065.8 3066.9 3071.4 3093.7 5936.6 5960.6 5974.4 5976.9 5978.4 5612.9 5843.5 5853.6 5895.9 5913.4 5986.0 6008.8 6028.2 6033.3 6043.5 5665.8 5819.8 5834.9 5844.1 5851.3 5732.7 5756.7 5762.6 5767.3 5782.7 5930.3 5967.3 5970.4 5978.4 5994.1 3828.5 4053.8 4099.4 4100.9 4106.0 5727.2 5758.2 5812.2 5825.7 5859.7 5362.5 5684.9 5690.9 5714.5 5714.9 5581.5 5611.9 5613.5 5613.7 5625.9 5495.0 5522.2 5544.3 5552.7 5581.5 5344.8 5621.9 5623.8 5661.0 5690.7 5485.6 5490.5 5490.8 5503.9 5523.3 19:28:41 INFO - PROCESS | 3640 | 19:28:41 INFO - PROCESS | 3640 | [#5] yui.html Cycles:180 Average:20752.81 Median:4731.06 stddev:30676.58 (648.4%) stddev-sans-first:30725.83 19:28:41 INFO - PROCESS | 3640 | Values: 739.8 780.0 797.9 819.5 870.3 15503.2 15634.2 15952.7 15984.7 16009.9 14724.9 14838.7 14841.0 14852.5 14854.0 672.9 795.3 810.6 812.3 812.5 799.2 804.4 806.3 807.3 809.6 706.9 863.6 869.0 869.7 870.5 832.1 854.0 854.5 858.2 860.3 635.5 637.3 637.5 637.5 637.7 15017.2 15036.1 15052.6 15082.8 15144.6 14025.2 14242.6 14296.2 14315.2 14391.9 12639.2 12918.2 12944.2 13122.6 13146.1 4459.1 4468.6 4489.3 4539.2 4554.4 70534.6 71006.3 71107.6 71157.3 71356.3 11450.9 11601.5 11619.2 11661.9 11704.6 21583.7 21598.1 21716.7 21718.2 21779.7 8471.6 8472.1 8490.0 8513.4 8720.3 4487.4 4529.7 4693.3 4757.0 4772.9 3392.7 3490.4 3494.4 3495.7 3503.8 533.6 623.1 643.5 663.0 677.1 633.7 680.6 760.4 775.7 779.7 9578.6 9767.6 9796.5 9852.1 9887.2 689.0 708.3 722.6 734.3 743.8 4560.2 4675.2 4705.2 4790.6 4842.8 86158.6 120652.0 121070.0 125374.0 126696.0 105511.0 105901.0 106244.5 106715.0 108265.0 91476.0 92220.0 92412.5 93406.0 97264.0 8995.9 9225.2 9235.4 9241.3 9283.4 54876.5 54951.2 55435.7 55641.0 57445.0 45880.8 46097.3 60730.4 80970.0 83367.6 12786.7 12892.5 12958.2 13020.2 13185.9 13780.0 14129.9 14177.2 14560.6 14689.9 11848.9 11951.2 12071.5 12393.9 12395.1 11503.5 11579.2 11874.3 11958.9 13423.7 11830.5 11874.2 11946.5 11951.5 12128.7 9703.8 9748.5 9753.6 9777.0 10041.5 9354.2 9706.3 10439.4 10460.9 10516.4 19:28:41 INFO - PROCESS | 3640 | -------- Summary: end -------- 19:28:41 INFO - PROCESS | 3640 | 19:28:42 INFO - TEST-INFO | 3640: exit 0 19:28:42 INFO - TEST-OK | dromaeo_css | took 1113927ms 19:28:42 INFO - TEST-START | kraken 19:28:42 INFO - operating with platform_type : linux_ 19:28:42 INFO - Initialising browser for kraken test... 19:28:42 INFO - TEST-INFO | started process 3827 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfuQuM2/profile http://localhost:45175/getInfo.html) 19:28:46 INFO - PROCESS | 3827 | __metrics Screen width/height:1600/1200 19:28:46 INFO - PROCESS | 3827 | colorDepth:24 19:28:46 INFO - PROCESS | 3827 | Browser inner width/height: 1024/697 19:28:46 INFO - PROCESS | 3827 | __metrics 19:28:47 ERROR - PROCESS | 3827 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:28:48 INFO - TEST-INFO | 3827: exit 0 19:28:48 INFO - Browser initialized. 19:28:48 INFO - Running cycle 1/1 for kraken test... 19:28:48 INFO - TEST-INFO | started process 3908 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfuQuM2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:29:00 INFO - PROCESS | 3908 | RSS: Main: 156508160 19:29:00 INFO - PROCESS | 3908 | 19:29:02 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45175/tests/kraken/driver.html?testName=audio-beat-detection) 19:29:03 INFO - PROCESS | 3908 | RSS: Main: 215048192 19:29:03 INFO - PROCESS | 3908 | 19:29:06 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45175/tests/kraken/driver.html?testName=audio-dft) 19:29:06 INFO - PROCESS | 3908 | RSS: Main: 197087232 19:29:06 INFO - PROCESS | 3908 | 19:29:09 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45175/tests/kraken/driver.html?testName=audio-fft) 19:29:10 INFO - PROCESS | 3908 | RSS: Main: 268865536 19:29:10 INFO - PROCESS | 3908 | 19:29:12 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45175/tests/kraken/driver.html?testName=audio-oscillator) 19:29:13 INFO - PROCESS | 3908 | RSS: Main: 179277824 19:29:13 INFO - PROCESS | 3908 | 19:29:15 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45175/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:29:15 INFO - PROCESS | 3908 | RSS: Main: 183685120 19:29:15 INFO - PROCESS | 3908 | 19:29:18 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45175/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:19 INFO - PROCESS | 3908 | RSS: Main: 219340800 19:29:19 INFO - PROCESS | 3908 | 19:29:22 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45175/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:23 INFO - PROCESS | 3908 | RSS: Main: 219734016 19:29:23 INFO - PROCESS | 3908 | 19:29:26 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45175/tests/kraken/driver.html?testName=json-parse-financial) 19:29:26 INFO - PROCESS | 3908 | RSS: Main: 220553216 19:29:26 INFO - PROCESS | 3908 | 19:29:28 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45175/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:29 INFO - PROCESS | 3908 | RSS: Main: 206041088 19:29:29 INFO - PROCESS | 3908 | 19:29:30 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:31 INFO - PROCESS | 3908 | RSS: Main: 210231296 19:29:31 INFO - PROCESS | 3908 | 19:29:33 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:34 INFO - PROCESS | 3908 | RSS: Main: 217186304 19:29:34 INFO - PROCESS | 3908 | 19:29:36 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:37 INFO - PROCESS | 3908 | RSS: Main: 222597120 19:29:37 INFO - PROCESS | 3908 | 19:29:40 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:40 INFO - PROCESS | 3908 | RSS: Main: 221548544 19:29:40 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:45175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45175/tests/kraken/driver.html?testName=ai-astar) 19:29:42 INFO - PROCESS | 3908 | RSS: Main: 263053312 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | __start_tp_report 19:29:42 INFO - PROCESS | 3908 | _x_x_mozilla_page_load 19:29:42 INFO - PROCESS | 3908 | _x_x_mozilla_page_load_details 19:29:42 INFO - PROCESS | 3908 | |i|pagename|runs| 19:29:42 INFO - PROCESS | 3908 | |0;ai-astar;112;105;113;110;112;111;111;109;112;111 19:29:42 INFO - PROCESS | 3908 | |1;audio-beat-detection;148;126;127;188;128;127;152;126;154;124 19:29:42 INFO - PROCESS | 3908 | |2;audio-dft;160;161;162;160;163;163;163;161;164;162 19:29:42 INFO - PROCESS | 3908 | |3;audio-fft;74;75;75;126;76;75;74;75;102;75 19:29:42 INFO - PROCESS | 3908 | |4;audio-oscillator;83;85;109;84;83;84;119;84;84;128 19:29:42 INFO - PROCESS | 3908 | |5;imaging-gaussian-blur;117;116;116;116;116;116;117;117;117;117 19:29:42 INFO - PROCESS | 3908 | |6;imaging-darkroom;145;146;145;146;146;146;145;146;145;147 19:29:42 INFO - PROCESS | 3908 | |7;imaging-desaturate;95;96;96;95;95;95;96;95;96;96 19:29:42 INFO - PROCESS | 3908 | |8;json-parse-financial;53;52;61;53;52;51;51;52;54;53 19:29:42 INFO - PROCESS | 3908 | |9;json-stringify-tinderbox;63;63;63;62;63;63;62;63;63;62 19:29:42 INFO - PROCESS | 3908 | |10;stanford-crypto-aes;79;79;78;79;80;80;84;86;139;80 19:29:42 INFO - PROCESS | 3908 | |11;stanford-crypto-ccm;122;129;122;125;121;131;118;122;123;125 19:29:42 INFO - PROCESS | 3908 | |12;stanford-crypto-pbkdf2;157;160;165;161;160;157;159;159;157;160 19:29:42 INFO - PROCESS | 3908 | |13;stanford-crypto-sha256-iterative;63;64;63;62;63;62;64;64;62;60 19:29:42 INFO - PROCESS | 3908 | __end_tp_report 19:29:42 INFO - PROCESS | 3908 | __start_cc_report 19:29:42 INFO - PROCESS | 3908 | _x_x_mozilla_cycle_collect,1636 19:29:42 INFO - PROCESS | 3908 | __end_cc_report 19:29:42 INFO - PROCESS | 3908 | __startTimestamp1464834582543__endTimestamp 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | ------- Summary: start ------- 19:29:42 INFO - PROCESS | 3908 | Number of tests: 14 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.50 stddev:2.27 (2.0%) stddev-sans-first:2.35 19:29:42 INFO - PROCESS | 3908 | Values: 112.0 105.0 113.0 110.0 112.0 111.0 111.0 109.0 112.0 111.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:138.00 stddev:20.65 (15.0%) stddev-sans-first:21.70 19:29:42 INFO - PROCESS | 3908 | Values: 148.0 126.0 127.0 188.0 128.0 127.0 152.0 126.0 154.0 124.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.50 stddev:1.37 (0.8%) stddev-sans-first:1.27 19:29:42 INFO - PROCESS | 3908 | Values: 160.0 161.0 162.0 160.0 163.0 163.0 163.0 161.0 164.0 162.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#3] audio-fft Cycles:10 Average:82.70 Median:75.00 stddev:17.45 (23.3%) stddev-sans-first:18.22 19:29:42 INFO - PROCESS | 3908 | Values: 74.0 75.0 75.0 126.0 76.0 75.0 74.0 75.0 102.0 75.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:84.00 stddev:17.41 (20.7%) stddev-sans-first:17.98 19:29:42 INFO - PROCESS | 3908 | Values: 83.0 85.0 109.0 84.0 83.0 84.0 119.0 84.0 84.0 128.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 19:29:42 INFO - PROCESS | 3908 | Values: 117.0 116.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 19:29:42 INFO - PROCESS | 3908 | Values: 145.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 145.0 147.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 19:29:42 INFO - PROCESS | 3908 | Values: 95.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:2.90 (5.5%) stddev-sans-first:3.07 19:29:42 INFO - PROCESS | 3908 | Values: 53.0 52.0 61.0 53.0 52.0 51.0 51.0 52.0 54.0 53.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#9] json-stringify-tinderbox Cycles:10 Average:62.70 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 19:29:42 INFO - PROCESS | 3908 | Values: 63.0 63.0 63.0 62.0 63.0 63.0 62.0 63.0 63.0 62.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:80.00 stddev:18.65 (23.3%) stddev-sans-first:19.59 19:29:42 INFO - PROCESS | 3908 | Values: 79.0 79.0 78.0 79.0 80.0 80.0 84.0 86.0 139.0 80.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:124.00 stddev:3.85 (3.1%) stddev-sans-first:4.03 19:29:42 INFO - PROCESS | 3908 | Values: 122.0 129.0 122.0 125.0 121.0 131.0 118.0 122.0 123.0 125.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.50 Median:160.00 stddev:2.42 (1.5%) stddev-sans-first:2.39 19:29:42 INFO - PROCESS | 3908 | Values: 157.0 160.0 165.0 161.0 160.0 157.0 159.0 159.0 157.0 160.0 19:29:42 INFO - PROCESS | 3908 | 19:29:42 INFO - PROCESS | 3908 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.25 (2.0%) stddev-sans-first:1.32 19:29:42 INFO - PROCESS | 3908 | Values: 63.0 64.0 63.0 62.0 63.0 62.0 64.0 64.0 62.0 60.0 19:29:42 INFO - PROCESS | 3908 | -------- Summary: end -------- 19:29:42 INFO - PROCESS | 3908 | 19:29:43 INFO - TEST-INFO | 3908: exit 0 19:29:43 INFO - TEST-OK | kraken | took 61439ms 19:29:43 INFO - SUITE-END | took 1175s 19:29:43 INFO - Completed test suite (00:19:35) 19:29:43 INFO - javascript benchmark 19:29:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3132.8433578321087, 3165.256164801273, 3174.333389988102, 3202.967970320296, 3225.9193520161994, 5355.732213389325, 5424.864378390542, 5448.182772584114, 5556.472135147164, 5583.07879199932, 5439.755211015504, 5454.072807622703, 5501.5323697485765, 5555.444455554442, 5612.242347283126, 4927.556519913207, 4971.726555039471, 4995.725235112068, 5003.649744517885, 5176.5341119299255, 5066.949330506684, 5113.181890897457, 5136.922946155792, 5147.974260128694, 5160.741962901859, 4861.465238823726, 4934.358533390654, 4937.111319962405, 4993.076280888047, 4995.925061124086, 4937.23472861707, 4970.254461830718, 4971.353724015891, 5012.398512178562, 5021.799128034874, 4922.680025798311, 4938.4814594467425, 4964.429090654548, 5027.673201241908, 5077.365329333833, 5513.724313784295, 5552.472515111058, 5556.972215138939, 5565.109582466826, 5570.3315602127595, 5667.603267771257, 5869.031609784381, 5923.289205295349, 5928.7628494860155, 6025.096235564656, 6001.339852616209, 6005.429484198994, 6027.9095813562835, 6046.0, 6064.60580062299, 5273.894522109613, 5611.663300202001, 5623.0, 5626.409227031144, 5630.718464076821, 5975.342712301644, 6022.819315420545, 6027.849303767353, 6101.267847858286, 6111.022236442148, 5860.267466566679, 5888.057910734262, 5900.822975310747, 5909.379515150933, 5912.142739302863, 5701.800436984686, 5837.649741015468, 5906.055031195074, 5952.434518720715, 5966.940330596639, 5853.912191317134, 5908.734106965197, 5911.4975227105315, 5912.497437717842, 5966.194563733927, 5883.61756485827, 5918.704064796743, 5928.170056192136, 5931.525477961753, 5934.525237980951, 4293.635041021485, 4436.26801577736, 4457.04173602677, 4465.910681786346, 4477.731336119843, 3998.8400463981116, 4012.4181993611346, 4020.2763502569233, 4052.149048699806, 4058.127502586899, 5613.073842815971, 5653.321601407857, 5668.064769313017, 5669.0, 5723.284589426322, 5777.220075289866, 5810.4189581041555, 5834.124881267759, 5871.500922421642, 6058.909116363258, 5530.2810634618045, 5552.0, 5552.111662134039, 5554.416786237468, 5555.972220138873, 4921.557059864629, 4925.13810083234, 4931.556159945622, 4935.876603084952, 4974.9751251243515, 5873.794417195378, 6095.451409373178, 6131.877362452726, 6133.764122814904, 6140.846478838065, 5984.102384642339, 5990.430909063632, 5997.760089596367, 5999.820005399845, 6037.9094313584455, 5885.087811389241, 6157.415045570664, 6172.537059720449, 6173.506119510339, 6174.104754810617, 5812.709364531841, 5830.4461076197695, 5856.355800861987, 5872.911906321323, 5881.0, 5930.0, 5975.073863551156, 5983.670898100472, 5985.311689155803, 5990.460858522754, 5525.64083334598, 5761.107028410603, 5850.005499065254, 5876.08920617305, 5879.353271140257, 5778.479936805707, 5815.2149459822385, 5816.796412125555, 5828.854278643068, 5866.794662186803, 5727.341355743976, 5733.713314334183, 5734.770609175753, 5740.0, 5749.683767392834, 5618.88762224745, 5782.161586570008, 5810.186573879576, 5832.708364581838, 5895.734691938959, 5439.836804895859, 5475.09660905946, 5628.718564071862, 5691.914621280601, 5722.2561067061015], "value": 5443.065302414724, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.4775701868318, 612.1857928954491, 617.7837756785123, 625.7434451874731, 649.6329573790811, 32101.839490802544, 32624.836875815618, 43599.34600980988, 44625.553744462544, 44684.776576117074, 42653.14693706132, 46716.29925551119, 46776.0, 46832.29751553721, 46837.0, 8626.482411055342, 8795.208431241186, 8799.120087991214, 8802.295816334678, 8802.911970880305, 8631.95684021579, 8751.431156974808, 8773.254273386738, 8775.912240877573, 8782.077881822412, 8778.034416214212, 8792.384533082686, 8824.955875220616, 8831.911680883173, 8838.513881736502, 8741.30069594431, 8765.430247033923, 8778.078301778285, 8782.077881822444, 8787.0773568775, 8794.0, 8810.163034511712, 8900.732978010605, 8902.465852048897, 8906.109389061106, 42827.35758963587, 43398.13203735939, 43420.3486947696, 43444.0, 43529.564704353186, 3437.1063523483826, 3964.147708242742, 4058.228936502055, 4110.835566577333, 4246.596573325529, 32674.6732532674, 32953.83523082369, 33357.33285334304, 33678.32643347119, 33854.49218261728, 3883.2233553289175, 3896.5713771485116, 3902.687784977195, 3959.1487830116375, 4025.8993525161814, 262495.0, 367304.0, 369901.0, 370626.0, 371075.0, 62046.0, 62826.37173628296, 62905.0, 63081.68459157675, 63106.68446657829, 35880.820595896854, 36537.81731091381, 36583.0, 65871.67064164649, 65999.67000164969, 17085.31658733637, 17107.572310692332, 17190.796644234782, 17227.31090756381, 17237.482875513753, 12871.356432178354, 12951.093423460266, 12952.740945181233, 12959.611211663665, 12961.22232666043, 20316.89841550783, 20399.388018359474, 20444.955504449732, 20449.261812745786, 20465.2837150702, 7248.855022899513, 7261.673224704795, 7264.418846492268, 7265.927340726631, 7286.417086633057, 1016.9338992965434, 1098.7198264442516, 1109.2346281066039, 1127.4193790198055, 1169.649105268433, 32508.512372314435, 32978.17554561107, 33038.69613038677, 33185.170370740445, 33286.0, 1127.6278827986744, 1130.8982191602631, 1175.3183153770792, 1192.1396081695746, 1211.9515219391126, 17416.65166696659, 17500.38486941742, 17557.122143892753, 17575.384861529787, 17671.73492397615, 592872.0, 593711.0, 596234.0, 596654.0, 600867.0, 58741.11888321678, 59279.70360148171, 59344.0, 59400.40599594035, 59493.405065948784, 1245.0973044542816, 1349.230938365142, 1353.7563238616947, 1353.8375394952668, 1384.1349156776814, 32908.67091329056, 33766.3246735059, 34323.656763432045, 34535.0, 34628.30743385169, 50337.244941326644, 50357.0, 50996.0, 51033.0, 51041.74479127581, 436410.0, 437400.0, 439980.0, 440769.0, 440984.0, 24533.386665332797, 28008.29979250535, 28473.0, 29544.409111818073, 29727.10818675443, 49292.0, 49348.75325623349, 49763.75118124386, 50637.493625063275, 51234.0, 41744.58255417407, 42364.61083113282, 42384.78807605942, 43245.13509729725, 43858.56141438545, 41668.0, 41888.371674425514, 42134.36798448087, 42338.5766142347, 42763.78618107014, 41613.1677366457, 41629.0, 42160.15679686328, 42516.36225456559, 42527.0, 35309.0, 36979.8151009254, 37126.44310335293, 37779.0, 37842.918639475094, 27652.723472765014, 28157.0, 28192.29519262035, 28261.717382825907, 28374.85812570924], "value": 20386.18058594963, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3677022602029, 387.95926427725095, 394.23124906432463, 403.4069917221684, 411.19816358101707, 17809.82190178098, 18609.348672796456, 20574.89712551437, 20598.794012059876, 20637.793622063775, 17431.128443577803, 17766.28934842608, 18162.364317248866, 18219.18013689384, 18239.452816415527, 5062.114130027249, 5067.594592432597, 5167.6641018333685, 5173.793048278064, 5222.817201397952, 3776.8678096266635, 5410.1073322901675, 5610.382857885629, 5610.859728506779, 5729.684867332295, 5630.29024325768, 5690.118031705071, 5696.629719068268, 5706.1726049722765, 5725.398833122525, 6242.1261023456755, 6420.325865784095, 6429.935700643027, 6432.00303952888, 6440.066190402412, 5485.588580856452, 5530.861728456781, 5555.083411237141, 5691.203231547586, 5729.197912292282, 12421.689457763538, 12705.491780328868, 12848.35758212095, 12910.935445322808, 12926.0, 2239.7984181423585, 2285.4514916420108, 2344.1481229828414, 2405.7834794868545, 2522.495500899809, 5940.702964851784, 5959.880802383928, 5968.910466343008, 9540.141387275178, 9549.233391822536, 1897.7153426985924, 1907.160849226336, 1944.8346890514324, 1962.3622322745166, 1977.0411350494999, 79999.6000020002, 80391.19608803895, 82621.0, 84200.57899710523, 85814.0, 10272.435016074187, 10311.175105991653, 10370.11853992404, 10407.94796026015, 10562.630307939186, 13764.17414955106, 13783.724325513434, 13834.308284585932, 13843.238621875693, 13846.9614778892, 7711.344535714415, 7784.494007889469, 7944.6027698614835, 7972.123066462685, 8102.513849169069, 7878.369730421668, 7999.28006479408, 8033.7991550210545, 8153.8776918346275, 8172.141925097898, 4189.413482112506, 4289.806958686867, 4336.457942757155, 4541.3869127668, 4564.109998550317, 1710.61511159988, 1826.7807863056519, 1838.4629211687786, 1925.9984807899814, 1994.3717728915344, 916.8991410944791, 987.0, 1032.7056788815153, 1070.8661417322835, 1100.5487750022453, 18598.22132612024, 18990.52523686891, 19131.808681913004, 19308.0, 19648.410547683594, 972.0619602084023, 1001.8897921228659, 1021.7190272674925, 1033.927625066253, 1038.600138946496, 10395.16838652906, 10876.564937402416, 10894.782104357868, 10927.562897484011, 10937.781244375068, 468314.0, 483689.0, 486418.0, 486913.0, 491414.0, 85521.0, 88656.55671721729, 89680.5515972416, 96554.51722741341, 98571.50714246383, 966.1015255812022, 972.8589354543551, 974.770928831724, 976.0922342221802, 983.257640481432, 16673.0, 16855.91572042132, 16879.493615191564, 17055.403060892808, 17360.0, 42615.0, 42759.3586096215, 42873.785631071645, 42885.57114428816, 42900.14199716051, 53712.1943170845, 54000.0, 56176.15735763885, 63969.68015159894, 64331.67834160799, 7511.737089201852, 7595.696172153052, 7646.847063058596, 7742.8451430969935, 7870.921290787248, 11136.220464567727, 13209.339533023503, 13214.207147571176, 13450.394732237268, 13558.525451609146, 6564.6389448580785, 6662.000699895055, 9846.359986600848, 11968.46141923633, 12288.078394120656, 11085.612003580158, 11516.654500365003, 11593.130515211562, 11652.0, 11741.0, 10867.076298514714, 11644.301341919512, 12168.391580421121, 12306.569270075504, 12407.317597532223, 8398.370122240734, 8533.871991920001, 8542.487450752975, 8604.526751028754, 8653.0, 10603.310784798963, 11848.644540663794, 12152.453139608915, 12172.330521821385, 12202.0], "value": 8807.018772638763, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.8897643700298, 1426.6861290516083, 1486.8141482314709, 1607.8552930236276, 1791.0686443049606, 8459.746207613774, 8863.734087977364, 8913.242374398184, 9180.770480737985, 9195.724128276146, 8892.599833007514, 9038.051004644516, 9069.863952040709, 9074.319426043057, 9123.40697854639, 2032.7865574114726, 2265.546890621874, 2323.9883800581015, 2349.9295021149305, 2361.3034154924253, 2250.561140577588, 2254.278630838124, 2257.0, 2260.9321720348416, 2262.830287728427, 2300.8389412741117, 2319.373769082347, 2331.9067237310487, 2335.3344296875393, 2335.5795956727784, 1988.7613486381583, 2094.769575346726, 2158.093600687715, 2158.57907707997, 2257.345369842744, 2299.3561802695103, 2306.4003359126746, 2322.036354912709, 2328.125858683759, 2330.9627215888936, 9054.77363065922, 9201.76995575116, 9204.861927071031, 9212.447253164832, 9221.262299016129, 4948.925766113512, 5092.592592592584, 5095.643304968654, 5102.413222479426, 5103.030424219423, 3170.8573114209685, 3266.852991615383, 3310.2717402171484, 3342.2145795737983, 3377.696007359325, 1049.3494033699078, 1068.6206396729206, 1073.8550295710056, 1077.4289626497969, 1094.6114129484, 12693.11148219625, 12696.301703406309, 12718.0, 12720.681982950407, 12730.618081457478, 4447.577480139382, 4544.636429085665, 4567.9086418271445, 4585.72485650862, 4690.038542098861, 10420.426876521713, 10436.947815261026, 10446.320989135682, 10446.373217606968, 10451.529681164366, 5188.169892817131, 5233.842984710464, 5234.371875374979, 5299.046171689133, 5305.257263983045, 4281.571842815756, 4527.547245275512, 4589.380433641416, 4623.260278355447, 4687.835925742651, 3858.575556688762, 3956.9208615827524, 3957.248122856648, 3980.2636512245326, 4037.7577345359373, 1185.974132375489, 1260.8085359335523, 1268.8921441677376, 1269.2130878855169, 1288.8195652608642, 954.289054654287, 958.6644674364056, 962.7929995050957, 963.691618682029, 985.6205360935948, 6125.264968203844, 6126.816195514141, 6204.441600255908, 6236.906924091278, 6389.424951754364, 433.34348462079976, 438.64031494175106, 443.29737366274173, 447.8343013085179, 448.3767563087311, 4138.213739389506, 4257.340112282601, 4257.467816522934, 4282.250606143913, 4311.0, 11095.167862410352, 11421.600243991421, 11427.942860285646, 11441.0, 11473.368964706855, 10739.570417183382, 11175.050120739828, 11188.328700278009, 11260.32438053703, 11485.770284594262, 8281.503109813431, 8350.874736878952, 8404.285635721033, 8414.326853851677, 8415.747527574182, 5694.686792226467, 5858.794942177004, 5915.260592425947, 5923.822285331447, 5946.548771259018, 10295.07344339013, 10583.788324233317, 10592.735181620214, 10642.095421888916, 10655.413952232702, 10459.424731639832, 10694.62568810088, 10768.19238557127, 10906.672799816019, 10914.181436392144, 7071.186813516306, 7611.238876112345, 7787.29914307715, 7891.131975482577, 8085.353171746128, 8851.114888511098, 8870.467771933705, 8889.911100888909, 8927.553622318988, 8935.821283574422, 7550.152882392292, 7818.10091839423, 7868.960655196687, 7875.684972601031, 7960.243776841191, 7699.961500192463, 7717.073951125901, 7803.648835802516, 7993.0, 8016.6392512335915, 7351.522151060164, 7403.296686814744, 7405.296496832792, 7432.516886402366, 7441.032665753418, 5065.468125846733, 5193.506616871391, 5363.115086010765, 5418.945810542002, 5494.670319780826, 5169.663971841818, 5395.136778115482, 5538.833834984957, 5661.207430959751, 5668.801591944427], "value": 4579.745979300604, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.7059646318313, 330.540548637394, 337.8817413905133, 342.23168985627285, 343.2928167973972, 5318.9202161967605, 5366.551031221567, 5371.650842695222, 5374.677519348842, 5389.434109418514, 5249.238860365244, 5304.0, 5306.336707911511, 5320.90921361121, 5330.653507522008, 3932.7050471214775, 4218.557051509593, 4231.661467082641, 4239.0, 4239.851605193819, 4152.750834949913, 4158.688098392618, 4168.916621667565, 4172.958270417287, 4207.242696314662, 4122.876313710579, 4134.607212314825, 4141.565135660758, 4143.917121657565, 4163.458750362449, 3986.282469155565, 4037.0, 4164.5215948338355, 4181.832726690929, 4214.747115173069, 3688.7458264190036, 3701.407774756026, 3707.4809526666018, 3714.981425092857, 3719.0, 5097.413797413271, 5125.871853203663, 5127.461616530248, 5165.605286572604, 5171.818986335498, 4607.493175750665, 4675.859724208279, 4851.878703032417, 4854.199057155565, 4867.464578896319, 5490.752916118784, 5536.339098270502, 5579.69311687857, 5587.3015873015875, 5595.63628364155, 4046.150308435232, 4046.846648705134, 4066.8169932353117, 4094.467719196485, 4112.362583799516, 5692.829215123553, 5802.7794574011405, 5803.187553742479, 5813.1979086483325, 5832.270966129234, 4180.707350485498, 4210.947063379026, 4211.410402543646, 4216.325387937915, 4218.915621687549, 4292.806823692878, 4307.978460107679, 4311.590398912098, 4320.870373888788, 4340.370646256339, 4540.0023989444535, 4710.175719249118, 4768.451628062817, 4771.117343291502, 4780.378550788375, 5598.86002849932, 5599.356074051536, 5608.298962629671, 5629.029839556204, 5662.5753068520025, 1466.332818567549, 1474.5060404764524, 1493.11905975475, 1495.7831114488338, 1504.977425338599, 962.6715133116255, 966.6278482784179, 966.8163049020663, 969.345691658128, 970.5098925042809, 4676.392069031072, 4831.396075490564, 4853.815782897639, 4883.316335713003, 4888.9755551222015, 5155.381354237516, 5176.767045482887, 5182.274481572583, 5185.766640501187, 5186.740662966837, 4771.33201351811, 4826.541478559531, 4833.685810422311, 5148.30497882781, 5200.843974680765, 3036.1802313375265, 3065.831379274117, 3066.923326916845, 3071.43178511976, 3093.7370323522305, 5936.5844390892225, 5960.5827592068135, 5974.372690867396, 5976.910346344808, 5978.432048955342, 5612.85967850807, 5843.474087332161, 5853.590248682552, 5895.852603684943, 5913.438223368773, 5985.970070149709, 6008.759649614054, 6028.216331876828, 6033.276006879115, 6043.45608895184, 5665.801696940752, 5819.76720931175, 5834.907499025523, 5844.12338149281, 5851.327097383855, 5732.656040637575, 5756.7409466574945, 5762.625429347079, 5767.279090113736, 5782.739776710142, 5930.34766175729, 5967.283925928917, 5970.373110823301, 5978.432048955342, 5994.100884867305, 3828.5405751310022, 4053.817578208929, 4099.405586190045, 4100.876973690794, 4105.973506623344, 5727.198192253005, 5758.193852860687, 5812.18629391877, 5825.737841797214, 5859.740155866509, 5362.544183744425, 5684.914726279192, 5690.918725442153, 5714.542746618994, 5714.942850571608, 5581.469760372758, 5611.8877622448135, 5613.522850557748, 5613.747381367766, 5625.943740562542, 5495.0, 5522.22688823573, 5544.251526043932, 5552.694601796778, 5581.49766521015, 5344.839654810362, 5621.887562248814, 5623.803166889139, 5661.0, 5690.743916523684, 5485.6434331768305, 5490.46267045228, 5490.835274941598, 5503.944960550503, 5523.337199536081], "value": 4263.044888753723, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.7965559471145, 780.009388077142, 797.8882956386103, 819.5082950229861, 870.3037569944044, 15503.224838758058, 15634.218289085542, 15952.680946381066, 15984.680306393866, 16009.91995040026, 14724.926375368135, 14838.703225935475, 14841.0, 14852.48016319429, 14854.0, 672.8654269146166, 795.3001358804273, 810.5622963599678, 812.305478815613, 812.5409143833715, 799.2447137455107, 804.4127786715701, 806.2783808491397, 807.3460496997421, 809.5709274084716, 706.8868980963047, 863.5639002303827, 868.9739307820776, 869.6521391443441, 870.5342641686699, 832.1137988042746, 853.9957300213521, 854.5086575219211, 858.2018722587991, 860.2601762484259, 635.4693830651412, 637.2957881540877, 637.4804534304527, 637.5186734015834, 637.7257779154961, 15017.24913754319, 15036.097834129878, 15052.623684407868, 15082.849171508255, 15144.621384465367, 14025.228612426312, 14242.632348989235, 14296.213708246043, 14315.212663303515, 14391.856081439157, 12639.178453400591, 12918.224906505546, 12944.223346599234, 13122.60632181036, 13146.145500542434, 4459.130469558438, 4468.5531446855375, 4489.281714925629, 4539.228331183674, 4554.407926969506, 70534.64732676354, 71006.28993710049, 71107.64446177788, 71157.2884271161, 71356.28643713548, 11450.885491145149, 11601.477933493014, 11619.186656934102, 11661.941690291495, 11704.590339338083, 21583.67624485634, 21598.13607455684, 21716.67424988595, 21718.23986160477, 21779.67330490044, 8471.602185639424, 8472.110428405053, 8490.0, 8513.404061715744, 8720.30237580992, 4487.41663583732, 4529.682922195415, 4693.319468677023, 4756.9762151189025, 4772.856814295576, 3392.71161951232, 3490.3891818931584, 3494.423420135649, 3495.685388315013, 3503.8423270952867, 533.6211289984077, 623.1276213301323, 643.5462998585986, 662.9535932484777, 677.1265068062243, 633.7496688807895, 680.6052489556046, 760.4068826315482, 775.6819703921365, 779.672537534237, 9578.56896439662, 9767.609295628237, 9796.461194634363, 9852.113309802153, 9887.159591434809, 688.9965550172217, 708.2987842036347, 722.5700708078684, 734.3024127079296, 743.7768669399159, 4560.179167749837, 4675.228587283128, 4705.153072446992, 4790.616750659939, 4842.83050093245, 86158.56920715356, 120652.0, 121070.0, 125374.0, 126696.0, 105511.0, 105901.0, 106244.46877765562, 106715.0, 108265.0, 91476.0, 92220.0, 92412.53112282076, 93406.0, 97264.0, 8995.865062023944, 9225.169734723908, 9235.445873247627, 9241.260699143917, 9283.39657922233, 54876.45123548713, 54951.17573236325, 55435.722821385636, 55641.0, 57445.0, 45880.77059614681, 46097.30854037259, 60730.39269607247, 80970.0, 83367.5831620838, 12786.68033299175, 12892.548760793326, 12958.22250664963, 13020.173083257427, 13185.868141318464, 13780.0, 14129.858701412853, 14177.22025288619, 14560.641962525873, 14689.853101468849, 11848.899809226992, 11951.163418560618, 12071.456784444545, 12393.876061239273, 12395.070369722127, 11503.53985840557, 11579.189456738284, 11874.287542747463, 11958.880411195776, 13423.664408389868, 11830.46805127675, 11874.228175168586, 11946.522139114339, 11951.521939122686, 12128.696782580506, 9703.757406064904, 9748.463834489172, 9753.634491171384, 9776.951115244377, 10041.49792510357, 9354.204892584206, 9706.272029597949, 10439.425831579336, 10460.91403036091, 10516.421596812248], "value": 7490.06678697612, "unit": "score"}], "name": "dromaeo_css", "value": 7230.749855557253, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 113.0, 110.0, 112.0, 111.0, 111.0, 109.0, 112.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 126.0, 127.0, 188.0, 128.0, 127.0, 152.0, 126.0, 154.0, 124.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 160.0, 163.0, 163.0, 163.0, 161.0, 164.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 126.0, 76.0, 75.0, 74.0, 75.0, 102.0, 75.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 109.0, 84.0, 83.0, 84.0, 119.0, 84.0, 84.0, 128.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 61.0, 53.0, 52.0, 51.0, 51.0, 52.0, 54.0, 53.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 63.0, 62.0], "value": 62.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 79.0, 80.0, 80.0, 84.0, 86.0, 139.0, 80.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 129.0, 122.0, 125.0, 121.0, 131.0, 118.0, 122.0, 123.0, 125.0], "value": 123.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 160.0, 165.0, 161.0, 160.0, 157.0, 159.0, 159.0, 157.0, 160.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 62.0, 63.0, 62.0, 64.0, 64.0, 62.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1495.5000000000002, "alertThreshold": 2.0}]} 19:29:43 INFO - Return code: 0 19:29:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:29:43 ERROR - # TBPL SUCCESS # 19:29:43 INFO - Running post-action listener: _resource_record_post_action 19:29:43 INFO - ##### 19:29:43 INFO - ##### Finished run-tests step (success) 19:29:43 INFO - ##### 19:29:43 INFO - Running post-run listener: _resource_record_post_run 19:29:43 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 540876800; Read time: 0; Write time: 664844 19:29:43 INFO - TinderboxPrint: CPU usage
13.2% 19:29:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:29:43 INFO - TinderboxPrint: I/O write bytes / time
540,876,800 / 664,844 19:29:43 INFO - TinderboxPrint: CPU idle
8,264.2 (86.8%) 19:29:43 INFO - TinderboxPrint: CPU user
1,209.8 (12.7%) 19:29:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:29:43 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 251043840; Read time: 0; Write time: 309532 19:29:43 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 289832960; Read time: 0; Write time: 355312 19:29:44 INFO - Running post-run listener: _upload_blobber_files 19:29:44 INFO - Blob upload gear active. 19:29:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:44 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 19:29:44 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'] 19:29:44 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 19:29:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:29:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:45 INFO - (blobuploader) - INFO - Done attempting. 19:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:29:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:46 INFO - (blobuploader) - INFO - Done attempting. 19:29:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:29:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:29:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:52 INFO - (blobuploader) - INFO - Done attempting. 19:29:52 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:52 INFO - Return code: 0 19:29:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9abe0df90f56f194287d6a2081fd6d809eed685942692d4be769fa7f83d436013e26c8822dd0b6f97e96209f128014296e2a7cd6b561af44924637b50f5ef7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/485b8ab8c5d029152b3e645d202358a697bf7602474f723be7327377920395522127d17a753a2be36fb553aef64ddaa21e21b54e6ee570ada4533294e0f53602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6c13064656cdf2b07943cabae3bae3836fa8ed9610c8234b577359bba80c58dfe3a23dcfce51a9050663ab23491fd8e3e0cd9042db9dff43e322a39907b4f7f2"} 19:29:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:52 INFO - Contents: 19:29:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9abe0df90f56f194287d6a2081fd6d809eed685942692d4be769fa7f83d436013e26c8822dd0b6f97e96209f128014296e2a7cd6b561af44924637b50f5ef7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/485b8ab8c5d029152b3e645d202358a697bf7602474f723be7327377920395522127d17a753a2be36fb553aef64ddaa21e21b54e6ee570ada4533294e0f53602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6c13064656cdf2b07943cabae3bae3836fa8ed9610c8234b577359bba80c58dfe3a23dcfce51a9050663ab23491fd8e3e0cd9042db9dff43e322a39907b4f7f2"} 19:29:52 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:52 INFO - Copying logs to upload dir... 19:29:52 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.078906 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-01 19:29:52.063497) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:29:52.068874) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689 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/9abe0df90f56f194287d6a2081fd6d809eed685942692d4be769fa7f83d436013e26c8822dd0b6f97e96209f128014296e2a7cd6b561af44924637b50f5ef7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/485b8ab8c5d029152b3e645d202358a697bf7602474f723be7327377920395522127d17a753a2be36fb553aef64ddaa21e21b54e6ee570ada4533294e0f53602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6c13064656cdf2b07943cabae3bae3836fa8ed9610c8234b577359bba80c58dfe3a23dcfce51a9050663ab23491fd8e3e0cd9042db9dff43e322a39907b4f7f2"} build_url:https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011453 build_url: 'https://queue.taskcluster.net/v1/task/GTqQS41PReaw2Ru0XPO7DQ/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/9abe0df90f56f194287d6a2081fd6d809eed685942692d4be769fa7f83d436013e26c8822dd0b6f97e96209f128014296e2a7cd6b561af44924637b50f5ef7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/485b8ab8c5d029152b3e645d202358a697bf7602474f723be7327377920395522127d17a753a2be36fb553aef64ddaa21e21b54e6ee570ada4533294e0f53602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6c13064656cdf2b07943cabae3bae3836fa8ed9610c8234b577359bba80c58dfe3a23dcfce51a9050663ab23491fd8e3e0cd9042db9dff43e322a39907b4f7f2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:29:52.094476) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:29:52.094790) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1464833332.534137-1187408689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:29:52.179254) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:29:52.179576) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:29:52.179864) ========= ========= Total master_lag: 0.18 =========