builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1463859797.85 results: success (0) buildid: 20160521100625 builduid: e023822d84a94c489ad3b643f9ee292f revision: 740a2bf1fa9e1b7b2f76bc9022a2cafdaabdc1f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:17.847793) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:17.848661) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:17.848951) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:17.888458) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:17.888882) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-21 12:43:18-- 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% 110M=0s 2016-05-21 12:43:18 (110 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085508 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:17.988849) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:17.989164) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018072 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:18.040439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:18.040836) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 740a2bf1fa9e1b7b2f76bc9022a2cafdaabdc1f4 --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 740a2bf1fa9e1b7b2f76bc9022a2cafdaabdc1f4 --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=e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-21 12:43:18,346 truncating revision to first 12 chars 2016-05-21 12:43:18,346 Setting DEBUG logging. 2016-05-21 12:43:18,346 attempt 1/10 2016-05-21 12:43:18,346 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/740a2bf1fa9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-21 12:43:18,848 unpacking tar archive at: mozilla-inbound-740a2bf1fa9e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.821303 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:18.876617) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:18.877103) ========= script_repo_revision: 740a2bf1fa9e1b7b2f76bc9022a2cafdaabdc1f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:18.877576) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:18.877911) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 12:43:18.887556) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-21 12:43:18.887888) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:43:19 INFO - MultiFileLogger online at 20160521 12:43:19 in /builds/slave/test 12:43:19 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 12:43:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:43:19 INFO - {'append_to_log': False, 12:43:19 INFO - 'base_work_dir': '/builds/slave/test', 12:43:19 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 12:43:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:43:19 INFO - 'branch': 'Mozilla-Inbound', 12:43:19 INFO - 'buildbot_json_path': 'buildprops.json', 12:43:19 INFO - 'config_files': ('talos/linux_config.py',), 12:43:19 INFO - 'default_actions': ('clobber', 12:43:19 INFO - 'read-buildbot-config', 12:43:19 INFO - 'download-and-extract', 12:43:19 INFO - 'populate-webroot', 12:43:19 INFO - 'create-virtualenv', 12:43:19 INFO - 'install', 12:43:19 INFO - 'run-tests'), 12:43:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:43:19 INFO - 'download_minidump_stackwalk': True, 12:43:19 INFO - 'download_symbols': 'ondemand', 12:43:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:43:19 INFO - 'tooltool.py': '/tools/tooltool.py', 12:43:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:43:19 INFO - '/tools/misc-python/virtualenv.py')}, 12:43:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:43:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:43:19 INFO - 'installer_path': 'installer.exe', 12:43:19 INFO - 'log_level': 'info', 12:43:19 INFO - 'log_name': 'talos', 12:43:19 INFO - 'log_to_console': True, 12:43:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:43:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:43:19 INFO - 'opt_config_files': (), 12:43:19 INFO - 'pip_index': False, 12:43:19 INFO - 'sps_profile': False, 12:43:19 INFO - 'sps_profile_interval': 0, 12:43:19 INFO - 'suite': 'dromaeojs', 12:43:19 INFO - 'system_bits': '32', 12:43:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:43:19 INFO - 'title': 'talos-linux64-ix-006', 12:43:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:43:19 INFO - 'use_talos_json': True, 12:43:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:43:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:43:19 INFO - 'work_dir': 'build'} 12:43:19 INFO - ##### 12:43:19 INFO - ##### Running clobber step. 12:43:19 INFO - ##### 12:43:19 INFO - Running pre-action listener: _resource_record_pre_action 12:43:19 INFO - Running main action method: clobber 12:43:19 INFO - rmtree: /builds/slave/test/build 12:43:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:43:19 INFO - Running post-action listener: _resource_record_post_action 12:43:19 INFO - ##### 12:43:19 INFO - ##### Finished clobber step (success) 12:43:19 INFO - ##### 12:43:19 INFO - ##### 12:43:19 INFO - ##### Running read-buildbot-config step. 12:43:19 INFO - ##### 12:43:19 INFO - Running pre-action listener: _resource_record_pre_action 12:43:19 INFO - Running main action method: read_buildbot_config 12:43:19 INFO - Using buildbot properties: 12:43:19 INFO - { 12:43:19 INFO - "project": "", 12:43:19 INFO - "product": "firefox", 12:43:19 INFO - "script_repo_revision": "production", 12:43:19 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 12:43:19 INFO - "repository": "", 12:43:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 12:43:19 INFO - "buildid": "20160521100625", 12:43:19 INFO - "pgo_build": "True", 12:43:19 INFO - "basedir": "/builds/slave/test", 12:43:19 INFO - "buildnumber": 959, 12:43:19 INFO - "slavename": "talos-linux64-ix-006", 12:43:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:43:19 INFO - "platform": "ubuntu64_hw", 12:43:19 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 12:43:19 INFO - "branch": "mozilla-inbound", 12:43:19 INFO - "repo_path": "integration/mozilla-inbound", 12:43:19 INFO - "revision": "740a2bf1fa9e1b7b2f76bc9022a2cafdaabdc1f4", 12:43:19 INFO - "stage_platform": "linux64-pgo", 12:43:19 INFO - "builduid": "e023822d84a94c489ad3b643f9ee292f", 12:43:19 INFO - "slavebuilddir": "test" 12:43:19 INFO - } 12:43:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 12:43:19 INFO - Running post-action listener: _resource_record_post_action 12:43:19 INFO - ##### 12:43:19 INFO - ##### Finished read-buildbot-config step (success) 12:43:19 INFO - ##### 12:43:19 INFO - ##### 12:43:19 INFO - ##### Running download-and-extract step. 12:43:19 INFO - ##### 12:43:19 INFO - Running pre-action listener: _resource_record_pre_action 12:43:19 INFO - Running main action method: download_and_extract 12:43:19 INFO - mkdir: /builds/slave/test/build/tests 12:43: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')]} 12:43:19 INFO - https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:43:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:43:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:43:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:24 INFO - Downloaded 1361 bytes. 12:43:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:43:24 INFO - Using the following test package requirements: 12:43:24 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:43:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:43:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:43:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'jsshell-linux-x86_64.zip'], 12:43:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:43:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:43:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:43:24 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:43:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:43:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:43:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:43:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:43:24 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')]} 12:43:24 INFO - https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:43:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:43:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:43:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:26 INFO - Downloaded 24389670 bytes. 12:43:26 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'] 12:43:26 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 12:43:27 INFO - Return code: 0 12:43:27 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 12:43:27 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')]} 12:43:27 INFO - https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:43:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:43:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:43:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:30 INFO - Downloaded 24389670 bytes. 12:43:30 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'] 12:43:30 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 12:43:31 INFO - Return code: 0 12:43: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')]} 12:43:31 INFO - https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:43:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:43:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:43:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:32 INFO - Downloaded 10840359 bytes. 12:43:32 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'] 12:43:32 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 12:43:33 INFO - Return code: 0 12:43: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')]} 12:43:33 INFO - https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:43:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:43:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:43:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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 12:43:34 INFO - Downloaded 58599820 bytes. 12:43:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:43:34 INFO - mkdir: /builds/slave/test/properties 12:43:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:43:34 INFO - Writing to file /builds/slave/test/properties/build_url 12:43:34 INFO - Contents: 12:43:34 INFO - build_url:https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:43:35 INFO - Running post-action listener: _resource_record_post_action 12:43:35 INFO - Running post-action listener: set_extra_try_arguments 12:43:35 INFO - ##### 12:43:35 INFO - ##### Finished download-and-extract step (success) 12:43:35 INFO - ##### 12:43:35 INFO - ##### 12:43:35 INFO - ##### Running populate-webroot step. 12:43:35 INFO - ##### 12:43:35 INFO - Running pre-action listener: _resource_record_pre_action 12:43:35 INFO - Running main action method: populate_webroot 12:43:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:43:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:43:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:43:35 INFO - '--tppagecycles', 12:43:35 INFO - '7'], 12:43:35 INFO - 'tests': ['tsvgm']}}, 12:43:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:43:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:43:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:43:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:43:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:43:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:43:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['damp', 'tps']}, 12:43:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'tests': ['damp', 'tps']}, 12:43:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['dromaeo_dom']}, 12:43:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:43:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['a11yr', 12:43:35 INFO - 'ts_paint', 12:43:35 INFO - 'tpaint', 12:43:35 INFO - 'sessionrestore', 12:43:35 INFO - 'sessionrestore_no_auto_restore', 12:43:35 INFO - 'tabpaint']}, 12:43:35 INFO - 'other-e10s': {'tests': ['a11yr', 12:43:35 INFO - 'ts_paint', 12:43:35 INFO - 'tpaint', 12:43:35 INFO - 'sessionrestore', 12:43:35 INFO - 'sessionrestore_no_auto_restore', 12:43:35 INFO - 'tabpaint']}, 12:43:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:43:35 INFO - 'ts_paint', 12:43:35 INFO - 'tpaint', 12:43:35 INFO - 'sessionrestore', 12:43:35 INFO - 'sessionrestore_no_auto_restore', 12:43:35 INFO - 'tabpaint']}, 12:43:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:43:35 INFO - 'ts_paint', 12:43:35 INFO - 'tpaint', 12:43:35 INFO - 'sessionrestore', 12:43:35 INFO - 'sessionrestore_no_auto_restore', 12:43:35 INFO - 'tabpaint']}, 12:43:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['a11yr', 12:43:35 INFO - 'ts_paint', 12:43:35 INFO - 'tpaint', 12:43:35 INFO - 'sessionrestore', 12:43:35 INFO - 'sessionrestore_no_auto_restore', 12:43:35 INFO - 'tabpaint']}, 12:43:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['a11yr', 12:43:35 INFO - 'ts_paint', 12:43:35 INFO - 'tpaint', 12:43:35 INFO - 'sessionrestore', 12:43:35 INFO - 'sessionrestore_no_auto_restore', 12:43:35 INFO - 'tabpaint']}, 12:43:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['tsvgx', 12:43:35 INFO - 'tsvgr_opacity', 12:43:35 INFO - 'tart', 12:43:35 INFO - 'tscrollx', 12:43:35 INFO - 'cart']}, 12:43:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:43:35 INFO - 'tsvgr_opacity', 12:43:35 INFO - 'tart', 12:43:35 INFO - 'tscrollx', 12:43:35 INFO - 'cart']}, 12:43:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'talos_options': ['--disable-e10s'], 12:43:35 INFO - 'tests': ['tp5o']}, 12:43:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'tests': ['tp5o']}, 12:43:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'talos_options': ['--disable-e10s', 12:43:35 INFO - '--xperf_path', 12:43:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:43:35 INFO - 'tests': ['tp5n']}, 12:43:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:43:35 INFO - 'talos_options': ['--xperf_path', 12:43:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:43:35 INFO - 'tests': ['tp5n']}}, 12:43:35 INFO - 'talos.zip': {'path': '', 12:43:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:43:35 INFO - Running post-action listener: _resource_record_post_action 12:43:35 INFO - ##### 12:43:35 INFO - ##### Finished populate-webroot step (success) 12:43:35 INFO - ##### 12:43:35 INFO - ##### 12:43:35 INFO - ##### Running create-virtualenv step. 12:43:35 INFO - ##### 12:43:35 INFO - Running pre-action listener: _resource_record_pre_action 12:43:35 INFO - Running main action method: create_virtualenv 12:43:35 INFO - Creating virtualenv /builds/slave/test/build/venv 12:43:35 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 12:43:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:43:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:43:35 INFO - Using real prefix '/usr' 12:43:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:43:35 INFO - Installing distribute.............................................................................................................................................................................................done. 12:43:37 INFO - Installing pip.................done. 12:43:37 INFO - Return code: 0 12:43:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:43: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')]} 12:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43: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')]} 12:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:37 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:37 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 12:43:37 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 12:43:37 INFO - Using env: {'DISPLAY': ':0', 12:43:37 INFO - 'HOME': '/home/cltbld', 12:43:37 INFO - 'LANG': 'en_US.UTF-8', 12:43:37 INFO - 'LANGUAGE': 'en_US:en', 12:43:37 INFO - 'LOGNAME': 'cltbld', 12:43:37 INFO - 'MAIL': '/var/mail/cltbld', 12:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:37 INFO - 'MOZ_NO_REMOTE': '1', 12:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:37 INFO - 'NO_EM_RESTART': '1', 12:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:37 INFO - 'PWD': '/builds/slave/test', 12:43:37 INFO - 'SHELL': '/bin/bash', 12:43:37 INFO - 'SHLVL': '1', 12:43:37 INFO - 'TERM': 'linux', 12:43:37 INFO - 'TMOUT': '86400', 12:43:37 INFO - 'USER': 'cltbld', 12:43:37 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:37 INFO - '_': '/tools/buildbot/bin/python'} 12:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:37 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 12:43:37 INFO - Cleaning up... 12:43:37 INFO - Return code: 0 12:43:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:43: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')]} 12:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43: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')]} 12:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:37 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:37 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 12:43:37 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 12:43:37 INFO - Using env: {'DISPLAY': ':0', 12:43:37 INFO - 'HOME': '/home/cltbld', 12:43:37 INFO - 'LANG': 'en_US.UTF-8', 12:43:37 INFO - 'LANGUAGE': 'en_US:en', 12:43:37 INFO - 'LOGNAME': 'cltbld', 12:43:37 INFO - 'MAIL': '/var/mail/cltbld', 12:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:37 INFO - 'MOZ_NO_REMOTE': '1', 12:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:37 INFO - 'NO_EM_RESTART': '1', 12:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:37 INFO - 'PWD': '/builds/slave/test', 12:43:37 INFO - 'SHELL': '/bin/bash', 12:43:37 INFO - 'SHLVL': '1', 12:43:37 INFO - 'TERM': 'linux', 12:43:37 INFO - 'TMOUT': '86400', 12:43:37 INFO - 'USER': 'cltbld', 12:43:37 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:37 INFO - '_': '/tools/buildbot/bin/python'} 12:43:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:38 INFO - Downloading/unpacking psutil>=3.1.1 12:43:38 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 12:43:38 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 12:43:38 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 12:43:38 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 12:43:38 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 12:43:38 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 12:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:43:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:40 INFO - Installing collected packages: psutil 12:43:40 INFO - Running setup.py install for psutil 12:43:40 INFO - building 'psutil._psutil_linux' extension 12:43:40 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 12:43:40 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 12:43:40 INFO - building 'psutil._psutil_posix' extension 12:43:40 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 12:43:40 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 12:43:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:40 INFO - Successfully installed psutil 12:43:40 INFO - Cleaning up... 12:43:40 INFO - Return code: 0 12:43:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:43: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')]} 12:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43: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')]} 12:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:40 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:40 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 12:43:40 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 12:43:40 INFO - Using env: {'DISPLAY': ':0', 12:43:40 INFO - 'HOME': '/home/cltbld', 12:43:40 INFO - 'LANG': 'en_US.UTF-8', 12:43:40 INFO - 'LANGUAGE': 'en_US:en', 12:43:40 INFO - 'LOGNAME': 'cltbld', 12:43:40 INFO - 'MAIL': '/var/mail/cltbld', 12:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:40 INFO - 'MOZ_NO_REMOTE': '1', 12:43:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:40 INFO - 'NO_EM_RESTART': '1', 12:43:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:40 INFO - 'PWD': '/builds/slave/test', 12:43:40 INFO - 'SHELL': '/bin/bash', 12:43:40 INFO - 'SHLVL': '1', 12:43:40 INFO - 'TERM': 'linux', 12:43:40 INFO - 'TMOUT': '86400', 12:43:40 INFO - 'USER': 'cltbld', 12:43:40 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:40 INFO - '_': '/tools/buildbot/bin/python'} 12:43:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:43:40 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 12:43:40 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 12:43:40 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 12:43:40 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 12:43:40 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 12:43:40 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 12:43:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:43:42 INFO - Installing collected packages: mozsystemmonitor 12:43:42 INFO - Running setup.py install for mozsystemmonitor 12:43:42 INFO - Successfully installed mozsystemmonitor 12:43:42 INFO - Cleaning up... 12:43:43 INFO - Return code: 0 12:43:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:43 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:43 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 12:43:43 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 12:43:43 INFO - Using env: {'DISPLAY': ':0', 12:43:43 INFO - 'HOME': '/home/cltbld', 12:43:43 INFO - 'LANG': 'en_US.UTF-8', 12:43:43 INFO - 'LANGUAGE': 'en_US:en', 12:43:43 INFO - 'LOGNAME': 'cltbld', 12:43:43 INFO - 'MAIL': '/var/mail/cltbld', 12:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:43 INFO - 'MOZ_NO_REMOTE': '1', 12:43:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:43 INFO - 'NO_EM_RESTART': '1', 12:43:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:43 INFO - 'PWD': '/builds/slave/test', 12:43:43 INFO - 'SHELL': '/bin/bash', 12:43:43 INFO - 'SHLVL': '1', 12:43:43 INFO - 'TERM': 'linux', 12:43:43 INFO - 'TMOUT': '86400', 12:43:43 INFO - 'USER': 'cltbld', 12:43:43 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:43 INFO - '_': '/tools/buildbot/bin/python'} 12:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:43 INFO - Downloading/unpacking blobuploader==1.2.4 12:43:43 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 12:43:43 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 12:43:43 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 12:43:43 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 12:43:43 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 12:43:43 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 12:43:45 INFO - Downloading blobuploader-1.2.4.tar.gz 12:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:43:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:43:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 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 12:43:45 INFO - Downloading docopt-0.6.1.tar.gz 12:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:43:45 INFO - Installing collected packages: blobuploader, requests, docopt 12:43:45 INFO - Running setup.py install for blobuploader 12:43:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:43:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:43:45 INFO - Running setup.py install for requests 12:43:46 INFO - Running setup.py install for docopt 12:43:46 INFO - Successfully installed blobuploader requests docopt 12:43:46 INFO - Cleaning up... 12:43:46 INFO - Return code: 0 12:43:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:43: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')]} 12:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43: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')]} 12:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:46 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:46 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 12:43:46 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 12:43:46 INFO - Using env: {'DISPLAY': ':0', 12:43:46 INFO - 'HOME': '/home/cltbld', 12:43:46 INFO - 'LANG': 'en_US.UTF-8', 12:43:46 INFO - 'LANGUAGE': 'en_US:en', 12:43:46 INFO - 'LOGNAME': 'cltbld', 12:43:46 INFO - 'MAIL': '/var/mail/cltbld', 12:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:46 INFO - 'MOZ_NO_REMOTE': '1', 12:43:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:46 INFO - 'NO_EM_RESTART': '1', 12:43:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:46 INFO - 'PWD': '/builds/slave/test', 12:43:46 INFO - 'SHELL': '/bin/bash', 12:43:46 INFO - 'SHLVL': '1', 12:43:46 INFO - 'TERM': 'linux', 12:43:46 INFO - 'TMOUT': '86400', 12:43:46 INFO - 'USER': 'cltbld', 12:43:46 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:46 INFO - '_': '/tools/buildbot/bin/python'} 12:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:43:46 INFO - Running setup.py (path:/tmp/pip-ariP2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:43:46 INFO - Running setup.py (path:/tmp/pip-UlpNW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:43:46 INFO - Running setup.py (path:/tmp/pip-Je_hW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:43:46 INFO - Running setup.py (path:/tmp/pip-rDjysw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:43:47 INFO - Running setup.py (path:/tmp/pip-IcpFuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:43:47 INFO - Running setup.py (path:/tmp/pip-6zYhz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:43:47 INFO - Running setup.py (path:/tmp/pip-_CvoYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:43:47 INFO - Running setup.py (path:/tmp/pip-NI37Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:43:47 INFO - Running setup.py (path:/tmp/pip-XNHQSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:43:47 INFO - Running setup.py (path:/tmp/pip-jYgs4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:43:47 INFO - Running setup.py (path:/tmp/pip-B2PrYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:43:47 INFO - Running setup.py (path:/tmp/pip-6GRmft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:43:47 INFO - Running setup.py (path:/tmp/pip-VLmcsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:43:47 INFO - Running setup.py (path:/tmp/pip-arxLkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:43:47 INFO - Running setup.py (path:/tmp/pip-bIQOJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:43:47 INFO - Running setup.py (path:/tmp/pip-ozfFE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:43:47 INFO - Running setup.py (path:/tmp/pip-ub8GPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:43:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:43:47 INFO - Running setup.py install for manifestparser 12:43:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Running setup.py install for mozcrash 12:43:48 INFO - Running setup.py install for mozdebug 12:43:48 INFO - Running setup.py install for mozdevice 12:43:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Running setup.py install for mozfile 12:43:48 INFO - Running setup.py install for mozhttpd 12:43:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Running setup.py install for mozinfo 12:43:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Running setup.py install for mozInstall 12:43:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Running setup.py install for mozleak 12:43:48 INFO - Running setup.py install for mozlog 12:43:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:43:48 INFO - Running setup.py install for moznetwork 12:43:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:43:49 INFO - Running setup.py install for mozprocess 12:43:49 INFO - Running setup.py install for mozprofile 12:43:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:43:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:43:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:43:49 INFO - Running setup.py install for mozrunner 12:43:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:43:49 INFO - Running setup.py install for mozscreenshot 12:43:49 INFO - Running setup.py install for moztest 12:43:49 INFO - Running setup.py install for mozversion 12:43:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:43:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:43:49 INFO - Cleaning up... 12:43:49 INFO - Return code: 0 12:43:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:49 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:49 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 12:43:49 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 12:43:49 INFO - Using env: {'DISPLAY': ':0', 12:43:49 INFO - 'HOME': '/home/cltbld', 12:43:49 INFO - 'LANG': 'en_US.UTF-8', 12:43:49 INFO - 'LANGUAGE': 'en_US:en', 12:43:49 INFO - 'LOGNAME': 'cltbld', 12:43:49 INFO - 'MAIL': '/var/mail/cltbld', 12:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:49 INFO - 'MOZ_NO_REMOTE': '1', 12:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:49 INFO - 'NO_EM_RESTART': '1', 12:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:49 INFO - 'PWD': '/builds/slave/test', 12:43:49 INFO - 'SHELL': '/bin/bash', 12:43:49 INFO - 'SHLVL': '1', 12:43:49 INFO - 'TERM': 'linux', 12:43:49 INFO - 'TMOUT': '86400', 12:43:49 INFO - 'USER': 'cltbld', 12:43:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:49 INFO - '_': '/tools/buildbot/bin/python'} 12:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:43:49 INFO - Running setup.py (path:/tmp/pip-3mf38P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:43:50 INFO - Running setup.py (path:/tmp/pip-qd_WRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:43:50 INFO - Running setup.py (path:/tmp/pip-MhFypR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:43:50 INFO - Running setup.py (path:/tmp/pip-x1rbN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:43:50 INFO - Running setup.py (path:/tmp/pip-Jc5BPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:43:50 INFO - Running setup.py (path:/tmp/pip-fpqNaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:43:50 INFO - Running setup.py (path:/tmp/pip-W1dQ6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:43:50 INFO - Running setup.py (path:/tmp/pip-XzdsD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:43:50 INFO - Running setup.py (path:/tmp/pip-2IhtNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:43:50 INFO - Running setup.py (path:/tmp/pip-FSkY1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:43:50 INFO - Running setup.py (path:/tmp/pip-VA14mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:43:50 INFO - Running setup.py (path:/tmp/pip-evPBCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:43:50 INFO - Running setup.py (path:/tmp/pip-4H4BLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:43:50 INFO - Running setup.py (path:/tmp/pip-xSm4_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:43:50 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)) 12:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:43:50 INFO - Running setup.py (path:/tmp/pip-uYA5MW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:43:51 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)) 12:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:43:51 INFO - Running setup.py (path:/tmp/pip-xdcCco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:43:51 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)) 12:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:43:51 INFO - Running setup.py (path:/tmp/pip-pDCZ4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:43:51 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)) 12:43:51 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)) 12:43:51 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)) 12:43:51 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)) 12:43:51 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)) 12:43:51 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)) 12:43:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:43:51 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 12:43:51 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 12:43:51 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 12:43:51 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 12:43:51 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 12:43:51 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 12:43:53 INFO - Downloading blessings-1.6.tar.gz 12:43:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:43:53 INFO - Installing collected packages: blessings 12:43:53 INFO - Running setup.py install for blessings 12:43:53 INFO - Successfully installed blessings 12:43:53 INFO - Cleaning up... 12:43:53 INFO - Return code: 0 12:43:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:43:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:43:53 INFO - Reading from file tmpfile_stdout 12:43:53 INFO - Current package versions: 12:43:53 INFO - argparse == 1.2.1 12:43:53 INFO - blessings == 1.6 12:43:53 INFO - blobuploader == 1.2.4 12:43:53 INFO - docopt == 0.6.1 12:43:53 INFO - manifestparser == 1.1 12:43:53 INFO - mozInstall == 1.12 12:43:53 INFO - mozcrash == 0.17 12:43:53 INFO - mozdebug == 0.1 12:43:53 INFO - mozdevice == 0.48 12:43:53 INFO - mozfile == 1.2 12:43:53 INFO - mozhttpd == 0.7 12:43:53 INFO - mozinfo == 0.9 12:43:53 INFO - mozleak == 0.1 12:43:53 INFO - mozlog == 3.2 12:43:53 INFO - moznetwork == 0.27 12:43:53 INFO - mozprocess == 0.22 12:43:53 INFO - mozprofile == 0.28 12:43:53 INFO - mozrunner == 6.11 12:43:53 INFO - mozscreenshot == 0.1 12:43:53 INFO - mozsystemmonitor == 0.3 12:43:53 INFO - moztest == 0.7 12:43:53 INFO - mozversion == 1.4 12:43:53 INFO - psutil == 3.1.1 12:43:53 INFO - requests == 1.2.3 12:43:53 INFO - wsgiref == 0.1.2 12:43:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:53 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:53 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 12:43:53 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 12:43:53 INFO - Using env: {'DISPLAY': ':0', 12:43:53 INFO - 'HOME': '/home/cltbld', 12:43:53 INFO - 'LANG': 'en_US.UTF-8', 12:43:53 INFO - 'LANGUAGE': 'en_US:en', 12:43:53 INFO - 'LOGNAME': 'cltbld', 12:43:53 INFO - 'MAIL': '/var/mail/cltbld', 12:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:53 INFO - 'MOZ_NO_REMOTE': '1', 12:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:53 INFO - 'NO_EM_RESTART': '1', 12:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:53 INFO - 'PWD': '/builds/slave/test', 12:43:53 INFO - 'SHELL': '/bin/bash', 12:43:53 INFO - 'SHLVL': '1', 12:43:53 INFO - 'TERM': 'linux', 12:43:53 INFO - 'TMOUT': '86400', 12:43:53 INFO - 'USER': 'cltbld', 12:43:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:53 INFO - '_': '/tools/buildbot/bin/python'} 12:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 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)) 12:43:53 INFO - Cleaning up... 12:43:53 INFO - Return code: 0 12:43:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:53 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:53 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 12:43:53 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 12:43:53 INFO - Using env: {'DISPLAY': ':0', 12:43:53 INFO - 'HOME': '/home/cltbld', 12:43:53 INFO - 'LANG': 'en_US.UTF-8', 12:43:53 INFO - 'LANGUAGE': 'en_US:en', 12:43:53 INFO - 'LOGNAME': 'cltbld', 12:43:53 INFO - 'MAIL': '/var/mail/cltbld', 12:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:53 INFO - 'MOZ_NO_REMOTE': '1', 12:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:53 INFO - 'NO_EM_RESTART': '1', 12:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:53 INFO - 'PWD': '/builds/slave/test', 12:43:53 INFO - 'SHELL': '/bin/bash', 12:43:53 INFO - 'SHLVL': '1', 12:43:53 INFO - 'TERM': 'linux', 12:43:53 INFO - 'TMOUT': '86400', 12:43:53 INFO - 'USER': 'cltbld', 12:43:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:53 INFO - '_': '/tools/buildbot/bin/python'} 12:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:53 INFO - Downloading/unpacking jsonschema 12:43:53 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 12:43:53 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 12:43:53 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 12:43:53 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 12:43:53 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 12:43:53 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 12:43:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:43:55 INFO - Downloading/unpacking functools32 (from jsonschema) 12:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:56 INFO - Downloading functools32-3.2.3-2.tar.gz 12:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:43:56 INFO - warning: no files found matching '*.txt' 12:43:56 INFO - no previously-included directories found matching 'build' 12:43:56 INFO - no previously-included directories found matching 'dist' 12:43:56 INFO - no previously-included directories found matching '.git*' 12:43:56 INFO - Installing collected packages: jsonschema, functools32 12:43:56 INFO - Running setup.py install for functools32 12:43:56 INFO - warning: no files found matching '*.txt' 12:43:56 INFO - no previously-included directories found matching 'build' 12:43:56 INFO - no previously-included directories found matching 'dist' 12:43:56 INFO - no previously-included directories found matching '.git*' 12:43:56 INFO - Successfully installed jsonschema functools32 12:43:56 INFO - Cleaning up... 12:43:56 INFO - Return code: 0 12:43:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:43:56 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')]} 12:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:56 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')]} 12:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:56 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 0x7fb7f8b21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', '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 12:43:56 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 12:43:56 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 12:43:56 INFO - Using env: {'DISPLAY': ':0', 12:43:56 INFO - 'HOME': '/home/cltbld', 12:43:56 INFO - 'LANG': 'en_US.UTF-8', 12:43:56 INFO - 'LANGUAGE': 'en_US:en', 12:43:56 INFO - 'LOGNAME': 'cltbld', 12:43:56 INFO - 'MAIL': '/var/mail/cltbld', 12:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:56 INFO - 'MOZ_NO_REMOTE': '1', 12:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:56 INFO - 'NO_EM_RESTART': '1', 12:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:56 INFO - 'PWD': '/builds/slave/test', 12:43:56 INFO - 'SHELL': '/bin/bash', 12:43:56 INFO - 'SHLVL': '1', 12:43:56 INFO - 'TERM': 'linux', 12:43:56 INFO - 'TMOUT': '86400', 12:43:56 INFO - 'USER': 'cltbld', 12:43:56 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:56 INFO - '_': '/tools/buildbot/bin/python'} 12:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:56 INFO - Downloading/unpacking flake8 12:43:56 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 12:43:56 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 12:43:56 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 12:43:56 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 12:43:56 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 12:43:56 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 12:43:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:43:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:43:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:43:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:43:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:43:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:43:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:43:59 INFO - no previously-included directories found matching 'docs/_build' 12:43:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:43:59 INFO - Running setup.py install for pep8 12:43:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:43:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:43:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:43:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:43:59 INFO - no previously-included directories found matching 'docs/_build' 12:43:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:43:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:43:59 INFO - Cleaning up... 12:43:59 INFO - Return code: 0 12:43:59 INFO - Running post-action listener: _resource_record_post_action 12:43:59 INFO - Running post-action listener: _start_resource_monitoring 12:43:59 INFO - Starting resource monitoring. 12:43:59 INFO - ##### 12:43:59 INFO - ##### Finished create-virtualenv step (success) 12:43:59 INFO - ##### 12:43:59 INFO - ##### 12:43:59 INFO - ##### Running install step. 12:43:59 INFO - ##### 12:43:59 INFO - Running pre-action listener: _resource_record_pre_action 12:43:59 INFO - Running main action method: install 12:43:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:43:59 INFO - Reading from file tmpfile_stdout 12:43:59 INFO - Detecting whether we're running mozinstall >=1.0... 12:43:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:43:59 INFO - Reading from file tmpfile_stdout 12:43:59 INFO - Output received: 12:43:59 INFO - Usage: mozinstall [options] installer 12:43:59 INFO - Options: 12:43:59 INFO - -h, --help show this help message and exit 12:43:59 INFO - -d DEST, --destination=DEST 12:43:59 INFO - Directory to install application into. [default: 12:43:59 INFO - "/builds/slave/test"] 12:43:59 INFO - --app=APP Application being installed. [default: firefox] 12:43:59 INFO - mkdir: /builds/slave/test/build/application 12:43:59 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'] 12:43:59 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 12:44:16 INFO - Reading from file tmpfile_stdout 12:44:16 INFO - Output received: 12:44:16 INFO - /builds/slave/test/build/application/firefox/firefox 12:44:16 INFO - Running post-action listener: _resource_record_post_action 12:44:16 INFO - ##### 12:44:16 INFO - ##### Finished install step (success) 12:44:16 INFO - ##### 12:44:16 INFO - ##### 12:44:16 INFO - ##### Running run-tests step. 12:44:16 INFO - ##### 12:44:16 INFO - Running pre-action listener: _resource_record_pre_action 12:44:16 INFO - Running main action method: run_tests 12:44:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:44:16 INFO - Python 2.7.3 12:44:16 INFO - Return code: 0 12:44:16 INFO - grabbing minidump binary from tooltool 12:44: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')]} 12:44:16 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 0x11249c0>, '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 0x11258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1125d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:44:16 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 12:44:16 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 12:44:16 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 12:44:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:44:16 INFO - Return code: 0 12:44:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:44:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:44:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:44:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:44:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:44:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:44:17 INFO - Return code: 0 12:44:17 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/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 12:44:17 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/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 12:44:17 INFO - Using env: {'DISPLAY': ':0', 12:44:17 INFO - 'HOME': '/home/cltbld', 12:44:17 INFO - 'LANG': 'en_US.UTF-8', 12:44:17 INFO - 'LANGUAGE': 'en_US:en', 12:44:17 INFO - 'LOGNAME': 'cltbld', 12:44:17 INFO - 'MAIL': '/var/mail/cltbld', 12:44:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:44:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:17 INFO - 'MOZ_NO_REMOTE': '1', 12:44:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:44:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:17 INFO - 'NO_EM_RESTART': '1', 12:44:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:17 INFO - 'PWD': '/builds/slave/test', 12:44:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:44:17 INFO - 'SHELL': '/bin/bash', 12:44:17 INFO - 'SHLVL': '1', 12:44:17 INFO - 'TERM': 'linux', 12:44:17 INFO - 'TMOUT': '86400', 12:44:17 INFO - 'USER': 'cltbld', 12:44:17 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979', 12:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:17 INFO - '_': '/tools/buildbot/bin/python'} 12:44:17 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/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 12:44:17 INFO - mozversion application_buildid: 20160521100625 12:44:17 INFO - mozversion application_changeset: 740a2bf1fa9e1b7b2f76bc9022a2cafdaabdc1f4 12:44:17 INFO - mozversion application_display_name: Nightly 12:44:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:44:17 INFO - mozversion application_name: Firefox 12:44:17 INFO - mozversion application_remotingname: firefox 12:44:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:44:17 INFO - mozversion application_vendor: Mozilla 12:44:17 INFO - mozversion application_version: 49.0a1 12:44:17 INFO - mozversion platform_buildid: 20160521100625 12:44:17 INFO - mozversion platform_changeset: 740a2bf1fa9e1b7b2f76bc9022a2cafdaabdc1f4 12:44:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:44:17 INFO - mozversion platform_version: 49.0a1 12:44:17 INFO - using testdate: 1463859857 12:44:17 INFO - actual date: 1463859857 12:44:17 INFO - starting webserver on 'localhost:44381' 12:44:17 INFO - SUITE-START | Running 2 tests 12:44:17 INFO - TEST-START | dromaeo_css 12:44:17 INFO - operating with platform_type : linux_ 12:44:17 INFO - Initialising browser for dromaeo_css test... 12:44:17 INFO - TEST-INFO | started process 22149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSyFlkc/profile http://localhost:44381/getInfo.html) 12:44:21 INFO - PROCESS | 22149 | __metrics Screen width/height:1600/1200 12:44:21 INFO - PROCESS | 22149 | colorDepth:24 12:44:21 INFO - PROCESS | 22149 | Browser inner width/height: 1024/697 12:44:21 INFO - PROCESS | 22149 | __metrics 12:44:22 ERROR - PROCESS | 22149 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:44:23 INFO - TEST-INFO | 22149: exit 0 12:44:23 INFO - Browser initialized. 12:44:23 INFO - Running cycle 1/1 for dromaeo_css test... 12:44:23 INFO - TEST-INFO | started process 22230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSyFlkc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:44:34 INFO - PROCESS | 22230 | RSS: Main: 152899584 12:44:34 INFO - PROCESS | 22230 | 12:45:25 INFO - PROCESS | 22230 | 1463859925789 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] 12:47:33 INFO - PROCESS | 22230 | Cycle 1(1): loaded http://localhost:44381/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44381/tests/dromaeo/cssquery-ext.html) 12:47:34 INFO - PROCESS | 22230 | RSS: Main: 251576320 12:47:34 INFO - PROCESS | 22230 | 12:50:37 INFO - PROCESS | 22230 | Cycle 1(1): loaded http://localhost:44381/tests/dromaeo/cssquery-ext.html (next: http://localhost:44381/tests/dromaeo/cssquery-jquery.html) 12:50:37 INFO - PROCESS | 22230 | RSS: Main: 237551616 12:50:37 INFO - PROCESS | 22230 | 12:53:39 INFO - PROCESS | 22230 | Cycle 1(1): loaded http://localhost:44381/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44381/tests/dromaeo/cssquery-mootools.html) 12:53:40 INFO - PROCESS | 22230 | RSS: Main: 242212864 12:53:40 INFO - PROCESS | 22230 | 12:56:42 INFO - PROCESS | 22230 | Cycle 1(1): loaded http://localhost:44381/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44381/tests/dromaeo/cssquery-prototype.html) 12:56:42 INFO - PROCESS | 22230 | RSS: Main: 235417600 12:56:42 INFO - PROCESS | 22230 | 12:59:45 INFO - PROCESS | 22230 | Cycle 1(1): loaded http://localhost:44381/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44381/tests/dromaeo/cssquery-yui.html) 12:59:46 INFO - PROCESS | 22230 | RSS: Main: 225910784 12:59:46 INFO - PROCESS | 22230 | 13:02:48 INFO - PROCESS | 22230 | Cycle 1(1): loaded http://localhost:44381/tests/dromaeo/cssquery-yui.html (next: http://localhost:44381/tests/dromaeo/cssquery-dojo.html) 13:02:48 INFO - PROCESS | 22230 | RSS: Main: 260571136 13:02:48 INFO - PROCESS | 22230 | 13:02:48 INFO - PROCESS | 22230 | __start_tp_report 13:02:48 INFO - PROCESS | 22230 | _x_x_mozilla_page_load 13:02:49 INFO - PROCESS | 22230 | _x_x_mozilla_page_load_details 13:02:49 INFO - PROCESS | 22230 | |i|pagename|runs| 13:02:49 INFO - PROCESS | 22230 | |0;dojo.html;3140.7801453898223;3144.4497212987735;3168.794028388154;3180.3162320101173;3195.424823531765;5339.679619222849;5376.542993845528;5386.461353864612;5480.068388373976;5515.448455154477;5463.48096930791;5496.477834605706;5526.198701188336;5543.916841247385;5583.106702927533;4916.87707807305;4940.382452193476;4947.876303092434;4998.950010499885;5056.949430505685;4901.681390709592;5110.770015349318;5114.923276150861;5120.871978200537;5174.223866420049;4772.9761351193365;4796.808127674889;5000.19996800512;5017.59859211264;5048.873778155539;4864.416270047582;4917.50824917509;4949.7772600233075;4980.477049909744;5056.191009438472;4949.678270912415;4962.925556116661;4963.4292056413615;4968.428630707479;4978.079055374767;5401.432849550819;5439;5452.454754524516;5454.53636440899;5459.890802183973;5637.605367624268;5733.88532229353;5784.623999440023;5788.623739456964;5874.1776151338845;5851.824445266649;5888;5909.084091965752;5910.261217347832;5911.822645320647;5250.816221432308;5489.204065410493;5512.5589952803675;5515.61390702651;5516.834494965157;5975.133605627247;6013.188219590299;6033.5173186145;6059.666718330446;6072.266215767289;5827.038538641077;5873.853153671193;5884.7940322088525;5887.264091988502;5887.499562537232;5627.549796016309;5761.308642962871;5924.822255332347;5937.643741375445;5991.460768530853;5776.711164441761;5830.76676932918;5833.212516310244;5840.182374467578;5883.235179426647;5788.450097240755;5808.215890854765;5830.621009634445;5839.970800145972;5845.152452894306;4320.373545835836;4360.629346505582;4362;4440.067585806952;4479.059397526491;3954.3475326570792;3984.880453586397;3988.222296652183;3990.1620659661216;3992.8203230854683;5460.017196904516;5465.207544906046;5466.535344495683;5523.4200408956485;5583.497485226349;5954.672493012926;5965.045592705146;5971.164037034819;5975.731092100866;6005.519558435315;5561.499465048165;5610.186522954149;5628.296462942132;5631.380548139783;5633.661980281115;4870.488598697086;4981.12830254704;5024.221245706921;5034;5054.721990290495;6050.485708714808;6075.75696972125;6079.209702738615;6083.361247069082;6093.329733729286;5843.795467158629;5905;5908.881822363443;5916.497097746567;5916.940830591639;5797.2173756542325;5995.400459953969;6020.367861374492;6047.637141771508;6052.092186172109;5753.079507278815;5796.1305804129715;5800.071988481823;5803.506701930383;5805.332386775406;5632.971835140962;5878.059510478303;5879.882402352015;5880.94119058804;5890.823275301748;5718.1136923776885;5769.682667453162;5792.131180322816;5811.680357580373;5814.592978491465;5762.481376676119;5767.077267637158;5770.596058275881;5772.7402266898935;5801.245838041196;5444.074507333842;5601.775928962796;5707.857303567444;5719.628224165416;5725.08398656213;5759.049756790083;5862.970685146547;5868.589198756046;5871.178035075008;5875.6474611521735;5677.460641239076;5678;5683.744231509675;5683.9715801420725;5688.061469857427 13:02:49 INFO - PROCESS | 22230 | |1;ext.html;610.0757352610792;630.2751835389305;634.8063840528638;651.7327895562818;653.9869202615948;32387.190320241978;44647.10705785886;45496.77251613741;45539.54460455394;45678.314825277645;46563.767181164134;46986.530134698645;47070.52929470704;47078.76460617692;47146.52853471464;8023.919760802376;8841;9026.23277021454;9042.547872606376;10861.456927153651;10651;10801.83797243042;10819.675409737682;10823.296485728442;10850.403227822466;10859.40273284969;10912.345259284428;10945.56217751289;10946;10946.39794811285;10789.892101078967;10871.945640271828;10877.67366978988;10880.401577913211;10922.617708380169;10762;10862.728431789268;10881.673549793519;10891.128709703284;10895.891041089568;43394.56605433937;43805;43842.12315753699;43914.56085439137;43926.34110488345;3288.3423315337027;3463.2554000889677;3469.774464659789;3599.4420864766003;3769.41574056019;33345.49981750276;33595.83202083974;33608.49587256169;33949.490757638654;34440;4414.800698745416;4415.359772832953;4425.623821975136;4551.544845515455;4597.60920321773;374298;374486;375556;376411.99999999726;377451;32389.54247058888;33182.502262466085;62293;63184.68407658024;63432.36567634265;36611.084722881606;36638.63361366405;36690.08274793098;36725.81637091797;36765.81617091951;15431.305591248196;15517.922410387875;15589;15600.843991560167;15613.531594052196;12097.758044839054;12987.350632468528;13331.66670833237;13345.73308533824;13578.796318055238;8519.74440766778;8523;8529.317654587618;8544.61549230286;8604.956975215084;3231.5152727091127;3313.3042061166943;3318.0543545090018;3341.5154802553493;3359.2699759623815;1031.6595523477235;1034.99482502587;1066.4347895615335;1124.1905827803969;1196.0969468051564;32246;32726.672733272837;32727.67272327294;32752.181195470304;33001.83499082537;1119.9664010079712;1129.5030186717818;1155.4742592120565;1155.9190856639955;1222.7676741419102;17162.828371716372;17595.73606395905;18235.17941692627;18304;18941;587872;587935;587974;591834;593350;63013;64463.355366446674;64533.67733161304;64559.354406456274;64614.676926615066;1298.5000774701618;1383.4120498787975;1406.0368647476512;1415.2074838090703;1432.9426822926966;32186.35627287488;34587.827060865544;34652.82673586616;34948.65051349454;35040;52327.215091774415;52739;53224.4677553235;53252.201216981004;53314;392466;394870;395691;398639;399159;25754.742452575985;28036.57945130784;28683.28291792722;28979.855100724362;30212;49232.75383623059;49649.25526117039;50077;51030.48969510257;51172.488275116775;40867.1826563473;41273.79363103165;41536.79231603823;41887;42730.78634606807;41305.58694413018;41510.37734433926;41731;41749.582504175785;42402.78798605987;40985.795071024455;41343.37984930289;42187.7890610545;42783;42813.5718642822;34943;36987.63012369842;37107.257854843294;37726.62273377341;37729.62270377371;27385.446352635678;28062.184374689918;28303.29241768972;28348.57477137886;30482.695173047985 13:02:49 INFO - PROCESS | 22230 | |2;jquery.html;374.4869528745618;377.6348500171878;385.62786910631235;390.03855496201857;399.73817149766893;18194.454166375013;20153.798462015377;20863.26978555751;20872.791272087274;20898.582028359422;17127.571810704707;17366;17648.735268970977;18947.52631184221;19652.115654795532;5622.8313150605545;5668.914966275509;5768.74040668171;5777.046787280094;5810.070388737803;5510.421405752399;5597.160425936101;5602.747876345554;5628.746706398222;5665.3201615806165;5456.072467680504;5576.7211639417865;5578.135389014709;5583.3858275589655;5771.134329850535;5674.602777805535;5709.429057094258;5713.285839270091;5733.971330143343;5734.225879506255;5442.727863606804;5622.32532096151;5628.831135065954;5630.549556035548;5636.4927156556105;12284.078694097889;12286.324252166127;12296.446659900326;14781.556553303419;23357.883210583837;2117.491801967532;2213.2806837777625;2258.898349574257;2290.599145149609;2432.635104734304;9223.769405764842;9325;10074.949625251827;11460.885391146065;15016.549503514912;1799.3702204228546;1831.716084006981;1842.2538871756826;1949.1131535151615;2003.24878170686;76757;79854.600726996;81000.18999809986;81130.59434702789;82063;10458.843117353246;10911.618093366695;11280.548778048786;11311.377874216832;11348.868585260372;15672;15742.763858542132;15817.05097694142;15824.44614438489;15837.762433563506;8314.376421768391;8433.19884610961;8460.534670593177;8627.137286271449;8632.525211113449;8274.29668478174;8303.294219991369;8305.792355191048;8437.270359576272;8487.915120848837;4695.647826413033;4790.305405716152;4817.927731084036;4823.710577365369;4955.776990035385;1907.9506271550592;1919.2610844824842;1935.6128774245208;1949.3664559018375;1973.1712680674152;870.9869351959599;912.6486302773347;983.960641574329;988.2588058955783;1057.5346847387125;19107.426777196706;19257.4222773317;19520.51198720015;19565.608687826163;19730.90134549318;964.0455948610964;969.0745338202;998.2263745597076;1022.3763504262394;1028.654667358424;10283.53724082418;10941.945290273658;10970.067544258827;10991.450427478594;10995.701289613084;473993;474779;486175;511266;512948;60113.39886601078;76550;98669;99319.50340248397;99524.5023774891;989.3569180033036;1028.3007554862766;1067.0823092140752;1067.957281708723;1085.4138765066775;17354.132293385534;17773.644527109645;18281.177347019682;18282;18656.253749849853;42183.83621228359;42786.35820462633;43117.35323970205;43480.782596086814;44611;61743.382566173765;63403.682981584796;63818.36181638124;64343.67828161016;64584.35415645783;7148.678309475982;8449.831003379775;8671.08953559867;8714.302855771397;8786.077461866667;7613.6573854175585;10972.62842144732;12652.936735316265;13997.860021399656;14080.36638351256;11634.418279085843;12117.878821211674;12492.43784029739;12588.370581471074;12728.299943503196;11979.14323279893;11987.100967427303;12323.44544495482;12352.75294494123;12745.10784245094;12221.511139554319;12340.753184936431;12549.184303020274;12576.811347829973;12746.171498852545;7397.519161254501;7642.961785191039;7980.600969951363;8036.799080023046;8290.295324897452;8324;11974.341411222467;12491;12493.375331233583;12687.11190216676 13:02:49 INFO - PROCESS | 22230 | |3;mootools.html;1339.263405127181;1402.7054318593096;1474.5207807462573;1606.5501659535335;1825.5892424204555;8664.306855451565;9054.773630659236;9063.728088157357;9279;9295.116963888437;9040.14118658728;9270.397424167424;9281.953590232039;9283.025282345341;9294.907050929489;2066.111572024029;2303.0327262549686;2356.3048900574286;2357.1985524921524;2423.660687503751;2426.3448868805413;2434.3305590962536;2435.987820060906;2437.4393889405446;2439.109724950389;2326.906923723049;2352.9764702352927;2353.364591560278;2355.6348765941307;2360.4570948681812;2052.1586149678633;2232.944176395587;2296.9540609187725;2304.308707387777;2308.2382813671447;2340.2628172125724;2345.753695861944;2356.2931120663734;2362.9172978945826;2364.3616223619697;8707.34694897879;8735.912640873574;8737.169968852948;8741.694040708544;8755.25580325673;5240.292560504359;5370.382406023318;5376.327959005124;5379.031774280628;5381.977424289373;3334.7498937579535;3411.4200585900207;3462.6883580477624;3501.9649803501893;3511.7892926424497;971.815355082532;973.776031512756;990.1929927109383;1002.7793885345285;1063.4576366053368;12843;12983.415746291439;12994.740105197938;13003.154794938393;13029.413676384585;4536.56902594253;4586.289125185602;4685.297205419146;4707.199776038082;4719.575238228507;9938.204943604494;9950.054744799378;9956.751081222883;9963.651272205438;9967.252456065824;5399.244105825188;5414.972925135349;5420.647657902224;5422.864428389243;5425.864353391118;4487.304467807495;4804.639652026112;4980.12847751642;4983.551480366785;5061.696298222045;3900.6294402031763;3944.7041471889725;3978.9853587335047;4057.0263136846943;4064.5732198119363;1128.4357821089454;1143.8055530559825;1172.3903570143477;1193.0574845871838;1212.9575464858688;910.267234875918;1009.1976878381704;1023.3655133817081;1033.7415646088477;1049.454283772434;6092.421219984094;6202.472789812917;6228.906566401508;6361.554691171574;6405.29541750407;441.94696636403836;448.4618457850592;450.4796959511769;455.99088018240116;1053.3363980692268;4358;4405.603495685404;4452.888677783081;4515.1195516873895;4516.864494065183;11135.944320278346;11440.427978601037;11520.76958460826;11531.538738450536;11537.88462115385;10848.511816968256;11504.194706370474;11532.769344613062;11543.595974140864;11545.307281563133;8097.2307630774985;8385.538795366314;8393.160683931681;8415.747527574182;8464.238218560358;6202.658853763086;6477.643729594917;6505.837354065997;6537.5096867734155;6571.310012448624;10439.634612788515;10564.313319634199;10713.089387402159;10713.464326783786;10732.51703673321;10063.798724025624;10433.843492347469;10437.425941573287;10724.302920310154;10815.783684326427;7181.35367816899;7832.0601527817025;8016.038075430985;8114.431989760897;8250.102476430575;8884.955575222082;8886.688965886164;8952.104789520996;8954.328425367994;8971.32715046361;7458.328750412489;7670;7721.691132354643;7752;7821.178776228414;7562.546247225184;7593.8860917087395;7679.769606911802;7733.729319473791;7843.764687059397;6803.591784492946;6817.93182068173;6874.518783685294;6907.205671347659;6908.171019477695;5240.711760853037;5256.080185967364;5343.145096784496;5443.673379597237;5474.781008759605;5247.658902171346;5361.919571206357;5437.34751829783;5482.17767334903;5617.4101719320515 13:02:49 INFO - PROCESS | 22230 | |4;prototype.html;311.93605310911266;322.9741620670345;323.9497877828936;325.29573473430025;329.6061206857806;5395.649282796616;5472.534834539064;5510.393856675762;5518.19986102015;5541.9999999999945;5101.897962040757;5227.3465816772905;5293.894122117565;5368.570514358852;5404.189371594262;4176.582341765829;4228.302330115527;4252.425922500453;4256.148575369716;4259.5740425957465;4269.060806622538;4273.29490634045;4275;4285.914281714364;4289.742615443068;4043.4137050127715;4072.1855628874346;4230.365445183216;4241.300185469394;4252.978735106336;4175.248455278049;4190.559991200941;4195.727277726938;4208.095259519203;4253.787310634471;3727.459518369821;3730.6269373062746;3740.0836794985403;3743;3744.64425879541;5007.023630392075;5136.922946155811;5138.841686491683;5145.459726728677;5220.947790522084;4736.016309724851;4756.904861902743;4776.161177946481;4796.400449943757;4799.896023914484;5611.130274807411;5613.46672066153;5614.29821272341;5634.267545219095;5652;3823.1397935464574;4041.2321658884716;4057.5942405759483;4062.390641403813;4073.6333729964445;5840.503557197643;5879.235699359056;5894.115882617599;5901.73442195098;5904.704764761745;4118.085090432963;4138.875833724993;4141.233871733739;4146.523149837747;4146.792660366969;4094.6314831665295;4122.958770412258;4139.668826493873;4140.710150289451;4201.97899010503;4714.15145273854;4717.433907931001;4724.267738500538;4752.405949256343;4773.427188737304;5613.85624050533;5632.155176723524;5645.463680950304;5654.321481422256;5688.866674996876;1326.562234462625;1380.9033367664367;1402.4810819996667;1432.0476882872981;1462.817147856518;892.2237653241722;893.7497500699815;900.3112618846587;906.7370462565914;907.9105073911201;4914.238293064581;4914.41992953695;4926.482719314492;4933.45731969483;4964.751762411865;5188.792448302102;5210.567094049166;5223.623251420595;5248.819015721432;5274.841754747364;4516.345129956138;4829.879253018703;4882.36529251195;4896.596865178738;4928.211486162197;2734.4394399148127;2743;2772.861356932145;2783.5685468752376;2789.302674331417;5242.580593552507;5277.205750044926;5282.445343238982;5288.497592728685;5418.756155972991;5119.257707632372;5198.92201616976;5199.80404506958;5588.776448942078;5997.850053748604;6055.182550355646;6063.242094738158;6097.048944337858;6105.664188469676;6117.727016046394;5799.797007104731;5816.41835816415;5935.970320148372;5942.583014766613;5959.91060134107;5408.540274076748;5725.080209093309;5751.194832723339;5756.827295181151;5774.884502310014;5945.851353716192;6015.157877897011;6025.246844144482;6034;6073.969630151821;3971.1859068891386;4077.2049450356894;4097.836086556505;4105.507339119325;4150.107726838623;5495.313085864267;5770.807093304517;5818.77805660799;5829.94170058294;5850.327212370632;5511.917321240105;5689.146628005832;5705.258316419005;5720.914186287292;5721.884232574775;5667.574931880043;5688.203651488712;5697.3448053474385;5706.543476521952;5715.714214289185;5413.891722165614;5556.666600004012;5562.2490963721;5623.550115990793;5700.287464066992;5723.3418156912485;5753.4246575343805;5787.94212057874;5802.274715660543;5804.6807425592;5565.749541270571;5698.6010979232715;5712.5429965601825;5717.456841600041;5723.627964182481 13:02:49 INFO - PROCESS | 22230 | |5;yui.html;705.2559549675092;723.6020188896106;726.0923845193508;739.4601940583377;754.3022703998802;14449.78325325121;14477.05899116558;14479.05886117403;14654.41382344705;14721.852781472182;13336.199828010302;13859.584212473643;13860.514881979132;14291.785623215661;14330.28348582572;819.9221073997961;820.7619790260832;823.1768231768232;824.3611201318965;825.859603867341;686.6120641837371;796;809.7125520440218;820.6717313074788;820.8563501387263;858.0689951402735;858.1590041759049;859.96130174142;860.101194252005;860.9741707748777;700.6146619359332;846.3990566697621;850.196564246787;850.7702920211539;851.0255757158081;603.2128072864909;621.2234706616729;621.3724138619981;621.8414304352407;624.6751689121677;14366.569002929824;14443.711125777425;14454.783178252335;14466.349014294383;14466.855331446655;12426.875731242753;12883.162594431333;12946;13162.560402067877;13218.405171767294;12068.758624827542;12102.576409825702;12225.755484890253;12369.93815030928;12375.443105060203;4266.296061149907;4305.612494875445;4336.371226172188;4363.2364336241035;4382.0797632497215;59460.702696486675;60369;60479;60822.39177608211;61074;10507;10544.000237879372;10652.147828173727;10728.570857165781;10765;17960.281588736303;18044.90977545104;18052.81947180538;18060.367887123888;18124;7465.776026719207;7745.109312429143;7822.530648161129;7981.8403631928195;8065.112837587861;4582.541745825458;4607.424071991001;4658.34783130362;4772.260299653559;4788.4253889533475;3339.13182572528;3414.7951122932704;3426.0920855973186;3431.519587257786;3439.3121375724954;579.7796837201836;590.1236663554618;690.9378155965987;725.4522835259345;728.5956294256632;562.3532937122342;640.1326202994968;714.2857142857143;716.4197000429668;721.3471808013755;9805.705828825146;9927.50362481873;10005.14956228714;10029.197664186848;10195.235357348229;663.6217356106972;670.2125003121313;734.5409119300438;742.2651574940878;758.4956004257107;4711.246200607886;4841.757912104381;4929.309896614477;4957.380327459067;5089.847304580869;71888;72724;73603;73674;76046;63420;63641.68179159167;64803;65525;66793;56943;57247.713761430925;57616.4238357611;58007;59092;9466.567238379319;9592;9637.518124093627;9641.517924103908;9718.805623887341;36194;36590.353434095;36648.81675591605;36781.816090919376;37863.81068094642;46088.53911460842;46471;48721.512784871695;50148.74925625348;50575.49424505708;12107.878921210675;12160.513579457076;12163.148579599343;12380.62858114258;12672.619821405373;13401.597952061455;13528.49722801285;13610.727785444038;13724.65688357799;14042.508512202025;9774.609015639579;12351.69120771988;12592.622221333375;13172.736545269232;13398.26309552945;12567.37163141821;12612.306323152316;12760.170588911691;12777.808332874829;12887.93556032214;12328.445219964944;12355.62933112008;12406.751864962831;12885.549005784753;13196.802047969097;10014.39913605186;10174.949125254327;10466.267361284637;10552.894471055191;10558.996895294935;10433.306362341165;10660.946695266473;10661.307015044307;10710.410927398756;10828.5127169276 13:02:49 INFO - PROCESS | 22230 | __end_tp_report 13:02:49 INFO - PROCESS | 22230 | __start_cc_report 13:02:49 INFO - PROCESS | 22230 | _x_x_mozilla_cycle_collect,824 13:02:49 INFO - PROCESS | 22230 | __end_cc_report 13:02:49 INFO - PROCESS | 22230 | __startTimestamp1463860968998__endTimestamp 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - PROCESS | 22230 | ------- Summary: start ------- 13:02:49 INFO - PROCESS | 22230 | Number of tests: 6 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - PROCESS | 22230 | [#0] dojo.html Cycles:165 Average:5446.51 Median:5683.74 stddev:632.13 (11.1%) stddev-sans-first:607.63 13:02:49 INFO - PROCESS | 22230 | Values: 3140.8 3144.4 3168.8 3180.3 3195.4 5339.7 5376.5 5386.5 5480.1 5515.4 5463.5 5496.5 5526.2 5543.9 5583.1 4916.9 4940.4 4947.9 4999.0 5056.9 4901.7 5110.8 5114.9 5120.9 5174.2 4773.0 4796.8 5000.2 5017.6 5048.9 4864.4 4917.5 4949.8 4980.5 5056.2 4949.7 4962.9 4963.4 4968.4 4978.1 5401.4 5439.0 5452.5 5454.5 5459.9 5637.6 5733.9 5784.6 5788.6 5874.2 5851.8 5888.0 5909.1 5910.3 5911.8 5250.8 5489.2 5512.6 5515.6 5516.8 5975.1 6013.2 6033.5 6059.7 6072.3 5827.0 5873.9 5884.8 5887.3 5887.5 5627.5 5761.3 5924.8 5937.6 5991.5 5776.7 5830.8 5833.2 5840.2 5883.2 5788.5 5808.2 5830.6 5840.0 5845.2 4320.4 4360.6 4362.0 4440.1 4479.1 3954.3 3984.9 3988.2 3990.2 3992.8 5460.0 5465.2 5466.5 5523.4 5583.5 5954.7 5965.0 5971.2 5975.7 6005.5 5561.5 5610.2 5628.3 5631.4 5633.7 4870.5 4981.1 5024.2 5034.0 5054.7 6050.5 6075.8 6079.2 6083.4 6093.3 5843.8 5905.0 5908.9 5916.5 5916.9 5797.2 5995.4 6020.4 6047.6 6052.1 5753.1 5796.1 5800.1 5803.5 5805.3 5633.0 5878.1 5879.9 5880.9 5890.8 5718.1 5769.7 5792.1 5811.7 5814.6 5762.5 5767.1 5770.6 5772.7 5801.2 5444.1 5601.8 5707.9 5719.6 5725.1 5759.0 5863.0 5868.6 5871.2 5875.6 5677.5 5678.0 5683.7 5684.0 5688.1 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - PROCESS | 22230 | [#1] ext.html Cycles:180 Average:60928.90 Median:36664.36 stddev:123371.71 (336.5%) stddev-sans-first:123634.68 13:02:49 INFO - PROCESS | 22230 | Values: 610.1 630.3 634.8 651.7 654.0 32387.2 44647.1 45496.8 45539.5 45678.3 46563.8 46986.5 47070.5 47078.8 47146.5 8023.9 8841.0 9026.2 9042.5 10861.5 10651.0 10801.8 10819.7 10823.3 10850.4 10859.4 10912.3 10945.6 10946.0 10946.4 10789.9 10871.9 10877.7 10880.4 10922.6 10762.0 10862.7 10881.7 10891.1 10895.9 43394.6 43805.0 43842.1 43914.6 43926.3 3288.3 3463.3 3469.8 3599.4 3769.4 33345.5 33595.8 33608.5 33949.5 34440.0 4414.8 4415.4 4425.6 4551.5 4597.6 374298.0 374486.0 375556.0 376412.0 377451.0 32389.5 33182.5 62293.0 63184.7 63432.4 36611.1 36638.6 36690.1 36725.8 36765.8 15431.3 15517.9 15589.0 15600.8 15613.5 12097.8 12987.4 13331.7 13345.7 13578.8 8519.7 8523.0 8529.3 8544.6 8605.0 3231.5 3313.3 3318.1 3341.5 3359.3 1031.7 1035.0 1066.4 1124.2 1196.1 32246.0 32726.7 32727.7 32752.2 33001.8 1120.0 1129.5 1155.5 1155.9 1222.8 17162.8 17595.7 18235.2 18304.0 18941.0 587872.0 587935.0 587974.0 591834.0 593350.0 63013.0 64463.4 64533.7 64559.4 64614.7 1298.5 1383.4 1406.0 1415.2 1432.9 32186.4 34587.8 34652.8 34948.7 35040.0 52327.2 52739.0 53224.5 53252.2 53314.0 392466.0 394870.0 395691.0 398639.0 399159.0 25754.7 28036.6 28683.3 28979.9 30212.0 49232.8 49649.3 50077.0 51030.5 51172.5 40867.2 41273.8 41536.8 41887.0 42730.8 41305.6 41510.4 41731.0 41749.6 42402.8 40985.8 41343.4 42187.8 42783.0 42813.6 34943.0 36987.6 37107.3 37726.6 37729.6 27385.4 28062.2 28303.3 28348.6 30482.7 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - PROCESS | 22230 | [#2] jquery.html Cycles:180 Average:28925.79 Median:387.83 stddev:81145.05 (20922.7%) stddev-sans-first:81344.36 13:02:49 INFO - PROCESS | 22230 | Values: 374.5 377.6 385.6 390.0 399.7 18194.5 20153.8 20863.3 20872.8 20898.6 17127.6 17366.0 17648.7 18947.5 19652.1 5622.8 5668.9 5768.7 5777.0 5810.1 5510.4 5597.2 5602.7 5628.7 5665.3 5456.1 5576.7 5578.1 5583.4 5771.1 5674.6 5709.4 5713.3 5734.0 5734.2 5442.7 5622.3 5628.8 5630.5 5636.5 12284.1 12286.3 12296.4 14781.6 23357.9 2117.5 2213.3 2258.9 2290.6 2432.6 9223.8 9325.0 10074.9 11460.9 15016.5 1799.4 1831.7 1842.3 1949.1 2003.2 76757.0 79854.6 81000.2 81130.6 82063.0 10458.8 10911.6 11280.5 11311.4 11348.9 15672.0 15742.8 15817.1 15824.4 15837.8 8314.4 8433.2 8460.5 8627.1 8632.5 8274.3 8303.3 8305.8 8437.3 8487.9 4695.6 4790.3 4817.9 4823.7 4955.8 1908.0 1919.3 1935.6 1949.4 1973.2 871.0 912.6 984.0 988.3 1057.5 19107.4 19257.4 19520.5 19565.6 19730.9 964.0 969.1 998.2 1022.4 1028.7 10283.5 10941.9 10970.1 10991.5 10995.7 473993.0 474779.0 486175.0 511266.0 512948.0 60113.4 76550.0 98669.0 99319.5 99524.5 989.4 1028.3 1067.1 1068.0 1085.4 17354.1 17773.6 18281.2 18282.0 18656.3 42183.8 42786.4 43117.4 43480.8 44611.0 61743.4 63403.7 63818.4 64343.7 64584.4 7148.7 8449.8 8671.1 8714.3 8786.1 7613.7 10972.6 12652.9 13997.9 14080.4 11634.4 12117.9 12492.4 12588.4 12728.3 11979.1 11987.1 12323.4 12352.8 12745.1 12221.5 12340.8 12549.2 12576.8 12746.2 7397.5 7643.0 7980.6 8036.8 8290.3 8324.0 11974.3 12491.0 12493.4 12687.1 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - PROCESS | 22230 | [#3] mootools.html Cycles:180 Average:5942.35 Median:4713.39 stddev:3442.61 (73.0%) stddev-sans-first:3434.89 13:02:49 INFO - PROCESS | 22230 | Values: 1339.3 1402.7 1474.5 1606.6 1825.6 8664.3 9054.8 9063.7 9279.0 9295.1 9040.1 9270.4 9282.0 9283.0 9294.9 2066.1 2303.0 2356.3 2357.2 2423.7 2426.3 2434.3 2436.0 2437.4 2439.1 2326.9 2353.0 2353.4 2355.6 2360.5 2052.2 2232.9 2297.0 2304.3 2308.2 2340.3 2345.8 2356.3 2362.9 2364.4 8707.3 8735.9 8737.2 8741.7 8755.3 5240.3 5370.4 5376.3 5379.0 5382.0 3334.7 3411.4 3462.7 3502.0 3511.8 971.8 973.8 990.2 1002.8 1063.5 12843.0 12983.4 12994.7 13003.2 13029.4 4536.6 4586.3 4685.3 4707.2 4719.6 9938.2 9950.1 9956.8 9963.7 9967.3 5399.2 5415.0 5420.6 5422.9 5425.9 4487.3 4804.6 4980.1 4983.6 5061.7 3900.6 3944.7 3979.0 4057.0 4064.6 1128.4 1143.8 1172.4 1193.1 1213.0 910.3 1009.2 1023.4 1033.7 1049.5 6092.4 6202.5 6228.9 6361.6 6405.3 441.9 448.5 450.5 456.0 1053.3 4358.0 4405.6 4452.9 4515.1 4516.9 11135.9 11440.4 11520.8 11531.5 11537.9 10848.5 11504.2 11532.8 11543.6 11545.3 8097.2 8385.5 8393.2 8415.7 8464.2 6202.7 6477.6 6505.8 6537.5 6571.3 10439.6 10564.3 10713.1 10713.5 10732.5 10063.8 10433.8 10437.4 10724.3 10815.8 7181.4 7832.1 8016.0 8114.4 8250.1 8885.0 8886.7 8952.1 8954.3 8971.3 7458.3 7670.0 7721.7 7752.0 7821.2 7562.5 7593.9 7679.8 7733.7 7843.8 6803.6 6817.9 6874.5 6907.2 6908.2 5240.7 5256.1 5343.1 5443.7 5474.8 5247.7 5361.9 5437.3 5482.2 5617.4 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - PROCESS | 22230 | [#4] prototype.html Cycles:180 Average:4679.60 Median:5222.29 stddev:1391.31 (26.6%) stddev-sans-first:1356.04 13:02:49 INFO - PROCESS | 22230 | Values: 311.9 323.0 323.9 325.3 329.6 5395.6 5472.5 5510.4 5518.2 5542.0 5101.9 5227.3 5293.9 5368.6 5404.2 4176.6 4228.3 4252.4 4256.1 4259.6 4269.1 4273.3 4275.0 4285.9 4289.7 4043.4 4072.2 4230.4 4241.3 4253.0 4175.2 4190.6 4195.7 4208.1 4253.8 3727.5 3730.6 3740.1 3743.0 3744.6 5007.0 5136.9 5138.8 5145.5 5220.9 4736.0 4756.9 4776.2 4796.4 4799.9 5611.1 5613.5 5614.3 5634.3 5652.0 3823.1 4041.2 4057.6 4062.4 4073.6 5840.5 5879.2 5894.1 5901.7 5904.7 4118.1 4138.9 4141.2 4146.5 4146.8 4094.6 4123.0 4139.7 4140.7 4202.0 4714.2 4717.4 4724.3 4752.4 4773.4 5613.9 5632.2 5645.5 5654.3 5688.9 1326.6 1380.9 1402.5 1432.0 1462.8 892.2 893.7 900.3 906.7 907.9 4914.2 4914.4 4926.5 4933.5 4964.8 5188.8 5210.6 5223.6 5248.8 5274.8 4516.3 4829.9 4882.4 4896.6 4928.2 2734.4 2743.0 2772.9 2783.6 2789.3 5242.6 5277.2 5282.4 5288.5 5418.8 5119.3 5198.9 5199.8 5588.8 5997.9 6055.2 6063.2 6097.0 6105.7 6117.7 5799.8 5816.4 5936.0 5942.6 5959.9 5408.5 5725.1 5751.2 5756.8 5774.9 5945.9 6015.2 6025.2 6034.0 6074.0 3971.2 4077.2 4097.8 4105.5 4150.1 5495.3 5770.8 5818.8 5829.9 5850.3 5511.9 5689.1 5705.3 5720.9 5721.9 5667.6 5688.2 5697.3 5706.5 5715.7 5413.9 5556.7 5562.2 5623.6 5700.3 5723.3 5753.4 5787.9 5802.3 5804.7 5565.7 5698.6 5712.5 5717.5 5723.6 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - PROCESS | 22230 | [#5] yui.html Cycles:180 Average:15962.67 Median:25564.67 stddev:19691.26 (77.0%) stddev-sans-first:19713.17 13:02:49 INFO - PROCESS | 22230 | Values: 705.3 723.6 726.1 739.5 754.3 14449.8 14477.1 14479.1 14654.4 14721.9 13336.2 13859.6 13860.5 14291.8 14330.3 819.9 820.8 823.2 824.4 825.9 686.6 796.0 809.7 820.7 820.9 858.1 858.2 860.0 860.1 861.0 700.6 846.4 850.2 850.8 851.0 603.2 621.2 621.4 621.8 624.7 14366.6 14443.7 14454.8 14466.3 14466.9 12426.9 12883.2 12946.0 13162.6 13218.4 12068.8 12102.6 12225.8 12369.9 12375.4 4266.3 4305.6 4336.4 4363.2 4382.1 59460.7 60369.0 60479.0 60822.4 61074.0 10507.0 10544.0 10652.1 10728.6 10765.0 17960.3 18044.9 18052.8 18060.4 18124.0 7465.8 7745.1 7822.5 7981.8 8065.1 4582.5 4607.4 4658.3 4772.3 4788.4 3339.1 3414.8 3426.1 3431.5 3439.3 579.8 590.1 690.9 725.5 728.6 562.4 640.1 714.3 716.4 721.3 9805.7 9927.5 10005.1 10029.2 10195.2 663.6 670.2 734.5 742.3 758.5 4711.2 4841.8 4929.3 4957.4 5089.8 71888.0 72724.0 73603.0 73674.0 76046.0 63420.0 63641.7 64803.0 65525.0 66793.0 56943.0 57247.7 57616.4 58007.0 59092.0 9466.6 9592.0 9637.5 9641.5 9718.8 36194.0 36590.4 36648.8 36781.8 37863.8 46088.5 46471.0 48721.5 50148.7 50575.5 12107.9 12160.5 12163.1 12380.6 12672.6 13401.6 13528.5 13610.7 13724.7 14042.5 9774.6 12351.7 12592.6 13172.7 13398.3 12567.4 12612.3 12760.2 12777.8 12887.9 12328.4 12355.6 12406.8 12885.5 13196.8 10014.4 10174.9 10466.3 10552.9 10559.0 10433.3 10660.9 10661.3 10710.4 10828.5 13:02:49 INFO - PROCESS | 22230 | -------- Summary: end -------- 13:02:49 INFO - PROCESS | 22230 | 13:02:49 INFO - TEST-INFO | 22230: exit 0 13:02:49 INFO - TEST-OK | dromaeo_css | took 1112068ms 13:02:49 INFO - TEST-START | kraken 13:02:49 INFO - operating with platform_type : linux_ 13:02:49 INFO - Initialising browser for kraken test... 13:02:49 INFO - TEST-INFO | started process 22407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXiI1ue/profile http://localhost:44381/getInfo.html) 13:02:53 INFO - PROCESS | 22407 | __metrics Screen width/height:1600/1200 13:02:53 INFO - PROCESS | 22407 | colorDepth:24 13:02:53 INFO - PROCESS | 22407 | Browser inner width/height: 1024/697 13:02:53 INFO - PROCESS | 22407 | __metrics 13:02:54 ERROR - PROCESS | 22407 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:02:55 INFO - TEST-INFO | 22407: exit 0 13:02:55 INFO - Browser initialized. 13:02:55 INFO - Running cycle 1/1 for kraken test... 13:02:55 INFO - TEST-INFO | started process 22487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXiI1ue/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:03:07 INFO - PROCESS | 22487 | RSS: Main: 152567808 13:03:07 INFO - PROCESS | 22487 | 13:03:09 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44381/tests/kraken/driver.html?testName=audio-beat-detection) 13:03:10 INFO - PROCESS | 22487 | RSS: Main: 208891904 13:03:10 INFO - PROCESS | 22487 | 13:03:13 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44381/tests/kraken/driver.html?testName=audio-dft) 13:03:13 INFO - PROCESS | 22487 | RSS: Main: 196444160 13:03:13 INFO - PROCESS | 22487 | 13:03:17 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44381/tests/kraken/driver.html?testName=audio-fft) 13:03:17 INFO - PROCESS | 22487 | RSS: Main: 265662464 13:03:17 INFO - PROCESS | 22487 | 13:03:19 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44381/tests/kraken/driver.html?testName=audio-oscillator) 13:03:20 INFO - PROCESS | 22487 | RSS: Main: 175063040 13:03:20 INFO - PROCESS | 22487 | 13:03:22 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44381/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:03:23 INFO - PROCESS | 22487 | RSS: Main: 179736576 13:03:23 INFO - PROCESS | 22487 | 13:03:26 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44381/tests/kraken/driver.html?testName=imaging-darkroom) 13:03:27 INFO - PROCESS | 22487 | RSS: Main: 215052288 13:03:27 INFO - PROCESS | 22487 | 13:03:30 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44381/tests/kraken/driver.html?testName=imaging-desaturate) 13:03:31 INFO - PROCESS | 22487 | RSS: Main: 213561344 13:03:31 INFO - PROCESS | 22487 | 13:03:34 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44381/tests/kraken/driver.html?testName=json-parse-financial) 13:03:35 INFO - PROCESS | 22487 | RSS: Main: 214618112 13:03:35 INFO - PROCESS | 22487 | 13:03:36 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44381/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:03:37 INFO - PROCESS | 22487 | RSS: Main: 204218368 13:03:37 INFO - PROCESS | 22487 | 13:03:39 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:03:39 INFO - PROCESS | 22487 | RSS: Main: 207241216 13:03:39 INFO - PROCESS | 22487 | 13:03:41 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:03:42 INFO - PROCESS | 22487 | RSS: Main: 216461312 13:03:42 INFO - PROCESS | 22487 | 13:03:45 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:03:45 INFO - PROCESS | 22487 | RSS: Main: 223555584 13:03:45 INFO - PROCESS | 22487 | 13:03:48 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:03:49 INFO - PROCESS | 22487 | RSS: Main: 220205056 13:03:49 INFO - PROCESS | 22487 | 13:03:50 INFO - PROCESS | 22487 | Cycle 1(1): loaded http://localhost:44381/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44381/tests/kraken/driver.html?testName=ai-astar) 13:03:51 INFO - PROCESS | 22487 | RSS: Main: 266244096 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | __start_tp_report 13:03:51 INFO - PROCESS | 22487 | _x_x_mozilla_page_load 13:03:51 INFO - PROCESS | 22487 | _x_x_mozilla_page_load_details 13:03:51 INFO - PROCESS | 22487 | |i|pagename|runs| 13:03:51 INFO - PROCESS | 22487 | |0;ai-astar;110;104;110;109;113;110;111;111;110;111 13:03:51 INFO - PROCESS | 22487 | |1;audio-beat-detection;153;124;131;203;125;125;154;128;166;124 13:03:51 INFO - PROCESS | 22487 | |2;audio-dft;161;161;161;161;159;162;161;160;161;159 13:03:51 INFO - PROCESS | 22487 | |3;audio-fft;73;75;76;142;75;76;74;75;121;75 13:03:51 INFO - PROCESS | 22487 | |4;audio-oscillator;92;93;180;91;92;92;128;92;93;131 13:03:51 INFO - PROCESS | 22487 | |5;imaging-gaussian-blur;117;116;116;116;116;116;116;117;117;116 13:03:51 INFO - PROCESS | 22487 | |6;imaging-darkroom;145;147;145;147;147;145;146;146;146;145 13:03:51 INFO - PROCESS | 22487 | |7;imaging-desaturate;95;94;95;95;95;95;96;95;95;94 13:03:51 INFO - PROCESS | 22487 | |8;json-parse-financial;56;53;57;56;55;56;58;123;54;54 13:03:51 INFO - PROCESS | 22487 | |9;json-stringify-tinderbox;61;61;61;61;61;60;62;62;61;62 13:03:51 INFO - PROCESS | 22487 | |10;stanford-crypto-aes;78;77;79;78;78;78;79;78;80;79 13:03:51 INFO - PROCESS | 22487 | |11;stanford-crypto-ccm;130;120;122;125;139;119;124;119;200;119 13:03:51 INFO - PROCESS | 22487 | |12;stanford-crypto-pbkdf2;165;158;159;162;166;159;162;163;158;163 13:03:51 INFO - PROCESS | 22487 | |13;stanford-crypto-sha256-iterative;63;73;64;65;64;62;63;61;62;62 13:03:51 INFO - PROCESS | 22487 | __end_tp_report 13:03:51 INFO - PROCESS | 22487 | __start_cc_report 13:03:51 INFO - PROCESS | 22487 | _x_x_mozilla_cycle_collect,1903 13:03:51 INFO - PROCESS | 22487 | __end_cc_report 13:03:51 INFO - PROCESS | 22487 | __startTimestamp1463861031163__endTimestamp 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | ------- Summary: start ------- 13:03:51 INFO - PROCESS | 22487 | Number of tests: 14 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.50 stddev:2.33 (2.1%) stddev-sans-first:2.47 13:03:51 INFO - PROCESS | 22487 | Values: 110.0 104.0 110.0 109.0 113.0 110.0 111.0 111.0 110.0 111.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:142.00 stddev:26.01 (18.3%) stddev-sans-first:27.35 13:03:51 INFO - PROCESS | 22487 | Values: 153.0 124.0 131.0 203.0 125.0 125.0 154.0 128.0 166.0 124.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 13:03:51 INFO - PROCESS | 22487 | Values: 161.0 161.0 161.0 161.0 159.0 162.0 161.0 160.0 161.0 159.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#3] audio-fft Cycles:10 Average:86.20 Median:75.00 stddev:24.40 (32.5%) stddev-sans-first:25.41 13:03:51 INFO - PROCESS | 22487 | Values: 73.0 75.0 76.0 142.0 75.0 76.0 74.0 75.0 121.0 75.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#4] audio-oscillator Cycles:10 Average:108.40 Median:92.00 stddev:29.58 (32.2%) stddev-sans-first:30.77 13:03:51 INFO - PROCESS | 22487 | Values: 92.0 93.0 180.0 91.0 92.0 92.0 128.0 92.0 93.0 131.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 13:03:51 INFO - PROCESS | 22487 | Values: 117.0 116.0 116.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 13:03:51 INFO - PROCESS | 22487 | Values: 145.0 147.0 145.0 147.0 147.0 145.0 146.0 146.0 146.0 145.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 13:03:51 INFO - PROCESS | 22487 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:56.00 stddev:21.42 (38.2%) stddev-sans-first:22.60 13:03:51 INFO - PROCESS | 22487 | Values: 56.0 53.0 57.0 56.0 55.0 56.0 58.0 123.0 54.0 54.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 13:03:51 INFO - PROCESS | 22487 | Values: 61.0 61.0 61.0 61.0 61.0 60.0 62.0 62.0 61.0 62.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.50 stddev:0.84 (1.1%) stddev-sans-first:0.88 13:03:51 INFO - PROCESS | 22487 | Values: 78.0 77.0 79.0 78.0 78.0 78.0 79.0 78.0 80.0 79.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:124.50 stddev:24.81 (19.9%) stddev-sans-first:26.31 13:03:51 INFO - PROCESS | 22487 | Values: 130.0 120.0 122.0 125.0 139.0 119.0 124.0 119.0 200.0 119.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.50 stddev:2.88 (1.8%) stddev-sans-first:2.76 13:03:51 INFO - PROCESS | 22487 | Values: 165.0 158.0 159.0 162.0 166.0 159.0 162.0 163.0 158.0 163.0 13:03:51 INFO - PROCESS | 22487 | 13:03:51 INFO - PROCESS | 22487 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:63.50 stddev:3.41 (5.4%) stddev-sans-first:3.61 13:03:51 INFO - PROCESS | 22487 | Values: 63.0 73.0 64.0 65.0 64.0 62.0 63.0 61.0 62.0 62.0 13:03:51 INFO - PROCESS | 22487 | -------- Summary: end -------- 13:03:51 INFO - PROCESS | 22487 | 13:03:52 INFO - TEST-INFO | 22487: exit 0 13:03:52 INFO - TEST-OK | kraken | took 62542ms 13:03:52 INFO - SUITE-END | took 1174s 13:03:52 INFO - Completed test suite (00:19:34) 13:03:52 INFO - javascript benchmark 13:03:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.7801453898223, 3144.4497212987735, 3168.794028388154, 3180.3162320101173, 3195.424823531765, 5339.679619222849, 5376.542993845528, 5386.461353864612, 5480.068388373976, 5515.448455154477, 5463.48096930791, 5496.477834605706, 5526.198701188336, 5543.916841247385, 5583.106702927533, 4916.87707807305, 4940.382452193476, 4947.876303092434, 4998.950010499885, 5056.949430505685, 4901.681390709592, 5110.770015349318, 5114.923276150861, 5120.871978200537, 5174.223866420049, 4772.9761351193365, 4796.808127674889, 5000.19996800512, 5017.59859211264, 5048.873778155539, 4864.416270047582, 4917.50824917509, 4949.7772600233075, 4980.477049909744, 5056.191009438472, 4949.678270912415, 4962.925556116661, 4963.4292056413615, 4968.428630707479, 4978.079055374767, 5401.432849550819, 5439.0, 5452.454754524516, 5454.53636440899, 5459.890802183973, 5637.605367624268, 5733.88532229353, 5784.623999440023, 5788.623739456964, 5874.1776151338845, 5851.824445266649, 5888.0, 5909.084091965752, 5910.261217347832, 5911.822645320647, 5250.816221432308, 5489.204065410493, 5512.5589952803675, 5515.61390702651, 5516.834494965157, 5975.133605627247, 6013.188219590299, 6033.5173186145, 6059.666718330446, 6072.266215767289, 5827.038538641077, 5873.853153671193, 5884.7940322088525, 5887.264091988502, 5887.499562537232, 5627.549796016309, 5761.308642962871, 5924.822255332347, 5937.643741375445, 5991.460768530853, 5776.711164441761, 5830.76676932918, 5833.212516310244, 5840.182374467578, 5883.235179426647, 5788.450097240755, 5808.215890854765, 5830.621009634445, 5839.970800145972, 5845.152452894306, 4320.373545835836, 4360.629346505582, 4362.0, 4440.067585806952, 4479.059397526491, 3954.3475326570792, 3984.880453586397, 3988.222296652183, 3990.1620659661216, 3992.8203230854683, 5460.017196904516, 5465.207544906046, 5466.535344495683, 5523.4200408956485, 5583.497485226349, 5954.672493012926, 5965.045592705146, 5971.164037034819, 5975.731092100866, 6005.519558435315, 5561.499465048165, 5610.186522954149, 5628.296462942132, 5631.380548139783, 5633.661980281115, 4870.488598697086, 4981.12830254704, 5024.221245706921, 5034.0, 5054.721990290495, 6050.485708714808, 6075.75696972125, 6079.209702738615, 6083.361247069082, 6093.329733729286, 5843.795467158629, 5905.0, 5908.881822363443, 5916.497097746567, 5916.940830591639, 5797.2173756542325, 5995.400459953969, 6020.367861374492, 6047.637141771508, 6052.092186172109, 5753.079507278815, 5796.1305804129715, 5800.071988481823, 5803.506701930383, 5805.332386775406, 5632.971835140962, 5878.059510478303, 5879.882402352015, 5880.94119058804, 5890.823275301748, 5718.1136923776885, 5769.682667453162, 5792.131180322816, 5811.680357580373, 5814.592978491465, 5762.481376676119, 5767.077267637158, 5770.596058275881, 5772.7402266898935, 5801.245838041196, 5444.074507333842, 5601.775928962796, 5707.857303567444, 5719.628224165416, 5725.08398656213, 5759.049756790083, 5862.970685146547, 5868.589198756046, 5871.178035075008, 5875.6474611521735, 5677.460641239076, 5678.0, 5683.744231509675, 5683.9715801420725, 5688.061469857427], "value": 5403.047098119288, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.0757352610792, 630.2751835389305, 634.8063840528638, 651.7327895562818, 653.9869202615948, 32387.190320241978, 44647.10705785886, 45496.77251613741, 45539.54460455394, 45678.314825277645, 46563.767181164134, 46986.530134698645, 47070.52929470704, 47078.76460617692, 47146.52853471464, 8023.919760802376, 8841.0, 9026.23277021454, 9042.547872606376, 10861.456927153651, 10651.0, 10801.83797243042, 10819.675409737682, 10823.296485728442, 10850.403227822466, 10859.40273284969, 10912.345259284428, 10945.56217751289, 10946.0, 10946.39794811285, 10789.892101078967, 10871.945640271828, 10877.67366978988, 10880.401577913211, 10922.617708380169, 10762.0, 10862.728431789268, 10881.673549793519, 10891.128709703284, 10895.891041089568, 43394.56605433937, 43805.0, 43842.12315753699, 43914.56085439137, 43926.34110488345, 3288.3423315337027, 3463.2554000889677, 3469.774464659789, 3599.4420864766003, 3769.41574056019, 33345.49981750276, 33595.83202083974, 33608.49587256169, 33949.490757638654, 34440.0, 4414.800698745416, 4415.359772832953, 4425.623821975136, 4551.544845515455, 4597.60920321773, 374298.0, 374486.0, 375556.0, 376411.99999999726, 377451.0, 32389.54247058888, 33182.502262466085, 62293.0, 63184.68407658024, 63432.36567634265, 36611.084722881606, 36638.63361366405, 36690.08274793098, 36725.81637091797, 36765.81617091951, 15431.305591248196, 15517.922410387875, 15589.0, 15600.843991560167, 15613.531594052196, 12097.758044839054, 12987.350632468528, 13331.66670833237, 13345.73308533824, 13578.796318055238, 8519.74440766778, 8523.0, 8529.317654587618, 8544.61549230286, 8604.956975215084, 3231.5152727091127, 3313.3042061166943, 3318.0543545090018, 3341.5154802553493, 3359.2699759623815, 1031.6595523477235, 1034.99482502587, 1066.4347895615335, 1124.1905827803969, 1196.0969468051564, 32246.0, 32726.672733272837, 32727.67272327294, 32752.181195470304, 33001.83499082537, 1119.9664010079712, 1129.5030186717818, 1155.4742592120565, 1155.9190856639955, 1222.7676741419102, 17162.828371716372, 17595.73606395905, 18235.17941692627, 18304.0, 18941.0, 587872.0, 587935.0, 587974.0, 591834.0, 593350.0, 63013.0, 64463.355366446674, 64533.67733161304, 64559.354406456274, 64614.676926615066, 1298.5000774701618, 1383.4120498787975, 1406.0368647476512, 1415.2074838090703, 1432.9426822926966, 32186.35627287488, 34587.827060865544, 34652.82673586616, 34948.65051349454, 35040.0, 52327.215091774415, 52739.0, 53224.4677553235, 53252.201216981004, 53314.0, 392466.0, 394870.0, 395691.0, 398639.0, 399159.0, 25754.742452575985, 28036.57945130784, 28683.28291792722, 28979.855100724362, 30212.0, 49232.75383623059, 49649.25526117039, 50077.0, 51030.48969510257, 51172.488275116775, 40867.1826563473, 41273.79363103165, 41536.79231603823, 41887.0, 42730.78634606807, 41305.58694413018, 41510.37734433926, 41731.0, 41749.582504175785, 42402.78798605987, 40985.795071024455, 41343.37984930289, 42187.7890610545, 42783.0, 42813.5718642822, 34943.0, 36987.63012369842, 37107.257854843294, 37726.62273377341, 37729.62270377371, 27385.446352635678, 28062.184374689918, 28303.29241768972, 28348.57477137886, 30482.695173047985], "value": 19794.087358854187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.4869528745618, 377.6348500171878, 385.62786910631235, 390.03855496201857, 399.73817149766893, 18194.454166375013, 20153.798462015377, 20863.26978555751, 20872.791272087274, 20898.582028359422, 17127.571810704707, 17366.0, 17648.735268970977, 18947.52631184221, 19652.115654795532, 5622.8313150605545, 5668.914966275509, 5768.74040668171, 5777.046787280094, 5810.070388737803, 5510.421405752399, 5597.160425936101, 5602.747876345554, 5628.746706398222, 5665.3201615806165, 5456.072467680504, 5576.7211639417865, 5578.135389014709, 5583.3858275589655, 5771.134329850535, 5674.602777805535, 5709.429057094258, 5713.285839270091, 5733.971330143343, 5734.225879506255, 5442.727863606804, 5622.32532096151, 5628.831135065954, 5630.549556035548, 5636.4927156556105, 12284.078694097889, 12286.324252166127, 12296.446659900326, 14781.556553303419, 23357.883210583837, 2117.491801967532, 2213.2806837777625, 2258.898349574257, 2290.599145149609, 2432.635104734304, 9223.769405764842, 9325.0, 10074.949625251827, 11460.885391146065, 15016.549503514912, 1799.3702204228546, 1831.716084006981, 1842.2538871756826, 1949.1131535151615, 2003.24878170686, 76757.0, 79854.600726996, 81000.18999809986, 81130.59434702789, 82063.0, 10458.843117353246, 10911.618093366695, 11280.548778048786, 11311.377874216832, 11348.868585260372, 15672.0, 15742.763858542132, 15817.05097694142, 15824.44614438489, 15837.762433563506, 8314.376421768391, 8433.19884610961, 8460.534670593177, 8627.137286271449, 8632.525211113449, 8274.29668478174, 8303.294219991369, 8305.792355191048, 8437.270359576272, 8487.915120848837, 4695.647826413033, 4790.305405716152, 4817.927731084036, 4823.710577365369, 4955.776990035385, 1907.9506271550592, 1919.2610844824842, 1935.6128774245208, 1949.3664559018375, 1973.1712680674152, 870.9869351959599, 912.6486302773347, 983.960641574329, 988.2588058955783, 1057.5346847387125, 19107.426777196706, 19257.4222773317, 19520.51198720015, 19565.608687826163, 19730.90134549318, 964.0455948610964, 969.0745338202, 998.2263745597076, 1022.3763504262394, 1028.654667358424, 10283.53724082418, 10941.945290273658, 10970.067544258827, 10991.450427478594, 10995.701289613084, 473993.0, 474779.0, 486175.0, 511266.0, 512948.0, 60113.39886601078, 76550.0, 98669.0, 99319.50340248397, 99524.5023774891, 989.3569180033036, 1028.3007554862766, 1067.0823092140752, 1067.957281708723, 1085.4138765066775, 17354.132293385534, 17773.644527109645, 18281.177347019682, 18282.0, 18656.253749849853, 42183.83621228359, 42786.35820462633, 43117.35323970205, 43480.782596086814, 44611.0, 61743.382566173765, 63403.682981584796, 63818.36181638124, 64343.67828161016, 64584.35415645783, 7148.678309475982, 8449.831003379775, 8671.08953559867, 8714.302855771397, 8786.077461866667, 7613.6573854175585, 10972.62842144732, 12652.936735316265, 13997.860021399656, 14080.36638351256, 11634.418279085843, 12117.878821211674, 12492.43784029739, 12588.370581471074, 12728.299943503196, 11979.14323279893, 11987.100967427303, 12323.44544495482, 12352.75294494123, 12745.10784245094, 12221.511139554319, 12340.753184936431, 12549.184303020274, 12576.811347829973, 12746.171498852545, 7397.519161254501, 7642.961785191039, 7980.600969951363, 8036.799080023046, 8290.295324897452, 8324.0, 11974.341411222467, 12491.0, 12493.375331233583, 12687.11190216676], "value": 9149.931908963394, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.263405127181, 1402.7054318593096, 1474.5207807462573, 1606.5501659535335, 1825.5892424204555, 8664.306855451565, 9054.773630659236, 9063.728088157357, 9279.0, 9295.116963888437, 9040.14118658728, 9270.397424167424, 9281.953590232039, 9283.025282345341, 9294.907050929489, 2066.111572024029, 2303.0327262549686, 2356.3048900574286, 2357.1985524921524, 2423.660687503751, 2426.3448868805413, 2434.3305590962536, 2435.987820060906, 2437.4393889405446, 2439.109724950389, 2326.906923723049, 2352.9764702352927, 2353.364591560278, 2355.6348765941307, 2360.4570948681812, 2052.1586149678633, 2232.944176395587, 2296.9540609187725, 2304.308707387777, 2308.2382813671447, 2340.2628172125724, 2345.753695861944, 2356.2931120663734, 2362.9172978945826, 2364.3616223619697, 8707.34694897879, 8735.912640873574, 8737.169968852948, 8741.694040708544, 8755.25580325673, 5240.292560504359, 5370.382406023318, 5376.327959005124, 5379.031774280628, 5381.977424289373, 3334.7498937579535, 3411.4200585900207, 3462.6883580477624, 3501.9649803501893, 3511.7892926424497, 971.815355082532, 973.776031512756, 990.1929927109383, 1002.7793885345285, 1063.4576366053368, 12843.0, 12983.415746291439, 12994.740105197938, 13003.154794938393, 13029.413676384585, 4536.56902594253, 4586.289125185602, 4685.297205419146, 4707.199776038082, 4719.575238228507, 9938.204943604494, 9950.054744799378, 9956.751081222883, 9963.651272205438, 9967.252456065824, 5399.244105825188, 5414.972925135349, 5420.647657902224, 5422.864428389243, 5425.864353391118, 4487.304467807495, 4804.639652026112, 4980.12847751642, 4983.551480366785, 5061.696298222045, 3900.6294402031763, 3944.7041471889725, 3978.9853587335047, 4057.0263136846943, 4064.5732198119363, 1128.4357821089454, 1143.8055530559825, 1172.3903570143477, 1193.0574845871838, 1212.9575464858688, 910.267234875918, 1009.1976878381704, 1023.3655133817081, 1033.7415646088477, 1049.454283772434, 6092.421219984094, 6202.472789812917, 6228.906566401508, 6361.554691171574, 6405.29541750407, 441.94696636403836, 448.4618457850592, 450.4796959511769, 455.99088018240116, 1053.3363980692268, 4358.0, 4405.603495685404, 4452.888677783081, 4515.1195516873895, 4516.864494065183, 11135.944320278346, 11440.427978601037, 11520.76958460826, 11531.538738450536, 11537.88462115385, 10848.511816968256, 11504.194706370474, 11532.769344613062, 11543.595974140864, 11545.307281563133, 8097.2307630774985, 8385.538795366314, 8393.160683931681, 8415.747527574182, 8464.238218560358, 6202.658853763086, 6477.643729594917, 6505.837354065997, 6537.5096867734155, 6571.310012448624, 10439.634612788515, 10564.313319634199, 10713.089387402159, 10713.464326783786, 10732.51703673321, 10063.798724025624, 10433.843492347469, 10437.425941573287, 10724.302920310154, 10815.783684326427, 7181.35367816899, 7832.0601527817025, 8016.038075430985, 8114.431989760897, 8250.102476430575, 8884.955575222082, 8886.688965886164, 8952.104789520996, 8954.328425367994, 8971.32715046361, 7458.328750412489, 7670.0, 7721.691132354643, 7752.0, 7821.178776228414, 7562.546247225184, 7593.8860917087395, 7679.769606911802, 7733.729319473791, 7843.764687059397, 6803.591784492946, 6817.93182068173, 6874.518783685294, 6907.205671347659, 6908.171019477695, 5240.711760853037, 5256.080185967364, 5343.145096784496, 5443.673379597237, 5474.781008759605, 5247.658902171346, 5361.919571206357, 5437.34751829783, 5482.17767334903, 5617.4101719320515], "value": 4653.745911931136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.93605310911266, 322.9741620670345, 323.9497877828936, 325.29573473430025, 329.6061206857806, 5395.649282796616, 5472.534834539064, 5510.393856675762, 5518.19986102015, 5541.9999999999945, 5101.897962040757, 5227.3465816772905, 5293.894122117565, 5368.570514358852, 5404.189371594262, 4176.582341765829, 4228.302330115527, 4252.425922500453, 4256.148575369716, 4259.5740425957465, 4269.060806622538, 4273.29490634045, 4275.0, 4285.914281714364, 4289.742615443068, 4043.4137050127715, 4072.1855628874346, 4230.365445183216, 4241.300185469394, 4252.978735106336, 4175.248455278049, 4190.559991200941, 4195.727277726938, 4208.095259519203, 4253.787310634471, 3727.459518369821, 3730.6269373062746, 3740.0836794985403, 3743.0, 3744.64425879541, 5007.023630392075, 5136.922946155811, 5138.841686491683, 5145.459726728677, 5220.947790522084, 4736.016309724851, 4756.904861902743, 4776.161177946481, 4796.400449943757, 4799.896023914484, 5611.130274807411, 5613.46672066153, 5614.29821272341, 5634.267545219095, 5652.0, 3823.1397935464574, 4041.2321658884716, 4057.5942405759483, 4062.390641403813, 4073.6333729964445, 5840.503557197643, 5879.235699359056, 5894.115882617599, 5901.73442195098, 5904.704764761745, 4118.085090432963, 4138.875833724993, 4141.233871733739, 4146.523149837747, 4146.792660366969, 4094.6314831665295, 4122.958770412258, 4139.668826493873, 4140.710150289451, 4201.97899010503, 4714.15145273854, 4717.433907931001, 4724.267738500538, 4752.405949256343, 4773.427188737304, 5613.85624050533, 5632.155176723524, 5645.463680950304, 5654.321481422256, 5688.866674996876, 1326.562234462625, 1380.9033367664367, 1402.4810819996667, 1432.0476882872981, 1462.817147856518, 892.2237653241722, 893.7497500699815, 900.3112618846587, 906.7370462565914, 907.9105073911201, 4914.238293064581, 4914.41992953695, 4926.482719314492, 4933.45731969483, 4964.751762411865, 5188.792448302102, 5210.567094049166, 5223.623251420595, 5248.819015721432, 5274.841754747364, 4516.345129956138, 4829.879253018703, 4882.36529251195, 4896.596865178738, 4928.211486162197, 2734.4394399148127, 2743.0, 2772.861356932145, 2783.5685468752376, 2789.302674331417, 5242.580593552507, 5277.205750044926, 5282.445343238982, 5288.497592728685, 5418.756155972991, 5119.257707632372, 5198.92201616976, 5199.80404506958, 5588.776448942078, 5997.850053748604, 6055.182550355646, 6063.242094738158, 6097.048944337858, 6105.664188469676, 6117.727016046394, 5799.797007104731, 5816.41835816415, 5935.970320148372, 5942.583014766613, 5959.91060134107, 5408.540274076748, 5725.080209093309, 5751.194832723339, 5756.827295181151, 5774.884502310014, 5945.851353716192, 6015.157877897011, 6025.246844144482, 6034.0, 6073.969630151821, 3971.1859068891386, 4077.2049450356894, 4097.836086556505, 4105.507339119325, 4150.107726838623, 5495.313085864267, 5770.807093304517, 5818.77805660799, 5829.94170058294, 5850.327212370632, 5511.917321240105, 5689.146628005832, 5705.258316419005, 5720.914186287292, 5721.884232574775, 5667.574931880043, 5688.203651488712, 5697.3448053474385, 5706.543476521952, 5715.714214289185, 5413.891722165614, 5556.666600004012, 5562.2490963721, 5623.550115990793, 5700.287464066992, 5723.3418156912485, 5753.4246575343805, 5787.94212057874, 5802.274715660543, 5804.6807425592, 5565.749541270571, 5698.6010979232715, 5712.5429965601825, 5717.456841600041, 5723.627964182481], "value": 4229.010988675718, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.2559549675092, 723.6020188896106, 726.0923845193508, 739.4601940583377, 754.3022703998802, 14449.78325325121, 14477.05899116558, 14479.05886117403, 14654.41382344705, 14721.852781472182, 13336.199828010302, 13859.584212473643, 13860.514881979132, 14291.785623215661, 14330.28348582572, 819.9221073997961, 820.7619790260832, 823.1768231768232, 824.3611201318965, 825.859603867341, 686.6120641837371, 796.0, 809.7125520440218, 820.6717313074788, 820.8563501387263, 858.0689951402735, 858.1590041759049, 859.96130174142, 860.101194252005, 860.9741707748777, 700.6146619359332, 846.3990566697621, 850.196564246787, 850.7702920211539, 851.0255757158081, 603.2128072864909, 621.2234706616729, 621.3724138619981, 621.8414304352407, 624.6751689121677, 14366.569002929824, 14443.711125777425, 14454.783178252335, 14466.349014294383, 14466.855331446655, 12426.875731242753, 12883.162594431333, 12946.0, 13162.560402067877, 13218.405171767294, 12068.758624827542, 12102.576409825702, 12225.755484890253, 12369.93815030928, 12375.443105060203, 4266.296061149907, 4305.612494875445, 4336.371226172188, 4363.2364336241035, 4382.0797632497215, 59460.702696486675, 60369.0, 60479.0, 60822.39177608211, 61074.0, 10507.0, 10544.000237879372, 10652.147828173727, 10728.570857165781, 10765.0, 17960.281588736303, 18044.90977545104, 18052.81947180538, 18060.367887123888, 18124.0, 7465.776026719207, 7745.109312429143, 7822.530648161129, 7981.8403631928195, 8065.112837587861, 4582.541745825458, 4607.424071991001, 4658.34783130362, 4772.260299653559, 4788.4253889533475, 3339.13182572528, 3414.7951122932704, 3426.0920855973186, 3431.519587257786, 3439.3121375724954, 579.7796837201836, 590.1236663554618, 690.9378155965987, 725.4522835259345, 728.5956294256632, 562.3532937122342, 640.1326202994968, 714.2857142857143, 716.4197000429668, 721.3471808013755, 9805.705828825146, 9927.50362481873, 10005.14956228714, 10029.197664186848, 10195.235357348229, 663.6217356106972, 670.2125003121313, 734.5409119300438, 742.2651574940878, 758.4956004257107, 4711.246200607886, 4841.757912104381, 4929.309896614477, 4957.380327459067, 5089.847304580869, 71888.0, 72724.0, 73603.0, 73674.0, 76046.0, 63420.0, 63641.68179159167, 64803.0, 65525.0, 66793.0, 56943.0, 57247.713761430925, 57616.4238357611, 58007.0, 59092.0, 9466.567238379319, 9592.0, 9637.518124093627, 9641.517924103908, 9718.805623887341, 36194.0, 36590.353434095, 36648.81675591605, 36781.816090919376, 37863.81068094642, 46088.53911460842, 46471.0, 48721.512784871695, 50148.74925625348, 50575.49424505708, 12107.878921210675, 12160.513579457076, 12163.148579599343, 12380.62858114258, 12672.619821405373, 13401.597952061455, 13528.49722801285, 13610.727785444038, 13724.65688357799, 14042.508512202025, 9774.609015639579, 12351.69120771988, 12592.622221333375, 13172.736545269232, 13398.26309552945, 12567.37163141821, 12612.306323152316, 12760.170588911691, 12777.808332874829, 12887.93556032214, 12328.445219964944, 12355.62933112008, 12406.751864962831, 12885.549005784753, 13196.802047969097, 10014.39913605186, 10174.949125254327, 10466.267361284637, 10552.894471055191, 10558.996895294935, 10433.306362341165, 10660.946695266473, 10661.307015044307, 10710.410927398756, 10828.5127169276], "value": 6904.864006006956, "unit": "score"}], "name": "dromaeo_css", "value": 7144.504461712533, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 110.0, 109.0, 113.0, 110.0, 111.0, 111.0, 110.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 131.0, 203.0, 125.0, 125.0, 154.0, 128.0, 166.0, 124.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 159.0, 162.0, 161.0, 160.0, 161.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 142.0, 75.0, 76.0, 74.0, 75.0, 121.0, 75.0], "value": 86.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 180.0, 91.0, 92.0, 92.0, 128.0, 92.0, 93.0, 131.0], "value": 108.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 147.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 57.0, 56.0, 55.0, 56.0, 58.0, 123.0, 54.0, 54.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 62.0, 62.0, 61.0, 62.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 79.0, 78.0, 78.0, 78.0, 79.0, 78.0, 80.0, 79.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 120.0, 122.0, 125.0, 139.0, 119.0, 124.0, 119.0, 200.0, 119.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 158.0, 159.0, 162.0, 166.0, 159.0, 162.0, 163.0, 158.0, 163.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 73.0, 64.0, 65.0, 64.0, 62.0, 63.0, 61.0, 62.0, 62.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1524.4, "alertThreshold": 2.0}]} 13:03:52 INFO - Return code: 0 13:03:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:03:52 ERROR - # TBPL SUCCESS # 13:03:52 INFO - Running post-action listener: _resource_record_post_action 13:03:52 INFO - ##### 13:03:52 INFO - ##### Finished run-tests step (success) 13:03:52 INFO - ##### 13:03:52 INFO - Running post-run listener: _resource_record_post_run 13:03:52 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 538685440; Read time: 8; Write time: 465728 13:03:52 INFO - TinderboxPrint: CPU usage
13.2% 13:03:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 13:03:52 INFO - TinderboxPrint: I/O write bytes / time
538,685,440 / 465,728 13:03:52 INFO - TinderboxPrint: CPU idle
8,261.5 (86.8%) 13:03:52 INFO - TinderboxPrint: CPU user
1,210.7 (12.7%) 13:03:52 INFO - TinderboxPrint: Swap in / out
0 / 0 13:03:52 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247099392; Read time: 0; Write time: 271848 13:03:52 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291586048; Read time: 8; Write time: 193880 13:03:53 INFO - Running post-run listener: _upload_blobber_files 13:03:53 INFO - Blob upload gear active. 13:03:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:03:53 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 13:03:53 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'] 13:03:53 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 13:03:53 INFO - (blobuploader) - INFO - Open directory for files ... 13:03:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:03:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:03:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:03:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:03:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:03:57 INFO - (blobuploader) - INFO - Done attempting. 13:03:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:03:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:03:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:03:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:03:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:03:58 INFO - (blobuploader) - INFO - Done attempting. 13:03:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:03:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:03:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:03:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:03:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:03:59 INFO - (blobuploader) - INFO - Done attempting. 13:03:59 INFO - (blobuploader) - INFO - Iteration through files over. 13:03:59 INFO - Return code: 0 13:03:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:03:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:03:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/26b6175b98d043dad2fb0d4978a1b0392bc43afa36dd6d60db2f5ab3a706bfa8a2a2b1395bfe64917ca6919819a1e14ad3c2dcd81db9d2f824d6cad395811a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d225b32985604c1d45e51da5e0d33496a4859fc02b1425fa161b7b6d30f8c345231962882b16519093424ecfa836e95197c1959fdee1086bde64aa0666d12f3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/24dcde4778c499d077a05672e1a2973889ce094ea7cce60520b8ee981810592113fb25e4958898f0536019120ef7b7800eeb72e5c918250a7f4975a3616030a2"} 13:03:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:03:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:03:59 INFO - Contents: 13:03:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/26b6175b98d043dad2fb0d4978a1b0392bc43afa36dd6d60db2f5ab3a706bfa8a2a2b1395bfe64917ca6919819a1e14ad3c2dcd81db9d2f824d6cad395811a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d225b32985604c1d45e51da5e0d33496a4859fc02b1425fa161b7b6d30f8c345231962882b16519093424ecfa836e95197c1959fdee1086bde64aa0666d12f3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/24dcde4778c499d077a05672e1a2973889ce094ea7cce60520b8ee981810592113fb25e4958898f0536019120ef7b7800eeb72e5c918250a7f4975a3616030a2"} 13:03:59 INFO - Running post-run listener: copy_logs_to_upload_dir 13:03:59 INFO - Copying logs to upload dir... 13:03:59 INFO - mkdir: /builds/slave/test/build/upload/logs 13:03:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.362659 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-21 13:03:59.264481) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 13:03:59.267962) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979 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/26b6175b98d043dad2fb0d4978a1b0392bc43afa36dd6d60db2f5ab3a706bfa8a2a2b1395bfe64917ca6919819a1e14ad3c2dcd81db9d2f824d6cad395811a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d225b32985604c1d45e51da5e0d33496a4859fc02b1425fa161b7b6d30f8c345231962882b16519093424ecfa836e95197c1959fdee1086bde64aa0666d12f3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/24dcde4778c499d077a05672e1a2973889ce094ea7cce60520b8ee981810592113fb25e4958898f0536019120ef7b7800eeb72e5c918250a7f4975a3616030a2"} build_url:https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/RQ9yOqiXR0-fp_dH__yLsA/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/26b6175b98d043dad2fb0d4978a1b0392bc43afa36dd6d60db2f5ab3a706bfa8a2a2b1395bfe64917ca6919819a1e14ad3c2dcd81db9d2f824d6cad395811a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d225b32985604c1d45e51da5e0d33496a4859fc02b1425fa161b7b6d30f8c345231962882b16519093424ecfa836e95197c1959fdee1086bde64aa0666d12f3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/24dcde4778c499d077a05672e1a2973889ce094ea7cce60520b8ee981810592113fb25e4958898f0536019120ef7b7800eeb72e5c918250a7f4975a3616030a2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 13:03:59.292939) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 13:03:59.293283) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463859116.591957-177320979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004554 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 13:03:59.377505) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 13:03:59.377821) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 13:03:59.378131) ========= ========= Total master_lag: 0.17 =========