builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1463706257.9 results: success (0) buildid: 20160519173313 builduid: c60de48900454dc8870c6eb9adfdce9c revision: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:17.903792) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:17.904481) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:17.905402) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:17.957502) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:17.957824) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-19 18:04: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% 12.5M=0.001s 2016-05-19 18:04:18 (12.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.347043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.322565) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.322917) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017274 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.373654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.374053) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2013e1255bf82c59a3d90ebc69895f62e9a180f7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2013e1255bf82c59a3d90ebc69895f62e9a180f7 --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=9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-19 18:04:18,722 truncating revision to first 12 chars 2016-05-19 18:04:18,722 Setting DEBUG logging. 2016-05-19 18:04:18,722 attempt 1/10 2016-05-19 18:04:18,722 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2013e1255bf8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 18:04:18,962 unpacking tar archive at: fx-team-2013e1255bf8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.547944 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.935176) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.935539) ========= script_repo_revision: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.935987) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.936302) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 18:04:18.948587) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-19 18:04:18.948906) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:04:19 INFO - MultiFileLogger online at 20160519 18:04:19 in /builds/slave/test 18:04:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 18:04:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:19 INFO - {'append_to_log': False, 18:04:19 INFO - 'base_work_dir': '/builds/slave/test', 18:04:19 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:04:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:19 INFO - 'branch': 'Fx-Team-Non-PGO', 18:04:19 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:19 INFO - 'config_files': ('talos/linux_config.py',), 18:04:19 INFO - 'default_actions': ('clobber', 18:04:19 INFO - 'read-buildbot-config', 18:04:19 INFO - 'download-and-extract', 18:04:19 INFO - 'populate-webroot', 18:04:19 INFO - 'create-virtualenv', 18:04:19 INFO - 'install', 18:04:19 INFO - 'run-tests'), 18:04:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:19 INFO - 'download_minidump_stackwalk': True, 18:04:19 INFO - 'download_symbols': 'ondemand', 18:04:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:19 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:19 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:19 INFO - 'installer_path': 'installer.exe', 18:04:19 INFO - 'log_level': 'info', 18:04:19 INFO - 'log_name': 'talos', 18:04:19 INFO - 'log_to_console': True, 18:04:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:04:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:04:19 INFO - 'opt_config_files': (), 18:04:19 INFO - 'pip_index': False, 18:04:19 INFO - 'sps_profile': False, 18:04:19 INFO - 'sps_profile_interval': 0, 18:04:19 INFO - 'suite': 'dromaeojs', 18:04:19 INFO - 'system_bits': '32', 18:04:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:19 INFO - 'title': 'talos-linux64-ix-022', 18:04:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:19 INFO - 'use_talos_json': True, 18:04:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:19 INFO - 'work_dir': 'build'} 18:04:19 INFO - ##### 18:04:19 INFO - ##### Running clobber step. 18:04:19 INFO - ##### 18:04:19 INFO - Running pre-action listener: _resource_record_pre_action 18:04:19 INFO - Running main action method: clobber 18:04:19 INFO - rmtree: /builds/slave/test/build 18:04:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:19 INFO - Running post-action listener: _resource_record_post_action 18:04:19 INFO - ##### 18:04:19 INFO - ##### Finished clobber step (success) 18:04:19 INFO - ##### 18:04:19 INFO - ##### 18:04:19 INFO - ##### Running read-buildbot-config step. 18:04:19 INFO - ##### 18:04:19 INFO - Running pre-action listener: _resource_record_pre_action 18:04:19 INFO - Running main action method: read_buildbot_config 18:04:19 INFO - Using buildbot properties: 18:04:19 INFO - { 18:04:19 INFO - "project": "", 18:04:19 INFO - "product": "firefox", 18:04:19 INFO - "script_repo_revision": "production", 18:04:19 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:04:19 INFO - "repository": "", 18:04:19 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:04:19 INFO - "buildid": "20160519173313", 18:04:19 INFO - "pgo_build": "False", 18:04:19 INFO - "basedir": "/builds/slave/test", 18:04:19 INFO - "buildnumber": 1758, 18:04:19 INFO - "slavename": "talos-linux64-ix-022", 18:04:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:04:19 INFO - "platform": "ubuntu64_hw", 18:04:19 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:04:19 INFO - "branch": "fx-team", 18:04:19 INFO - "repo_path": "integration/fx-team", 18:04:19 INFO - "revision": "2013e1255bf82c59a3d90ebc69895f62e9a180f7", 18:04:19 INFO - "stage_platform": "linux64", 18:04:19 INFO - "builduid": "c60de48900454dc8870c6eb9adfdce9c", 18:04:19 INFO - "slavebuilddir": "test" 18:04:19 INFO - } 18:04:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:04:19 INFO - Running post-action listener: _resource_record_post_action 18:04:19 INFO - ##### 18:04:19 INFO - ##### Finished read-buildbot-config step (success) 18:04:19 INFO - ##### 18:04:19 INFO - ##### 18:04:19 INFO - ##### Running download-and-extract step. 18:04:19 INFO - ##### 18:04:19 INFO - Running pre-action listener: _resource_record_pre_action 18:04:19 INFO - Running main action method: download_and_extract 18:04:19 INFO - mkdir: /builds/slave/test/build/tests 18:04: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')]} 18:04:19 INFO - https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:04:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:04:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:04:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:21 INFO - Downloaded 1361 bytes. 18:04:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:04:21 INFO - Using the following test package requirements: 18:04:21 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:04:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:04:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:04:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'jsshell-linux-x86_64.zip'], 18:04:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:04:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:04:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:04:21 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:04:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:04:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:04:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:04:21 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')]} 18:04:21 INFO - https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:04:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:04:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:04:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:23 INFO - Downloaded 22813669 bytes. 18:04:23 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'] 18:04:23 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 18:04:25 INFO - Return code: 0 18:04:25 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 18:04:25 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')]} 18:04:25 INFO - https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:04:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:04:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:04:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:42 INFO - Downloaded 22813669 bytes. 18:04:42 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'] 18:04:42 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 18:04:43 INFO - Return code: 0 18:04: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')]} 18:04:43 INFO - https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:04:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:04:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:04:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:44 INFO - Downloaded 10840359 bytes. 18:04:44 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'] 18:04:44 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 18:04:44 INFO - Return code: 0 18:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:44 INFO - https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:04:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:04:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:04:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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 18:04:46 INFO - Downloaded 54811870 bytes. 18:04:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:04:46 INFO - mkdir: /builds/slave/test/properties 18:04:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:46 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:46 INFO - Contents: 18:04:46 INFO - build_url:https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:04:47 INFO - Running post-action listener: _resource_record_post_action 18:04:47 INFO - Running post-action listener: set_extra_try_arguments 18:04:47 INFO - ##### 18:04:47 INFO - ##### Finished download-and-extract step (success) 18:04:47 INFO - ##### 18:04:47 INFO - ##### 18:04:47 INFO - ##### Running populate-webroot step. 18:04:47 INFO - ##### 18:04:47 INFO - Running pre-action listener: _resource_record_pre_action 18:04:47 INFO - Running main action method: populate_webroot 18:04:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:47 INFO - '--tppagecycles', 18:04:47 INFO - '7'], 18:04:47 INFO - 'tests': ['tsvgm']}}, 18:04:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['damp', 'tps']}, 18:04:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'tests': ['damp', 'tps']}, 18:04:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['dromaeo_dom']}, 18:04:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['a11yr', 18:04:47 INFO - 'ts_paint', 18:04:47 INFO - 'tpaint', 18:04:47 INFO - 'sessionrestore', 18:04:47 INFO - 'sessionrestore_no_auto_restore', 18:04:47 INFO - 'tabpaint']}, 18:04:47 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:47 INFO - 'ts_paint', 18:04:47 INFO - 'tpaint', 18:04:47 INFO - 'sessionrestore', 18:04:47 INFO - 'sessionrestore_no_auto_restore', 18:04:47 INFO - 'tabpaint']}, 18:04:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:04:47 INFO - 'ts_paint', 18:04:47 INFO - 'tpaint', 18:04:47 INFO - 'sessionrestore', 18:04:47 INFO - 'sessionrestore_no_auto_restore', 18:04:47 INFO - 'tabpaint']}, 18:04:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:04:47 INFO - 'ts_paint', 18:04:47 INFO - 'tpaint', 18:04:47 INFO - 'sessionrestore', 18:04:47 INFO - 'sessionrestore_no_auto_restore', 18:04:47 INFO - 'tabpaint']}, 18:04:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['a11yr', 18:04:47 INFO - 'ts_paint', 18:04:47 INFO - 'tpaint', 18:04:47 INFO - 'sessionrestore', 18:04:47 INFO - 'sessionrestore_no_auto_restore', 18:04:47 INFO - 'tabpaint']}, 18:04:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['a11yr', 18:04:47 INFO - 'ts_paint', 18:04:47 INFO - 'tpaint', 18:04:47 INFO - 'sessionrestore', 18:04:47 INFO - 'sessionrestore_no_auto_restore', 18:04:47 INFO - 'tabpaint']}, 18:04:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['tsvgx', 18:04:47 INFO - 'tsvgr_opacity', 18:04:47 INFO - 'tart', 18:04:47 INFO - 'tscrollx', 18:04:47 INFO - 'cart']}, 18:04:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:47 INFO - 'tsvgr_opacity', 18:04:47 INFO - 'tart', 18:04:47 INFO - 'tscrollx', 18:04:47 INFO - 'cart']}, 18:04:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'talos_options': ['--disable-e10s'], 18:04:47 INFO - 'tests': ['tp5o']}, 18:04:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'tests': ['tp5o']}, 18:04:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'talos_options': ['--disable-e10s', 18:04:47 INFO - '--xperf_path', 18:04:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:47 INFO - 'tests': ['tp5n']}, 18:04:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:47 INFO - 'talos_options': ['--xperf_path', 18:04:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:47 INFO - 'tests': ['tp5n']}}, 18:04:47 INFO - 'talos.zip': {'path': '', 18:04:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:47 INFO - Running post-action listener: _resource_record_post_action 18:04:47 INFO - ##### 18:04:47 INFO - ##### Finished populate-webroot step (success) 18:04:47 INFO - ##### 18:04:47 INFO - ##### 18:04:47 INFO - ##### Running create-virtualenv step. 18:04:47 INFO - ##### 18:04:47 INFO - Running pre-action listener: _resource_record_pre_action 18:04:47 INFO - Running main action method: create_virtualenv 18:04:47 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:47 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 18:04:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:04:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:47 INFO - Using real prefix '/usr' 18:04:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:47 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:49 INFO - Installing pip.................done. 18:04:49 INFO - Return code: 0 18:04:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04: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')]} 18:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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')]} 18:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:49 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:04:49 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 18:04:49 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 18:04:49 INFO - Using env: {'DISPLAY': ':0', 18:04:49 INFO - 'HOME': '/home/cltbld', 18:04:49 INFO - 'LANG': 'en_US.UTF-8', 18:04:49 INFO - 'LANGUAGE': 'en_US:en', 18:04:49 INFO - 'LOGNAME': 'cltbld', 18:04:49 INFO - 'MAIL': '/var/mail/cltbld', 18:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:49 INFO - 'MOZ_NO_REMOTE': '1', 18:04:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:49 INFO - 'NO_EM_RESTART': '1', 18:04:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:49 INFO - 'PWD': '/builds/slave/test', 18:04:49 INFO - 'SHELL': '/bin/bash', 18:04:49 INFO - 'SHLVL': '1', 18:04:49 INFO - 'TERM': 'linux', 18:04:49 INFO - 'TMOUT': '86400', 18:04:49 INFO - 'USER': 'cltbld', 18:04:49 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:49 INFO - '_': '/tools/buildbot/bin/python'} 18:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:50 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 18:04:50 INFO - Cleaning up... 18:04:50 INFO - Return code: 0 18:04:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:50 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:04:50 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 18:04:50 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 18:04:50 INFO - Using env: {'DISPLAY': ':0', 18:04:50 INFO - 'HOME': '/home/cltbld', 18:04:50 INFO - 'LANG': 'en_US.UTF-8', 18:04:50 INFO - 'LANGUAGE': 'en_US:en', 18:04:50 INFO - 'LOGNAME': 'cltbld', 18:04:50 INFO - 'MAIL': '/var/mail/cltbld', 18:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:50 INFO - 'MOZ_NO_REMOTE': '1', 18:04:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:50 INFO - 'NO_EM_RESTART': '1', 18:04:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:50 INFO - 'PWD': '/builds/slave/test', 18:04:50 INFO - 'SHELL': '/bin/bash', 18:04:50 INFO - 'SHLVL': '1', 18:04:50 INFO - 'TERM': 'linux', 18:04:50 INFO - 'TMOUT': '86400', 18:04:50 INFO - 'USER': 'cltbld', 18:04:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:50 INFO - '_': '/tools/buildbot/bin/python'} 18:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:50 INFO - Downloading/unpacking psutil>=3.1.1 18:04:50 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 18:04:50 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 18:04:50 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 18:04:50 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 18:04:50 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 18:04:50 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 18:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:04:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:52 INFO - Installing collected packages: psutil 18:04:52 INFO - Running setup.py install for psutil 18:04:52 INFO - building 'psutil._psutil_linux' extension 18:04:52 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 18:04:52 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 18:04:52 INFO - building 'psutil._psutil_posix' extension 18:04:52 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 18:04:52 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 18:04:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:52 INFO - Successfully installed psutil 18:04:52 INFO - Cleaning up... 18:04:52 INFO - Return code: 0 18:04:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:52 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:04:52 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 18:04:52 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 18:04:52 INFO - Using env: {'DISPLAY': ':0', 18:04:52 INFO - 'HOME': '/home/cltbld', 18:04:52 INFO - 'LANG': 'en_US.UTF-8', 18:04:52 INFO - 'LANGUAGE': 'en_US:en', 18:04:52 INFO - 'LOGNAME': 'cltbld', 18:04:52 INFO - 'MAIL': '/var/mail/cltbld', 18:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:52 INFO - 'MOZ_NO_REMOTE': '1', 18:04:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:52 INFO - 'NO_EM_RESTART': '1', 18:04:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:52 INFO - 'PWD': '/builds/slave/test', 18:04:52 INFO - 'SHELL': '/bin/bash', 18:04:52 INFO - 'SHLVL': '1', 18:04:52 INFO - 'TERM': 'linux', 18:04:52 INFO - 'TMOUT': '86400', 18:04:52 INFO - 'USER': 'cltbld', 18:04:52 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:52 INFO - '_': '/tools/buildbot/bin/python'} 18:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:04:55 INFO - Installing collected packages: mozsystemmonitor 18:04:55 INFO - Running setup.py install for mozsystemmonitor 18:04:55 INFO - Successfully installed mozsystemmonitor 18:04:55 INFO - Cleaning up... 18:04:55 INFO - Return code: 0 18:04:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:04:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:04:55 INFO - Using env: {'DISPLAY': ':0', 18:04:55 INFO - 'HOME': '/home/cltbld', 18:04:55 INFO - 'LANG': 'en_US.UTF-8', 18:04:55 INFO - 'LANGUAGE': 'en_US:en', 18:04:55 INFO - 'LOGNAME': 'cltbld', 18:04:55 INFO - 'MAIL': '/var/mail/cltbld', 18:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:55 INFO - 'MOZ_NO_REMOTE': '1', 18:04:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:55 INFO - 'NO_EM_RESTART': '1', 18:04:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:55 INFO - 'PWD': '/builds/slave/test', 18:04:55 INFO - 'SHELL': '/bin/bash', 18:04:55 INFO - 'SHLVL': '1', 18:04:55 INFO - 'TERM': 'linux', 18:04:55 INFO - 'TMOUT': '86400', 18:04:55 INFO - 'USER': 'cltbld', 18:04:55 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:55 INFO - '_': '/tools/buildbot/bin/python'} 18:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:55 INFO - Downloading/unpacking blobuploader==1.2.4 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04:57 INFO - Downloading blobuploader-1.2.4.tar.gz 18:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:04:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:04:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 INFO - Downloading docopt-0.6.1.tar.gz 18:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:04:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:04:57 INFO - Running setup.py install for blobuploader 18:04:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:04:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:04:58 INFO - Running setup.py install for requests 18:04:58 INFO - Running setup.py install for docopt 18:04:58 INFO - Successfully installed blobuploader requests docopt 18:04:58 INFO - Cleaning up... 18:04:58 INFO - Return code: 0 18:04:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:58 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:04:58 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 18:04:58 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 18:04:58 INFO - Using env: {'DISPLAY': ':0', 18:04:58 INFO - 'HOME': '/home/cltbld', 18:04:58 INFO - 'LANG': 'en_US.UTF-8', 18:04:58 INFO - 'LANGUAGE': 'en_US:en', 18:04:58 INFO - 'LOGNAME': 'cltbld', 18:04:58 INFO - 'MAIL': '/var/mail/cltbld', 18:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:58 INFO - 'MOZ_NO_REMOTE': '1', 18:04:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:58 INFO - 'NO_EM_RESTART': '1', 18:04:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:58 INFO - 'PWD': '/builds/slave/test', 18:04:58 INFO - 'SHELL': '/bin/bash', 18:04:58 INFO - 'SHLVL': '1', 18:04:58 INFO - 'TERM': 'linux', 18:04:58 INFO - 'TMOUT': '86400', 18:04:58 INFO - 'USER': 'cltbld', 18:04:58 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:58 INFO - '_': '/tools/buildbot/bin/python'} 18:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:58 INFO - Running setup.py (path:/tmp/pip-UVbYzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:59 INFO - Running setup.py (path:/tmp/pip-rLZhEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:59 INFO - Running setup.py (path:/tmp/pip-fp6L5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:59 INFO - Running setup.py (path:/tmp/pip-K_KuI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:59 INFO - Running setup.py (path:/tmp/pip-sLsKKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:59 INFO - Running setup.py (path:/tmp/pip-BYkZUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:59 INFO - Running setup.py (path:/tmp/pip-7ZdYXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:59 INFO - Running setup.py (path:/tmp/pip-mJV61z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:59 INFO - Running setup.py (path:/tmp/pip-4vASnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:59 INFO - Running setup.py (path:/tmp/pip-1g9dUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:59 INFO - Running setup.py (path:/tmp/pip-C7j6ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:59 INFO - Running setup.py (path:/tmp/pip-5HJysO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:59 INFO - Running setup.py (path:/tmp/pip-c93X9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:59 INFO - Running setup.py (path:/tmp/pip-Rdmk8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:59 INFO - Running setup.py (path:/tmp/pip-A6V6Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:00 INFO - Running setup.py (path:/tmp/pip-EnYFHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:00 INFO - Running setup.py (path:/tmp/pip-PEyi5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:00 INFO - Running setup.py install for manifestparser 18:05:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozcrash 18:05:00 INFO - Running setup.py install for mozdebug 18:05:00 INFO - Running setup.py install for mozdevice 18:05:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozfile 18:05:00 INFO - Running setup.py install for mozhttpd 18:05:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozinfo 18:05:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozInstall 18:05:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozleak 18:05:01 INFO - Running setup.py install for mozlog 18:05:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for moznetwork 18:05:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozprocess 18:05:01 INFO - Running setup.py install for mozprofile 18:05:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozrunner 18:05:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozscreenshot 18:05:01 INFO - Running setup.py install for moztest 18:05:01 INFO - Running setup.py install for mozversion 18:05:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:01 INFO - Cleaning up... 18:05:01 INFO - Return code: 0 18:05:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:01 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:05:01 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 18:05:01 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 18:05:01 INFO - Using env: {'DISPLAY': ':0', 18:05:01 INFO - 'HOME': '/home/cltbld', 18:05:01 INFO - 'LANG': 'en_US.UTF-8', 18:05:01 INFO - 'LANGUAGE': 'en_US:en', 18:05:01 INFO - 'LOGNAME': 'cltbld', 18:05:01 INFO - 'MAIL': '/var/mail/cltbld', 18:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:01 INFO - 'MOZ_NO_REMOTE': '1', 18:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:01 INFO - 'NO_EM_RESTART': '1', 18:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:01 INFO - 'PWD': '/builds/slave/test', 18:05:01 INFO - 'SHELL': '/bin/bash', 18:05:01 INFO - 'SHLVL': '1', 18:05:01 INFO - 'TERM': 'linux', 18:05:01 INFO - 'TMOUT': '86400', 18:05:01 INFO - 'USER': 'cltbld', 18:05:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:01 INFO - '_': '/tools/buildbot/bin/python'} 18:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:02 INFO - Running setup.py (path:/tmp/pip-9mk0fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:02 INFO - Running setup.py (path:/tmp/pip-d3CEJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:02 INFO - Running setup.py (path:/tmp/pip-UWQzbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:02 INFO - Running setup.py (path:/tmp/pip-sqiIVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:02 INFO - Running setup.py (path:/tmp/pip-5Yi7a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:02 INFO - Running setup.py (path:/tmp/pip-ekFe77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:02 INFO - Running setup.py (path:/tmp/pip-5dNxt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:02 INFO - Running setup.py (path:/tmp/pip-hWPXnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:02 INFO - Running setup.py (path:/tmp/pip-4Xx0Fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:02 INFO - Running setup.py (path:/tmp/pip-W7g8Gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:02 INFO - Running setup.py (path:/tmp/pip-m3jqAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:03 INFO - Running setup.py (path:/tmp/pip-T7_uw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:03 INFO - Running setup.py (path:/tmp/pip-RJVV6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:03 INFO - Running setup.py (path:/tmp/pip-VFnIwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:03 INFO - Running setup.py (path:/tmp/pip-UgkdTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:03 INFO - Running setup.py (path:/tmp/pip-KRw_zI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:03 INFO - Running setup.py (path:/tmp/pip-d8BeXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:03 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)) 18:05:03 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)) 18:05:03 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)) 18:05:03 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)) 18:05:03 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)) 18:05:03 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)) 18:05:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:03 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 18:05:03 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 18:05:03 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 18:05:03 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 18:05:03 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 18:05:03 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 18:05:05 INFO - Downloading blessings-1.6.tar.gz 18:05:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:05 INFO - Installing collected packages: blessings 18:05:05 INFO - Running setup.py install for blessings 18:05:05 INFO - Successfully installed blessings 18:05:05 INFO - Cleaning up... 18:05:05 INFO - Return code: 0 18:05:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:05 INFO - Reading from file tmpfile_stdout 18:05:05 INFO - Current package versions: 18:05:05 INFO - argparse == 1.2.1 18:05:05 INFO - blessings == 1.6 18:05:05 INFO - blobuploader == 1.2.4 18:05:05 INFO - docopt == 0.6.1 18:05:05 INFO - manifestparser == 1.1 18:05:05 INFO - mozInstall == 1.12 18:05:05 INFO - mozcrash == 0.17 18:05:05 INFO - mozdebug == 0.1 18:05:05 INFO - mozdevice == 0.48 18:05:05 INFO - mozfile == 1.2 18:05:05 INFO - mozhttpd == 0.7 18:05:05 INFO - mozinfo == 0.9 18:05:05 INFO - mozleak == 0.1 18:05:05 INFO - mozlog == 3.2 18:05:05 INFO - moznetwork == 0.27 18:05:05 INFO - mozprocess == 0.22 18:05:05 INFO - mozprofile == 0.28 18:05:05 INFO - mozrunner == 6.11 18:05:05 INFO - mozscreenshot == 0.1 18:05:05 INFO - mozsystemmonitor == 0.3 18:05:05 INFO - moztest == 0.7 18:05:05 INFO - mozversion == 1.4 18:05:05 INFO - psutil == 3.1.1 18:05:05 INFO - requests == 1.2.3 18:05:05 INFO - wsgiref == 0.1.2 18:05:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:05 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')]} 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 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')]} 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:05:05 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 18:05:05 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 18:05:05 INFO - Using env: {'DISPLAY': ':0', 18:05:05 INFO - 'HOME': '/home/cltbld', 18:05:05 INFO - 'LANG': 'en_US.UTF-8', 18:05:05 INFO - 'LANGUAGE': 'en_US:en', 18:05:05 INFO - 'LOGNAME': 'cltbld', 18:05:05 INFO - 'MAIL': '/var/mail/cltbld', 18:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:05 INFO - 'MOZ_NO_REMOTE': '1', 18:05:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:05 INFO - 'NO_EM_RESTART': '1', 18:05:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:05 INFO - 'PWD': '/builds/slave/test', 18:05:05 INFO - 'SHELL': '/bin/bash', 18:05:05 INFO - 'SHLVL': '1', 18:05:05 INFO - 'TERM': 'linux', 18:05:05 INFO - 'TMOUT': '86400', 18:05:05 INFO - 'USER': 'cltbld', 18:05:05 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:05 INFO - '_': '/tools/buildbot/bin/python'} 18:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 INFO - Cleaning up... 18:05:06 INFO - Return code: 0 18:05:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:06 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:05:06 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 18:05:06 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 18:05:06 INFO - Using env: {'DISPLAY': ':0', 18:05:06 INFO - 'HOME': '/home/cltbld', 18:05:06 INFO - 'LANG': 'en_US.UTF-8', 18:05:06 INFO - 'LANGUAGE': 'en_US:en', 18:05:06 INFO - 'LOGNAME': 'cltbld', 18:05:06 INFO - 'MAIL': '/var/mail/cltbld', 18:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:06 INFO - 'MOZ_NO_REMOTE': '1', 18:05:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:06 INFO - 'NO_EM_RESTART': '1', 18:05:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:06 INFO - 'PWD': '/builds/slave/test', 18:05:06 INFO - 'SHELL': '/bin/bash', 18:05:06 INFO - 'SHLVL': '1', 18:05:06 INFO - 'TERM': 'linux', 18:05:06 INFO - 'TMOUT': '86400', 18:05:06 INFO - 'USER': 'cltbld', 18:05:06 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:06 INFO - '_': '/tools/buildbot/bin/python'} 18:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:06 INFO - Downloading/unpacking jsonschema 18:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:08 INFO - Downloading/unpacking functools32 (from jsonschema) 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:08 INFO - warning: no files found matching '*.txt' 18:05:08 INFO - no previously-included directories found matching 'build' 18:05:08 INFO - no previously-included directories found matching 'dist' 18:05:08 INFO - no previously-included directories found matching '.git*' 18:05:08 INFO - Installing collected packages: jsonschema, functools32 18:05:08 INFO - Running setup.py install for functools32 18:05:08 INFO - warning: no files found matching '*.txt' 18:05:08 INFO - no previously-included directories found matching 'build' 18:05:08 INFO - no previously-included directories found matching 'dist' 18:05:08 INFO - no previously-included directories found matching '.git*' 18:05:08 INFO - Successfully installed jsonschema functools32 18:05:08 INFO - Cleaning up... 18:05:08 INFO - Return code: 0 18:05:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:05:08 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')]} 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:08 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')]} 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:08 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 0x7fc5e1658f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c148e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', '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 18:05:08 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 18:05:08 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 18:05:08 INFO - Using env: {'DISPLAY': ':0', 18:05:08 INFO - 'HOME': '/home/cltbld', 18:05:08 INFO - 'LANG': 'en_US.UTF-8', 18:05:08 INFO - 'LANGUAGE': 'en_US:en', 18:05:08 INFO - 'LOGNAME': 'cltbld', 18:05:08 INFO - 'MAIL': '/var/mail/cltbld', 18:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:08 INFO - 'MOZ_NO_REMOTE': '1', 18:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:08 INFO - 'NO_EM_RESTART': '1', 18:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:08 INFO - 'PWD': '/builds/slave/test', 18:05:08 INFO - 'SHELL': '/bin/bash', 18:05:08 INFO - 'SHLVL': '1', 18:05:08 INFO - 'TERM': 'linux', 18:05:08 INFO - 'TMOUT': '86400', 18:05:08 INFO - 'USER': 'cltbld', 18:05:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:08 INFO - '_': '/tools/buildbot/bin/python'} 18:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:08 INFO - Downloading/unpacking flake8 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:05:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:05:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 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 18:05:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:05:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:11 INFO - no previously-included directories found matching 'docs/_build' 18:05:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:05:11 INFO - Running setup.py install for pep8 18:05:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:11 INFO - no previously-included directories found matching 'docs/_build' 18:05:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:05:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:05:11 INFO - Cleaning up... 18:05:11 INFO - Return code: 0 18:05:11 INFO - Running post-action listener: _resource_record_post_action 18:05:11 INFO - Running post-action listener: _start_resource_monitoring 18:05:11 INFO - Starting resource monitoring. 18:05:11 INFO - ##### 18:05:11 INFO - ##### Finished create-virtualenv step (success) 18:05:11 INFO - ##### 18:05:11 INFO - ##### 18:05:11 INFO - ##### Running install step. 18:05:11 INFO - ##### 18:05:11 INFO - Running pre-action listener: _resource_record_pre_action 18:05:11 INFO - Running main action method: install 18:05:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:11 INFO - Reading from file tmpfile_stdout 18:05:11 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:11 INFO - Reading from file tmpfile_stdout 18:05:11 INFO - Output received: 18:05:11 INFO - Usage: mozinstall [options] installer 18:05:11 INFO - Options: 18:05:11 INFO - -h, --help show this help message and exit 18:05:11 INFO - -d DEST, --destination=DEST 18:05:11 INFO - Directory to install application into. [default: 18:05:11 INFO - "/builds/slave/test"] 18:05:11 INFO - --app=APP Application being installed. [default: firefox] 18:05:11 INFO - mkdir: /builds/slave/test/build/application 18:05:11 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'] 18:05:11 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 18:05:27 INFO - Reading from file tmpfile_stdout 18:05:27 INFO - Output received: 18:05:27 INFO - /builds/slave/test/build/application/firefox/firefox 18:05:27 INFO - Running post-action listener: _resource_record_post_action 18:05:27 INFO - ##### 18:05:27 INFO - ##### Finished install step (success) 18:05:27 INFO - ##### 18:05:27 INFO - ##### 18:05:27 INFO - ##### Running run-tests step. 18:05:27 INFO - ##### 18:05:27 INFO - Running pre-action listener: _resource_record_pre_action 18:05:27 INFO - Running main action method: run_tests 18:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:27 INFO - Python 2.7.3 18:05:27 INFO - Return code: 0 18:05:27 INFO - grabbing minidump binary from tooltool 18:05: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')]} 18:05:27 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 0x1ca3b20>, '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 0x1ca4a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:27 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 18:05:27 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 18:05:27 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 18:05:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:27 INFO - Return code: 0 18:05:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:05:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:05:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:05:28 INFO - Return code: 0 18:05:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 18:05:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 18:05:28 INFO - Using env: {'DISPLAY': ':0', 18:05:28 INFO - 'HOME': '/home/cltbld', 18:05:28 INFO - 'LANG': 'en_US.UTF-8', 18:05:28 INFO - 'LANGUAGE': 'en_US:en', 18:05:28 INFO - 'LOGNAME': 'cltbld', 18:05:28 INFO - 'MAIL': '/var/mail/cltbld', 18:05:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:28 INFO - 'MOZ_NO_REMOTE': '1', 18:05:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:28 INFO - 'NO_EM_RESTART': '1', 18:05:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:28 INFO - 'PWD': '/builds/slave/test', 18:05:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:28 INFO - 'SHELL': '/bin/bash', 18:05:28 INFO - 'SHLVL': '1', 18:05:28 INFO - 'TERM': 'linux', 18:05:28 INFO - 'TMOUT': '86400', 18:05:28 INFO - 'USER': 'cltbld', 18:05:28 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000', 18:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:28 INFO - '_': '/tools/buildbot/bin/python'} 18:05:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 18:05:28 INFO - mozversion application_buildid: 20160519173313 18:05:28 INFO - mozversion application_changeset: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 18:05:28 INFO - mozversion application_display_name: Nightly 18:05:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:28 INFO - mozversion application_name: Firefox 18:05:28 INFO - mozversion application_remotingname: firefox 18:05:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:05:28 INFO - mozversion application_vendor: Mozilla 18:05:28 INFO - mozversion application_version: 49.0a1 18:05:28 INFO - mozversion platform_buildid: 20160519173313 18:05:28 INFO - mozversion platform_changeset: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 18:05:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:05:28 INFO - mozversion platform_version: 49.0a1 18:05:28 INFO - using testdate: 1463706328 18:05:28 INFO - actual date: 1463706328 18:05:28 INFO - starting webserver on 'localhost:51198' 18:05:28 INFO - SUITE-START | Running 2 tests 18:05:28 INFO - TEST-START | dromaeo_css 18:05:28 INFO - operating with platform_type : linux_ 18:05:28 INFO - Initialising browser for dromaeo_css test... 18:05:28 INFO - TEST-INFO | started process 8880 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBtuWKy/profile http://localhost:51198/getInfo.html) 18:05:33 INFO - PROCESS | 8880 | __metrics Screen width/height:1600/1200 18:05:33 INFO - PROCESS | 8880 | colorDepth:24 18:05:33 INFO - PROCESS | 8880 | Browser inner width/height: 1024/697 18:05:33 INFO - PROCESS | 8880 | __metrics 18:05:34 ERROR - PROCESS | 8880 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:05:35 INFO - TEST-INFO | 8880: exit 0 18:05:35 INFO - Browser initialized. 18:05:35 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:35 INFO - TEST-INFO | started process 8964 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBtuWKy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:47 INFO - PROCESS | 8964 | RSS: Main: 154247168 18:05:47 INFO - PROCESS | 8964 | 18:06:39 INFO - PROCESS | 8964 | 1463706399192 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] 18:08:50 INFO - PROCESS | 8964 | Cycle 1(1): loaded http://localhost:51198/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51198/tests/dromaeo/cssquery-ext.html) 18:08:51 INFO - PROCESS | 8964 | RSS: Main: 267612160 18:08:51 INFO - PROCESS | 8964 | 18:11:54 INFO - PROCESS | 8964 | Cycle 1(1): loaded http://localhost:51198/tests/dromaeo/cssquery-ext.html (next: http://localhost:51198/tests/dromaeo/cssquery-jquery.html) 18:11:54 INFO - PROCESS | 8964 | RSS: Main: 233365504 18:11:54 INFO - PROCESS | 8964 | 18:14:57 INFO - PROCESS | 8964 | Cycle 1(1): loaded http://localhost:51198/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51198/tests/dromaeo/cssquery-mootools.html) 18:14:57 INFO - PROCESS | 8964 | RSS: Main: 238198784 18:14:57 INFO - PROCESS | 8964 | 18:18:00 INFO - PROCESS | 8964 | Cycle 1(1): loaded http://localhost:51198/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51198/tests/dromaeo/cssquery-prototype.html) 18:18:00 INFO - PROCESS | 8964 | RSS: Main: 231399424 18:18:00 INFO - PROCESS | 8964 | 18:21:03 INFO - PROCESS | 8964 | Cycle 1(1): loaded http://localhost:51198/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51198/tests/dromaeo/cssquery-yui.html) 18:21:04 INFO - PROCESS | 8964 | RSS: Main: 215953408 18:21:04 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | Cycle 1(1): loaded http://localhost:51198/tests/dromaeo/cssquery-yui.html (next: http://localhost:51198/tests/dromaeo/cssquery-dojo.html) 18:24:06 INFO - PROCESS | 8964 | RSS: Main: 258916352 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | __start_tp_report 18:24:06 INFO - PROCESS | 8964 | _x_x_mozilla_page_load 18:24:06 INFO - PROCESS | 8964 | _x_x_mozilla_page_load_details 18:24:06 INFO - PROCESS | 8964 | |i|pagename|runs| 18:24:06 INFO - PROCESS | 8964 | |0;dojo.html;2642.4715056988603;2655.5883838005116;2690.582959641255;2694.5823397373415;2705.310145912792;4575.65682573807;4576.83981060663;4642.535746425356;4688.742119183444;4694.413198350207;4652.255639097745;4655.208614535529;4717.693349932252;4724.574788269055;4750.097481478517;4244.745315281078;4304.74171549708;4337.7397356158735;4361.3457981302745;4472.262076757347;4401.493828209765;4415.183191109639;4424.601785839274;4446.288593824988;4477.753723545204;4245;4280.892977675552;4295.699301048928;4317.654587632982;4353;4271.380649805792;4275.615194632467;4285.4214681017975;4391.604755572014;4404.647628189769;4293.141371725667;4296.5273819879785;4347.456567929009;4350.238708226079;4354.847580334673;4495.910081798345;4576.633869290482;4607.884802879921;4625.838095666669;4632.467266264389;5012.974935125338;5041.873953151201;5061.16490779021;5102.693838369672;5107.3360463139925;5055.898882022376;5057.544820966131;5074.340335756365;5081.79672813087;5120.02719483297;4586.793594288265;4709.646776491777;4735.313379559944;4748.430188377417;4788.994311194653;5244.659097158673;5253.3958594761325;5259.868503287448;5266.368035835724;5270.657407268515;4989.850304490871;4990.675606085593;4994.975025124923;5000.049990501793;5045.2432135180015;4938.086454005948;4957.727324997088;4988.27669987857;4996.525330093636;5124.436312005676;4944.1100601891285;4966.329545511382;5000.599952003831;5003.424606170337;5048.974755126201;4962.454130045692;4966.056449274626;4984.651074424755;4987.152184128707;5017.222330538772;4261.4673165854265;4290.871273861789;4291.935620965688;4294.677899157513;4345.608895199447;3760.304343696425;3767.05823544114;3794.9810250948567;3814.4659747635355;3859.1316953685196;4326.113146804897;4330.480342358874;4347.847825326098;4380.540043295472;4393.055493069006;4885.218365061573;4990.90018199634;5076.771545280398;5087.23691446286;5130.9230361544605;4284.871453856389;4316.115196384733;4327.6970612057485;4365.170617582649;4367.148406060787;4548.499665036843;4561.931571026437;4562.954370456319;4578.633709303248;4605;4645.07098580292;4734.360861283821;4734.431868175772;4749.287606858929;4764.523547645346;5069.467705890828;5078;5084.51697088776;5092.134337162617;5139.871503212449;4724.03157352735;4747.62019038483;4751.928721069117;4754.50077741846;4794.376731024944;4806.423229212517;4876.634252431011;4895.559399654048;4923.5814955729165;5033.471485494117;4993.750312484289;5008.974955125201;5009.248612707977;5010.398752149619;5055.797768089383;4851.272309153655;4859.635527335393;4863.659543831898;4872;4915.926261106158;4812.133815913029;4868.367112275381;4871.9269210962575;4907.460179380194;4928.359313289249;4818.68678535894;4853.490383509822;4895.388076490439;4896.975515122402;4908.239222920453;4869.6347773917805;4947.628927830355;4964.776585053609;4995.525425084611;4999.725015124203;4746.4541577719;4746.715197088186;4767.33257343979;4786.856394308037;4799.952000479951 18:24:06 INFO - PROCESS | 8964 | |1;ext.html;551.9254900588422;589.6845187824515;594.9881002379951;601.9969900150498;602.3043384890448;39626.80186599067;41148;41312;41386.17227655449;45003.549964500344;37432.81283593585;37847.62152378476;37912.24175516488;37931.43102853459;37947;7767.184445633212;7924.643391047388;7939.0870049944415;7946.721864734735;7947.205279472064;7746.38028957682;7827.217278272185;7847.489913155655;7852;7852.3325517331095;7860.68557257709;7888.56612886291;7903.920960790376;7907.6046197690175;7909.683612655487;7817.257360550739;7849.68601255949;7864.2922137007945;7869.134395216522;7880.211978802131;7698.923010769877;7799.766007019798;7801.414893882981;7804.687812487494;7805.141434442206;38611.227775444335;38882;38908.416373754415;38934.415983760264;39188;3013.442513135077;3031.848407579634;3055.1751027222526;3321.9003429897143;3381.7294616430627;29468.410631787458;29597.408051838844;29637.962671306617;30122.24694382636;30304.393912121635;3476.387178774272;3530.9117272068147;3551.058969373118;3556.199855032597;3582.2119133790525;267734;268929;269522;270541;271156;47917.520824792;48089.75955120202;48342.27486587634;48363.5163648359;49759.25361119586;53034;53597.73201133969;54295.45704542904;60580;60734.69632651808;13642.045056846124;13748.106373085717;13819.447222111205;13823.585292441243;13858.237796921065;10002.399856008662;10026.248031397674;11099.778004439866;11106.500207490682;11174.050205732441;14940.626484337978;15472.458463953708;15545.067295962279;15547.533573992798;15595.298211580279;6135.459759777732;6280.497560195265;6352.809415717536;6458.22501299847;6483.805485835433;829.8423299573062;956.8851737791509;989.3322007644811;996.6113215845826;1043.634727845248;27584;28730.138095857157;28795.136145915658;29136;29185.708142918724;990.277097718655;999.6251405722854;1005.6631028605353;1109.5339957217989;1133.9092872570175;15063.623409414853;15065.17141557203;15223.199154654394;15252.694946101015;15535.145566993699;372025;373395;375218;376930;377965;49243.753781230866;49484.257736133986;49659.751701241265;49705.75147124241;49788.75105624521;1038.776663017455;1195.551668124436;1197.0184448752113;1224.906092191341;1234.512367614787;25125.74874251307;29627.555586665785;29770.553441698827;29965;30119.247018824706;41335.586644133175;46635.53364466448;47810.28284575804;47838;52224.21663675124;337286;337405;338597;339418;339778;22115.341349399183;24410.633840492053;24601.507969840863;25024.74975250224;25546.616800748376;42273.78863105665;42985.140297194506;42992.570074298856;43898;44196.11607767891;36142;36403.63596364002;36584.268314633024;36936.63063369332;37504;35605.28789424249;35859.282814344086;36173.638263617024;36516;36530.38246541633;35596.11009724777;35658.64341356553;35675;35991.64008359988;36437.817810910776;31584.210394739395;32761.508577371835;32955;32974.83512582422;33193.50209746904;24482.265532034067;24605.50788984246;24915.127970520945;24962.875185623954;25147.49705005926 18:24:06 INFO - PROCESS | 8964 | |2;jquery.html;375.29819238024896;379.30019114733324;383.26413286489947;387.2622653844424;395.5451231084253;16015.359385624575;16290.42983495578;17370.21834017469;17400.30398784047;17420.912895435536;12027.338496382696;15812.920935395307;16598.419055333066;16623.41818036369;16638.750418743697;4605.861824145281;4786.545278198566;4818.445878723939;4825.7587120644;4841.612670986313;3437.415639341318;5146.099432599281;5411.648242864221;5419.99999999998;5509.559235261169;5445.128779395298;5471.288732464774;5476.123820188771;5492.615516913817;5504.724763761796;5244.921326180111;5560.110382338847;5567;5637.23897273871;5649;3411.4712219606;5054.469280725508;5361.624686271963;5367.382750983647;5453.400125986138;12447.564335248313;12595.433205505775;12615.053870959715;19890.502737431536;21298.82856442895;1900.2683966672791;2014.74815648044;2039.5920815836744;2142.7535833379047;2397.412633904687;9188.26493880488;9233.815323693556;9485.051494850528;9626.855597166048;12255.571055013032;1678.7397953317256;1712.1781544858538;1713.477389396246;1775.1390575570838;1780.6705759434547;66638.33361666418;68806.31193688049;68837.65581172112;69892.30107698908;69947.95078073884;10163.796724065478;10207.846882296772;10260.97390260968;10263.74340641475;10503.264771466076;14253.857461425254;14338.068025578512;14393.78409323861;14408.063475874034;14411.495597654031;7150.421358788853;7291.361785088881;7323.890141647879;7374.594397308092;7380.376317210205;6647;6692.96653516739;6763.086983257295;6875.3124687531845;7071.752488662872;4444.688871778944;4503.343027613723;4517.367568540407;4525.977370113194;4592.402987611656;1693.7120689482817;1746.9912650436665;1805.4493379519372;1858.2659849359427;1882.7646544181978;877.4515927545284;891.4829398948594;911.261877878907;929.762910457829;952.9904700953041;16246.593835153979;16823.15884205785;16846.410375636795;16976.660466790596;17140;874.3835595904872;882.6998820401094;886.4814083760944;905.4929239625831;935.9204467620328;9190.448573085636;9590.520473976412;9652.517374131265;9667.564959576835;9732.270079744048;337998;339356;370395;377657;378868;70884;72923;72945.27054729385;73610;73835;920.7744102694749;925.9629614815467;928.9628414863465;931.5575101826713;932.4032619123766;14732.484363047242;14814.185219813015;15102.39590416415;15153.166575837993;15254;33555.83222083874;34088.318233635684;34259.828700856335;34268.65731342655;34778.82610586931;42700;46058;46265;46893.765531172125;47285.2907206399;7524.435667324863;7685.807854803675;7819.257170568787;7830.804229894298;7877.7636670899965;8647.308215342884;11252.549898003988;11416.82874756896;11487.483063262007;11748.471318790504;9951.950240248752;9999.750006249902;10063.949680251551;10108.646197383056;10214.029667181605;9827.901720982698;10024.198064154703;10152.492375381054;10186.337888037255;10215.744606384602;10193.643222487177;10406.427646479517;10430.739231519272;10453.68638940833;10510.632127875488;7753.961230193813;7834.529928204326;7915.406344524299;7949.8410031800195;7987.960060199662;9944.950275248577;10029.648962286285;10101.646442374482;10117.696469105938;10270.178385729274 18:24:06 INFO - PROCESS | 8964 | |3;mootools.html;1292.3344477594046;1415.5470249520151;1463.626775172331;1574.8818838587108;1764.9382271620495;6761.154855643044;7596.544207347563;7828.412869034823;7838.294553490199;8039.356851451885;7765.06819181699;7919;7926.326262267699;7938.722144724936;7946.721864734735;1887.9433616991512;2146.162996431393;2229.5429436965464;2230.710007698997;2253.6281513550243;2255.548890221954;2272.9772702273012;2277.6241920083166;2279.3389916924157;2283.486215601493;2200.8239340852692;2201.7908298711595;2249.6513040478753;2251.4146321956246;2252.628316327804;1841.981580184201;2141.1221399226242;2174.4346469917778;2199.0544066051593;2200.548887478063;2191.660292654641;2201.8018378345864;2211.756706762255;2214.258223495115;2217.7671344508753;7812.843743125162;7851.2148785121035;7860.724874629418;7861.56761378124;7869.645865936047;4560.270356742905;4691.671582989192;4696.225122854725;4698.718076915396;4700.130475861976;2858.3568697043206;2905.5205891027954;2945.7937944343903;2980.582718419423;3019.0942717184757;879.3317079019927;892.8705337725993;892.8973168085688;899.0470101692227;906.2206502407923;10823.404712740796;10860.022597966221;10871.510782014828;10887.945560272226;10897.83653245202;4285.100128972888;4427.335899615084;4459.554044595515;4497.145542346943;4511.210538155809;9221.123993220634;9245.491497967676;9258.814823703487;9267.953660231655;9286.535673216313;4687.484376718627;4690.202665546865;4690.6012988895645;4690.6951048182445;4691.507391723888;3645.6172101929446;3979.5622481527007;4096.283150448659;4112.9588704112575;4164.979175104105;3718.2377612589353;3752.849886004584;3816.885493435201;3831.7700937943814;3861.7489863158803;1213.4600102954278;1229.0843321725192;1233.2045830439317;1245.6698974771512;1263.5261776833688;834.4492634860962;840.1682334488934;847.1147650705028;851.2636569367453;857.4169564696067;5088.898222035538;5257.08001099806;5281.683099013994;5342.439043900412;5345.893082138336;414.9066460046465;419.7418587568623;420.694996127811;425.33435173952665;897.228383590112;3666.5966743658178;3757.5490941087246;3796.734228603971;3841.327767640652;3891.0661441253897;9738.805223895482;9784.119429251401;9796.363236389612;9803.921568627446;9919.454430006275;9691.51542422876;9750.707478775648;9754.46350450718;9793.118619324296;9844.950775246078;7054.012438258648;7310.671019804122;7311.305425984523;7380.225076367012;7521.962390188014;5343.091674415281;5351.554506296312;5384.6769193848495;5455.345358556999;5585;8867.068957759342;8883.156100170474;8939.821203575762;8971.910280897107;8976.730698079067;8847.49592569252;9244.630214791334;9262.379083660468;9270.582823772811;9293.907060929305;6119.694015299128;6503.577267477599;6562.573432726857;6679.298673639198;6704.195496540447;7632.19861914491;7684.11632662251;7727.072751269883;7742.961285193538;7746.845063098819;6595.505337099833;6720.495962802712;6775.525713200226;6850.21222559412;6911.136107986502;6612.173478315211;6681.29846366144;6725.764598239038;6735.966320168368;6780.220274668279;6142.078688196628;6431.549791514736;6473.643949582818;6493.902591461227;6497.18785151856;4562.406887104655;4595.448546174347;4598.793054312497;4704.811807527661;4792;4630.39804825384;4778.593819525379;4790.808367665394;4880.389951256093;4890.168671325954 18:24:06 INFO - PROCESS | 8964 | |4;prototype.html;283.97728181745447;286.70326212370196;295.6156995905324;296.1249507704733;301.26942165249267;4814.614830813535;4843.46721860596;4859.663592512056;4876.756162191885;4893.755312234383;4546.613537849286;4582.633389328854;4634.258518637019;4764.1424543582225;4789.544993225647;3762.849486020556;3766.4538641896906;3806.200697853454;3808.5620153682266;3808.9238215235746;3772.4152756322815;3775.1128484806186;3776.622337766215;3795.715321350896;3814.370628846237;3595.478655594937;3648.7810731356203;3756.69946404287;3789.393697008479;3793.4499497572833;3753.380692185786;3756.7370284080125;3772.5472943246714;3777.9055523611855;3785.5835858055593;3359.6304406515264;3361.277325375056;3362.596488421405;3364.411228035084;3377.2738861144726;4409.536998615164;4566.20091483989;4576.748278844662;4623.306504024423;4625.884352891171;4308.82764689412;4309.310510318334;4331.415258940035;4341.60925516705;4343.522212556616;4968.527989840959;4978.82574109908;4985.379751580751;5017.623678224148;5028.296038554606;3662.890113296579;3676.3933950898067;3686.981565092184;3687.612800655946;3706.4625629283873;5029.673071250357;5218.191180367049;5225.790968361299;5231.450697676765;5247.265382846366;3730.7948062856804;3735.14091758897;3735.869244576427;3740.5698344690163;3763.8118094095184;3801.8099095045136;3805.771653700787;3808.790516521618;3816.904577385588;3825.6939444844347;4195.265828480004;4237.533871274158;4289.528151903288;4306.267934451151;4320.179165958521;4967.5032496750755;5007.724575148329;5040.899182016339;5045.8738531537;5059.787035232654;1251.949922003128;1276.2215048820212;1284.4348486665838;1304.6868751499571;1316.2892038299403;851.3231980575457;856.6573370651789;871.0810095349341;871.8605023196258;873.7597160780709;3954.762714237155;3957.861474848424;3981.343078392033;3987;3993.3228044221496;4556.384888040138;4579.793909274024;4584.9541504585195;4611.400517932714;4626.68139580218;3982.342913419311;3988.561258261589;3989.5611482736876;3991.201759648082;3994.800259987047;2344.355302291883;2449.9265022049367;2461.839980401304;2464.322311364389;2475.504899020203;4327.891802704958;4374.803133858984;4388.802503887333;4419.447569053868;4420.204363214652;4650.627949764011;4659.394278743742;4670.439547254283;4704.65969161892;4726.243800991893;4150.979245103756;4271.935920961126;4306.181825453154;4333.609975102256;4363.890902727394;4548.56788605082;4559.658025648156;4562.908741825211;4603.861884143481;4621.2837010263465;4457.8439754608435;4464.53122422154;4513.435820522434;4516.683832131719;4539.251023581072;4156.58434156594;4246.810892949979;4351.630111440436;4352.173087113439;4376;3408.5739282589675;3432.9485057724655;3435.7423193260106;3449.6205417404567;3465.653434656514;4546.636269098398;4573.748443835488;4610.515895831024;4614.2617181250835;4990.301357809982;4851.660383773102;5011.273365361929;5072.4673909239;5074.314967479343;5095.541401273903;5032.547070763649;5043.36957880265;5047.520485553867;5059.316992206005;5071.264666623393;4742.241241401359;4912.901741965069;4970.155073637563;4976.880201954531;4982.302477653059;5025.899482010413;5028.220625803006;5033.421156566895;5038.244263360525;5068.417132029716;4941.504540207173;4958.57852082577;4974.32846565709;5004.499550044967;5022.347094877642 18:24:06 INFO - PROCESS | 8964 | |5;yui.html;616.7193926763323;653.885125860408;722.9024081748962;743.9739609113682;758.1887380502864;12333.691657708561;12449.502019919193;12696.492140314376;12699.492020319176;12974.351282435875;11883.168178227528;11896;11931.64205073847;11940.985016273627;12044.879551204485;620.1782638004639;769.0348612491316;793.1869833420747;806.3146325623203;806.3549160671469;788.585992354013;800.7317548621219;802.2819576479077;802.3100133884855;802.4262652203703;851.0680804519042;854.406187699549;859.5143743784741;859.7334826203866;859.7334826203897;656.3928366261189;800.5076877720219;832.7085520067989;845.3913182508584;846.5809424334932;614.9969250153765;615.4614712126889;615.4891440104702;615.7383112177307;615.7444660465901;11498.712532186679;11582.47878845454;11596.59411920587;11612.941935290353;11635.243709158878;11832.290062596272;12309;12354.567590134391;12451.190672606252;12658.367081645973;10993.230473866832;10996.615118470894;11125.165612579009;11158.665240042812;11304.434778261055;4015.9397609035886;4021.8592349267633;4045.8583949561626;4059.451973983504;4140.585941405865;43452.3482147768;43888.780556097336;44076.33885491752;44086.33870491945;44117.99999999968;9097.454152750855;9407.90592094084;9991.650292239738;10117.241206909364;10193.74515637115;16939.745903811454;17095.230714617872;17112.74330885038;17131.74302385465;17166;6326.398992095744;6326.5255105867245;6432.388923052303;6474.5791523550815;6525.4779617630475;3459;3469.3061387722555;3550.1302180965827;3565.893023209308;3640.927181456409;2626.448445826398;2689.596560515938;2695.420484595782;2701.6082668013028;2703.7025927147997;465.33457156266866;482.29103218269086;489.18550613229286;491.56250936666396;495.88842510434864;494.7575687912969;500.68456872171026;504.3468708023046;573.2604939627834;601.2634522709645;7803.68785248595;7904.802379940547;8136.389770767216;8241.464304820167;8301.709440169565;455.257929574791;561.5900392713343;562.1258942344612;577.6447484796904;581.2098735893438;3677.852885884588;3722.3299806034634;3791.33651610967;3807.6573108420375;3930.587288334741;47473.28790068152;48341.516584834404;48608.756956214995;53951.190732139046;53963.730181348845;47044.29433558499;49349.272775452686;49381;50306;50433.49566504288;44176.116477670264;44521.55478445239;45255;45330.546694533296;46771.532284676716;7991.360691144579;8005.279524842559;8017.198280171936;8117.79705507367;8222.095569487232;31188.53217201789;32024.199395015312;32171.839140804146;32267.677323227406;33130.17174570655;37909;40841;40893;40968.38547421731;41689.791551043265;10025.598976040877;10516.211284153565;10550.103241224275;10597.364158150536;10650.094741947021;6913.5506192099365;7905.893174955396;10728.356298622108;11179.628808008252;11667.708307292047;9628.47043412619;9989.850152247867;10242.692719218137;10369.377837329785;10373.481325933522;10915.235933484897;10959.561617535528;10989.285696429706;11001.779964400826;11291.322520648788;9775.233354075917;9912.35569687968;9912.900870991198;10145.695629131138;10148.391096534231;9037.77405564866;9069.365144439826;9110.579547597457;9321.254299656146;9410.058994100535;8809.339299552432;9082.863757043782;9131.315151363542;9151.679691210775;9220.446773193362 18:24:06 INFO - PROCESS | 8964 | __end_tp_report 18:24:06 INFO - PROCESS | 8964 | __start_cc_report 18:24:06 INFO - PROCESS | 8964 | _x_x_mozilla_cycle_collect,1010 18:24:06 INFO - PROCESS | 8964 | __end_cc_report 18:24:06 INFO - PROCESS | 8964 | __startTimestamp1463707446789__endTimestamp 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | ------- Summary: start ------- 18:24:06 INFO - PROCESS | 8964 | Number of tests: 6 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | [#0] dojo.html Cycles:165 Average:4656.45 Median:4751.93 stddev:478.06 (10.1%) stddev-sans-first:452.66 18:24:06 INFO - PROCESS | 8964 | Values: 2642.5 2655.6 2690.6 2694.6 2705.3 4575.7 4576.8 4642.5 4688.7 4694.4 4652.3 4655.2 4717.7 4724.6 4750.1 4244.7 4304.7 4337.7 4361.3 4472.3 4401.5 4415.2 4424.6 4446.3 4477.8 4245.0 4280.9 4295.7 4317.7 4353.0 4271.4 4275.6 4285.4 4391.6 4404.6 4293.1 4296.5 4347.5 4350.2 4354.8 4495.9 4576.6 4607.9 4625.8 4632.5 5013.0 5041.9 5061.2 5102.7 5107.3 5055.9 5057.5 5074.3 5081.8 5120.0 4586.8 4709.6 4735.3 4748.4 4789.0 5244.7 5253.4 5259.9 5266.4 5270.7 4989.9 4990.7 4995.0 5000.0 5045.2 4938.1 4957.7 4988.3 4996.5 5124.4 4944.1 4966.3 5000.6 5003.4 5049.0 4962.5 4966.1 4984.7 4987.2 5017.2 4261.5 4290.9 4291.9 4294.7 4345.6 3760.3 3767.1 3795.0 3814.5 3859.1 4326.1 4330.5 4347.8 4380.5 4393.1 4885.2 4990.9 5076.8 5087.2 5130.9 4284.9 4316.1 4327.7 4365.2 4367.1 4548.5 4561.9 4563.0 4578.6 4605.0 4645.1 4734.4 4734.4 4749.3 4764.5 5069.5 5078.0 5084.5 5092.1 5139.9 4724.0 4747.6 4751.9 4754.5 4794.4 4806.4 4876.6 4895.6 4923.6 5033.5 4993.8 5009.0 5009.2 5010.4 5055.8 4851.3 4859.6 4863.7 4872.0 4915.9 4812.1 4868.4 4871.9 4907.5 4928.4 4818.7 4853.5 4895.4 4897.0 4908.2 4869.6 4947.6 4964.8 4995.5 4999.7 4746.5 4746.7 4767.3 4786.9 4800.0 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | [#1] ext.html Cycles:180 Average:48215.40 Median:36557.33 stddev:87004.66 (238.0%) stddev-sans-first:87175.13 18:24:06 INFO - PROCESS | 8964 | Values: 551.9 589.7 595.0 602.0 602.3 39626.8 41148.0 41312.0 41386.2 45003.5 37432.8 37847.6 37912.2 37931.4 37947.0 7767.2 7924.6 7939.1 7946.7 7947.2 7746.4 7827.2 7847.5 7852.0 7852.3 7860.7 7888.6 7903.9 7907.6 7909.7 7817.3 7849.7 7864.3 7869.1 7880.2 7698.9 7799.8 7801.4 7804.7 7805.1 38611.2 38882.0 38908.4 38934.4 39188.0 3013.4 3031.8 3055.2 3321.9 3381.7 29468.4 29597.4 29638.0 30122.2 30304.4 3476.4 3530.9 3551.1 3556.2 3582.2 267734.0 268929.0 269522.0 270541.0 271156.0 47917.5 48089.8 48342.3 48363.5 49759.3 53034.0 53597.7 54295.5 60580.0 60734.7 13642.0 13748.1 13819.4 13823.6 13858.2 10002.4 10026.2 11099.8 11106.5 11174.1 14940.6 15472.5 15545.1 15547.5 15595.3 6135.5 6280.5 6352.8 6458.2 6483.8 829.8 956.9 989.3 996.6 1043.6 27584.0 28730.1 28795.1 29136.0 29185.7 990.3 999.6 1005.7 1109.5 1133.9 15063.6 15065.2 15223.2 15252.7 15535.1 372025.0 373395.0 375218.0 376930.0 377965.0 49243.8 49484.3 49659.8 49705.8 49788.8 1038.8 1195.6 1197.0 1224.9 1234.5 25125.7 29627.6 29770.6 29965.0 30119.2 41335.6 46635.5 47810.3 47838.0 52224.2 337286.0 337405.0 338597.0 339418.0 339778.0 22115.3 24410.6 24601.5 25024.7 25546.6 42273.8 42985.1 42992.6 43898.0 44196.1 36142.0 36403.6 36584.3 36936.6 37504.0 35605.3 35859.3 36173.6 36516.0 36530.4 35596.1 35658.6 35675.0 35991.6 36437.8 31584.2 32761.5 32955.0 32974.8 33193.5 24482.3 24605.5 24915.1 24962.9 25147.5 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | [#2] jquery.html Cycles:180 Average:22972.50 Median:4510.36 stddev:59701.81 (1323.7%) stddev-sans-first:59845.18 18:24:06 INFO - PROCESS | 8964 | Values: 375.3 379.3 383.3 387.3 395.5 16015.4 16290.4 17370.2 17400.3 17420.9 12027.3 15812.9 16598.4 16623.4 16638.8 4605.9 4786.5 4818.4 4825.8 4841.6 3437.4 5146.1 5411.6 5420.0 5509.6 5445.1 5471.3 5476.1 5492.6 5504.7 5244.9 5560.1 5567.0 5637.2 5649.0 3411.5 5054.5 5361.6 5367.4 5453.4 12447.6 12595.4 12615.1 19890.5 21298.8 1900.3 2014.7 2039.6 2142.8 2397.4 9188.3 9233.8 9485.1 9626.9 12255.6 1678.7 1712.2 1713.5 1775.1 1780.7 66638.3 68806.3 68837.7 69892.3 69948.0 10163.8 10207.8 10261.0 10263.7 10503.3 14253.9 14338.1 14393.8 14408.1 14411.5 7150.4 7291.4 7323.9 7374.6 7380.4 6647.0 6693.0 6763.1 6875.3 7071.8 4444.7 4503.3 4517.4 4526.0 4592.4 1693.7 1747.0 1805.4 1858.3 1882.8 877.5 891.5 911.3 929.8 953.0 16246.6 16823.2 16846.4 16976.7 17140.0 874.4 882.7 886.5 905.5 935.9 9190.4 9590.5 9652.5 9667.6 9732.3 337998.0 339356.0 370395.0 377657.0 378868.0 70884.0 72923.0 72945.3 73610.0 73835.0 920.8 926.0 929.0 931.6 932.4 14732.5 14814.2 15102.4 15153.2 15254.0 33555.8 34088.3 34259.8 34268.7 34778.8 42700.0 46058.0 46265.0 46893.8 47285.3 7524.4 7685.8 7819.3 7830.8 7877.8 8647.3 11252.5 11416.8 11487.5 11748.5 9952.0 9999.8 10063.9 10108.6 10214.0 9827.9 10024.2 10152.5 10186.3 10215.7 10193.6 10406.4 10430.7 10453.7 10510.6 7754.0 7834.5 7915.4 7949.8 7988.0 9945.0 10029.6 10101.6 10117.7 10270.2 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | [#3] mootools.html Cycles:180 Average:5195.01 Median:5269.38 stddev:2923.28 (55.5%) stddev-sans-first:2916.77 18:24:06 INFO - PROCESS | 8964 | Values: 1292.3 1415.5 1463.6 1574.9 1764.9 6761.2 7596.5 7828.4 7838.3 8039.4 7765.1 7919.0 7926.3 7938.7 7946.7 1887.9 2146.2 2229.5 2230.7 2253.6 2255.5 2273.0 2277.6 2279.3 2283.5 2200.8 2201.8 2249.7 2251.4 2252.6 1842.0 2141.1 2174.4 2199.1 2200.5 2191.7 2201.8 2211.8 2214.3 2217.8 7812.8 7851.2 7860.7 7861.6 7869.6 4560.3 4691.7 4696.2 4698.7 4700.1 2858.4 2905.5 2945.8 2980.6 3019.1 879.3 892.9 892.9 899.0 906.2 10823.4 10860.0 10871.5 10887.9 10897.8 4285.1 4427.3 4459.6 4497.1 4511.2 9221.1 9245.5 9258.8 9268.0 9286.5 4687.5 4690.2 4690.6 4690.7 4691.5 3645.6 3979.6 4096.3 4113.0 4165.0 3718.2 3752.8 3816.9 3831.8 3861.7 1213.5 1229.1 1233.2 1245.7 1263.5 834.4 840.2 847.1 851.3 857.4 5088.9 5257.1 5281.7 5342.4 5345.9 414.9 419.7 420.7 425.3 897.2 3666.6 3757.5 3796.7 3841.3 3891.1 9738.8 9784.1 9796.4 9803.9 9919.5 9691.5 9750.7 9754.5 9793.1 9845.0 7054.0 7310.7 7311.3 7380.2 7522.0 5343.1 5351.6 5384.7 5455.3 5585.0 8867.1 8883.2 8939.8 8971.9 8976.7 8847.5 9244.6 9262.4 9270.6 9293.9 6119.7 6503.6 6562.6 6679.3 6704.2 7632.2 7684.1 7727.1 7743.0 7746.8 6595.5 6720.5 6775.5 6850.2 6911.1 6612.2 6681.3 6725.8 6736.0 6780.2 6142.1 6431.5 6473.6 6493.9 6497.2 4562.4 4595.4 4598.8 4704.8 4792.0 4630.4 4778.6 4790.8 4880.4 4890.2 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | [#4] prototype.html Cycles:180 Average:4023.20 Median:4375.40 stddev:1144.73 (26.2%) stddev-sans-first:1113.01 18:24:06 INFO - PROCESS | 8964 | Values: 284.0 286.7 295.6 296.1 301.3 4814.6 4843.5 4859.7 4876.8 4893.8 4546.6 4582.6 4634.3 4764.1 4789.5 3762.8 3766.5 3806.2 3808.6 3808.9 3772.4 3775.1 3776.6 3795.7 3814.4 3595.5 3648.8 3756.7 3789.4 3793.4 3753.4 3756.7 3772.5 3777.9 3785.6 3359.6 3361.3 3362.6 3364.4 3377.3 4409.5 4566.2 4576.7 4623.3 4625.9 4308.8 4309.3 4331.4 4341.6 4343.5 4968.5 4978.8 4985.4 5017.6 5028.3 3662.9 3676.4 3687.0 3687.6 3706.5 5029.7 5218.2 5225.8 5231.5 5247.3 3730.8 3735.1 3735.9 3740.6 3763.8 3801.8 3805.8 3808.8 3816.9 3825.7 4195.3 4237.5 4289.5 4306.3 4320.2 4967.5 5007.7 5040.9 5045.9 5059.8 1251.9 1276.2 1284.4 1304.7 1316.3 851.3 856.7 871.1 871.9 873.8 3954.8 3957.9 3981.3 3987.0 3993.3 4556.4 4579.8 4585.0 4611.4 4626.7 3982.3 3988.6 3989.6 3991.2 3994.8 2344.4 2449.9 2461.8 2464.3 2475.5 4327.9 4374.8 4388.8 4419.4 4420.2 4650.6 4659.4 4670.4 4704.7 4726.2 4151.0 4271.9 4306.2 4333.6 4363.9 4548.6 4559.7 4562.9 4603.9 4621.3 4457.8 4464.5 4513.4 4516.7 4539.3 4156.6 4246.8 4351.6 4352.2 4376.0 3408.6 3432.9 3435.7 3449.6 3465.7 4546.6 4573.7 4610.5 4614.3 4990.3 4851.7 5011.3 5072.5 5074.3 5095.5 5032.5 5043.4 5047.5 5059.3 5071.3 4742.2 4912.9 4970.2 4976.9 4982.3 5025.9 5028.2 5033.4 5038.2 5068.4 4941.5 4958.6 4974.3 5004.5 5022.3 18:24:06 INFO - PROCESS | 8964 | 18:24:06 INFO - PROCESS | 8964 | [#5] yui.html Cycles:180 Average:12784.28 Median:23618.43 stddev:14736.58 (62.4%) stddev-sans-first:14749.59 18:24:06 INFO - PROCESS | 8964 | Values: 616.7 653.9 722.9 744.0 758.2 12333.7 12449.5 12696.5 12699.5 12974.4 11883.2 11896.0 11931.6 11941.0 12044.9 620.2 769.0 793.2 806.3 806.4 788.6 800.7 802.3 802.3 802.4 851.1 854.4 859.5 859.7 859.7 656.4 800.5 832.7 845.4 846.6 615.0 615.5 615.5 615.7 615.7 11498.7 11582.5 11596.6 11612.9 11635.2 11832.3 12309.0 12354.6 12451.2 12658.4 10993.2 10996.6 11125.2 11158.7 11304.4 4015.9 4021.9 4045.9 4059.5 4140.6 43452.3 43888.8 44076.3 44086.3 44118.0 9097.5 9407.9 9991.7 10117.2 10193.7 16939.7 17095.2 17112.7 17131.7 17166.0 6326.4 6326.5 6432.4 6474.6 6525.5 3459.0 3469.3 3550.1 3565.9 3640.9 2626.4 2689.6 2695.4 2701.6 2703.7 465.3 482.3 489.2 491.6 495.9 494.8 500.7 504.3 573.3 601.3 7803.7 7904.8 8136.4 8241.5 8301.7 455.3 561.6 562.1 577.6 581.2 3677.9 3722.3 3791.3 3807.7 3930.6 47473.3 48341.5 48608.8 53951.2 53963.7 47044.3 49349.3 49381.0 50306.0 50433.5 44176.1 44521.6 45255.0 45330.5 46771.5 7991.4 8005.3 8017.2 8117.8 8222.1 31188.5 32024.2 32171.8 32267.7 33130.2 37909.0 40841.0 40893.0 40968.4 41689.8 10025.6 10516.2 10550.1 10597.4 10650.1 6913.6 7905.9 10728.4 11179.6 11667.7 9628.5 9989.9 10242.7 10369.4 10373.5 10915.2 10959.6 10989.3 11001.8 11291.3 9775.2 9912.4 9912.9 10145.7 10148.4 9037.8 9069.4 9110.6 9321.3 9410.1 8809.3 9082.9 9131.3 9151.7 9220.4 18:24:06 INFO - PROCESS | 8964 | -------- Summary: end -------- 18:24:06 INFO - PROCESS | 8964 | 18:24:07 INFO - TEST-INFO | 8964: exit 0 18:24:07 INFO - TEST-OK | dromaeo_css | took 1118588ms 18:24:07 INFO - TEST-START | kraken 18:24:07 INFO - operating with platform_type : linux_ 18:24:07 INFO - Initialising browser for kraken test... 18:24:07 INFO - TEST-INFO | started process 9146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ98JnY/profile http://localhost:51198/getInfo.html) 18:24:12 INFO - PROCESS | 9146 | __metrics Screen width/height:1600/1200 18:24:12 INFO - PROCESS | 9146 | colorDepth:24 18:24:12 INFO - PROCESS | 9146 | Browser inner width/height: 1024/697 18:24:12 INFO - PROCESS | 9146 | __metrics 18:24:13 ERROR - PROCESS | 9146 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:24:13 INFO - TEST-INFO | 9146: exit 0 18:24:13 INFO - Browser initialized. 18:24:13 INFO - Running cycle 1/1 for kraken test... 18:24:13 INFO - TEST-INFO | started process 9226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ98JnY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:25 INFO - PROCESS | 9226 | RSS: Main: 154775552 18:24:25 INFO - PROCESS | 9226 | 18:24:28 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51198/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:29 INFO - PROCESS | 9226 | RSS: Main: 210251776 18:24:29 INFO - PROCESS | 9226 | 18:24:32 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51198/tests/kraken/driver.html?testName=audio-dft) 18:24:33 INFO - PROCESS | 9226 | RSS: Main: 198471680 18:24:33 INFO - PROCESS | 9226 | 18:24:36 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51198/tests/kraken/driver.html?testName=audio-fft) 18:24:37 INFO - PROCESS | 9226 | RSS: Main: 266940416 18:24:37 INFO - PROCESS | 9226 | 18:24:39 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51198/tests/kraken/driver.html?testName=audio-oscillator) 18:24:40 INFO - PROCESS | 9226 | RSS: Main: 176451584 18:24:40 INFO - PROCESS | 9226 | 18:24:42 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51198/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:43 INFO - PROCESS | 9226 | RSS: Main: 180260864 18:24:43 INFO - PROCESS | 9226 | 18:24:46 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51198/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:47 INFO - PROCESS | 9226 | RSS: Main: 215744512 18:24:47 INFO - PROCESS | 9226 | 18:24:50 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51198/tests/kraken/driver.html?testName=imaging-desaturate) 18:24:51 INFO - PROCESS | 9226 | RSS: Main: 214958080 18:24:51 INFO - PROCESS | 9226 | 18:24:54 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51198/tests/kraken/driver.html?testName=json-parse-financial) 18:24:54 INFO - PROCESS | 9226 | RSS: Main: 215597056 18:24:54 INFO - PROCESS | 9226 | 18:24:56 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51198/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:24:57 INFO - PROCESS | 9226 | RSS: Main: 208404480 18:24:57 INFO - PROCESS | 9226 | 18:24:59 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:24:59 INFO - PROCESS | 9226 | RSS: Main: 208084992 18:24:59 INFO - PROCESS | 9226 | 18:25:02 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:02 INFO - PROCESS | 9226 | RSS: Main: 216326144 18:25:02 INFO - PROCESS | 9226 | 18:25:05 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:06 INFO - PROCESS | 9226 | RSS: Main: 219758592 18:25:06 INFO - PROCESS | 9226 | 18:25:09 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:10 INFO - PROCESS | 9226 | RSS: Main: 219582464 18:25:10 INFO - PROCESS | 9226 | 18:25:11 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:51198/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51198/tests/kraken/driver.html?testName=ai-astar) 18:25:12 INFO - PROCESS | 9226 | RSS: Main: 266723328 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | __start_tp_report 18:25:12 INFO - PROCESS | 9226 | _x_x_mozilla_page_load 18:25:12 INFO - PROCESS | 9226 | _x_x_mozilla_page_load_details 18:25:12 INFO - PROCESS | 9226 | |i|pagename|runs| 18:25:12 INFO - PROCESS | 9226 | |0;ai-astar;114;110;117;114;115;113;114;114;113;116 18:25:12 INFO - PROCESS | 9226 | |1;audio-beat-detection;151;130;152;214;131;160;165;131;192;129 18:25:12 INFO - PROCESS | 9226 | |2;audio-dft;163;163;163;161;161;161;160;160;162;160 18:25:12 INFO - PROCESS | 9226 | |3;audio-fft;77;76;77;150;76;74;77;76;128;76 18:25:12 INFO - PROCESS | 9226 | |4;audio-oscillator;94;94;155;93;94;94;129;93;94;126 18:25:12 INFO - PROCESS | 9226 | |5;imaging-gaussian-blur;117;116;116;116;117;117;116;118;117;116 18:25:12 INFO - PROCESS | 9226 | |6;imaging-darkroom;145;146;147;146;146;147;147;146;146;147 18:25:12 INFO - PROCESS | 9226 | |7;imaging-desaturate;94;94;95;94;94;94;94;96;95;95 18:25:12 INFO - PROCESS | 9226 | |8;json-parse-financial;64;65;69;64;64;64;64;64;64;63 18:25:12 INFO - PROCESS | 9226 | |9;json-stringify-tinderbox;65;65;66;65;66;65;65;69;65;64 18:25:12 INFO - PROCESS | 9226 | |10;stanford-crypto-aes;82;82;86;84;84;84;83;90;145;83 18:25:12 INFO - PROCESS | 9226 | |11;stanford-crypto-ccm;135;132;130;130;131;129;127;131;149;131 18:25:12 INFO - PROCESS | 9226 | |12;stanford-crypto-pbkdf2;173;170;178;171;173;170;172;174;173;172 18:25:12 INFO - PROCESS | 9226 | |13;stanford-crypto-sha256-iterative;65;67;66;66;66;67;65;67;65;65 18:25:12 INFO - PROCESS | 9226 | __end_tp_report 18:25:12 INFO - PROCESS | 9226 | __start_cc_report 18:25:12 INFO - PROCESS | 9226 | _x_x_mozilla_cycle_collect,2101 18:25:12 INFO - PROCESS | 9226 | __end_cc_report 18:25:12 INFO - PROCESS | 9226 | __startTimestamp1463707512098__endTimestamp 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | ------- Summary: start ------- 18:25:12 INFO - PROCESS | 9226 | Number of tests: 14 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:1.89 (1.7%) stddev-sans-first:2.00 18:25:12 INFO - PROCESS | 9226 | Values: 114.0 110.0 117.0 114.0 115.0 113.0 114.0 114.0 113.0 116.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#1] audio-beat-detection Cycles:10 Average:155.50 Median:156.00 stddev:28.77 (18.4%) stddev-sans-first:30.47 18:25:12 INFO - PROCESS | 9226 | Values: 151.0 130.0 152.0 214.0 131.0 160.0 165.0 131.0 192.0 129.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.26 (0.8%) stddev-sans-first:1.20 18:25:12 INFO - PROCESS | 9226 | Values: 163.0 163.0 163.0 161.0 161.0 161.0 160.0 160.0 162.0 160.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#3] audio-fft Cycles:10 Average:88.70 Median:76.00 stddev:27.03 (35.6%) stddev-sans-first:28.33 18:25:12 INFO - PROCESS | 9226 | Values: 77.0 76.0 77.0 150.0 76.0 74.0 77.0 76.0 128.0 76.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#4] audio-oscillator Cycles:10 Average:106.60 Median:94.00 stddev:22.07 (23.5%) stddev-sans-first:22.93 18:25:12 INFO - PROCESS | 9226 | Values: 94.0 94.0 155.0 93.0 94.0 94.0 129.0 93.0 94.0 126.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 18:25:12 INFO - PROCESS | 9226 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 116.0 118.0 117.0 116.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.53 18:25:12 INFO - PROCESS | 9226 | Values: 145.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 18:25:12 INFO - PROCESS | 9226 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 94.0 96.0 95.0 95.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:1.65 (2.6%) stddev-sans-first:1.74 18:25:12 INFO - PROCESS | 9226 | Values: 64.0 65.0 69.0 64.0 64.0 64.0 64.0 64.0 64.0 63.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:65.00 stddev:1.35 (2.1%) stddev-sans-first:1.42 18:25:12 INFO - PROCESS | 9226 | Values: 65.0 65.0 66.0 65.0 66.0 65.0 65.0 69.0 65.0 64.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:84.00 stddev:19.36 (23.1%) stddev-sans-first:20.30 18:25:12 INFO - PROCESS | 9226 | Values: 82.0 82.0 86.0 84.0 84.0 84.0 83.0 90.0 145.0 83.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:131.00 stddev:6.15 (4.7%) stddev-sans-first:6.46 18:25:12 INFO - PROCESS | 9226 | Values: 135.0 132.0 130.0 130.0 131.0 129.0 127.0 131.0 149.0 131.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.00 stddev:2.32 (1.3%) stddev-sans-first:2.46 18:25:12 INFO - PROCESS | 9226 | Values: 173.0 170.0 178.0 171.0 173.0 170.0 172.0 174.0 173.0 172.0 18:25:12 INFO - PROCESS | 9226 | 18:25:12 INFO - PROCESS | 9226 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 18:25:12 INFO - PROCESS | 9226 | Values: 65.0 67.0 66.0 66.0 66.0 67.0 65.0 67.0 65.0 65.0 18:25:12 INFO - PROCESS | 9226 | -------- Summary: end -------- 18:25:12 INFO - PROCESS | 9226 | 18:25:13 INFO - TEST-INFO | 9226: exit 0 18:25:13 INFO - TEST-OK | kraken | took 65649ms 18:25:13 INFO - SUITE-END | took 1184s 18:25:13 INFO - Completed test suite (00:19:44) 18:25:13 INFO - javascript benchmark 18:25:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2642.4715056988603, 2655.5883838005116, 2690.582959641255, 2694.5823397373415, 2705.310145912792, 4575.65682573807, 4576.83981060663, 4642.535746425356, 4688.742119183444, 4694.413198350207, 4652.255639097745, 4655.208614535529, 4717.693349932252, 4724.574788269055, 4750.097481478517, 4244.745315281078, 4304.74171549708, 4337.7397356158735, 4361.3457981302745, 4472.262076757347, 4401.493828209765, 4415.183191109639, 4424.601785839274, 4446.288593824988, 4477.753723545204, 4245.0, 4280.892977675552, 4295.699301048928, 4317.654587632982, 4353.0, 4271.380649805792, 4275.615194632467, 4285.4214681017975, 4391.604755572014, 4404.647628189769, 4293.141371725667, 4296.5273819879785, 4347.456567929009, 4350.238708226079, 4354.847580334673, 4495.910081798345, 4576.633869290482, 4607.884802879921, 4625.838095666669, 4632.467266264389, 5012.974935125338, 5041.873953151201, 5061.16490779021, 5102.693838369672, 5107.3360463139925, 5055.898882022376, 5057.544820966131, 5074.340335756365, 5081.79672813087, 5120.02719483297, 4586.793594288265, 4709.646776491777, 4735.313379559944, 4748.430188377417, 4788.994311194653, 5244.659097158673, 5253.3958594761325, 5259.868503287448, 5266.368035835724, 5270.657407268515, 4989.850304490871, 4990.675606085593, 4994.975025124923, 5000.049990501793, 5045.2432135180015, 4938.086454005948, 4957.727324997088, 4988.27669987857, 4996.525330093636, 5124.436312005676, 4944.1100601891285, 4966.329545511382, 5000.599952003831, 5003.424606170337, 5048.974755126201, 4962.454130045692, 4966.056449274626, 4984.651074424755, 4987.152184128707, 5017.222330538772, 4261.4673165854265, 4290.871273861789, 4291.935620965688, 4294.677899157513, 4345.608895199447, 3760.304343696425, 3767.05823544114, 3794.9810250948567, 3814.4659747635355, 3859.1316953685196, 4326.113146804897, 4330.480342358874, 4347.847825326098, 4380.540043295472, 4393.055493069006, 4885.218365061573, 4990.90018199634, 5076.771545280398, 5087.23691446286, 5130.9230361544605, 4284.871453856389, 4316.115196384733, 4327.6970612057485, 4365.170617582649, 4367.148406060787, 4548.499665036843, 4561.931571026437, 4562.954370456319, 4578.633709303248, 4605.0, 4645.07098580292, 4734.360861283821, 4734.431868175772, 4749.287606858929, 4764.523547645346, 5069.467705890828, 5078.0, 5084.51697088776, 5092.134337162617, 5139.871503212449, 4724.03157352735, 4747.62019038483, 4751.928721069117, 4754.50077741846, 4794.376731024944, 4806.423229212517, 4876.634252431011, 4895.559399654048, 4923.5814955729165, 5033.471485494117, 4993.750312484289, 5008.974955125201, 5009.248612707977, 5010.398752149619, 5055.797768089383, 4851.272309153655, 4859.635527335393, 4863.659543831898, 4872.0, 4915.926261106158, 4812.133815913029, 4868.367112275381, 4871.9269210962575, 4907.460179380194, 4928.359313289249, 4818.68678535894, 4853.490383509822, 4895.388076490439, 4896.975515122402, 4908.239222920453, 4869.6347773917805, 4947.628927830355, 4964.776585053609, 4995.525425084611, 4999.725015124203, 4746.4541577719, 4746.715197088186, 4767.33257343979, 4786.856394308037, 4799.952000479951], "value": 4626.85209431733, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.9254900588422, 589.6845187824515, 594.9881002379951, 601.9969900150498, 602.3043384890448, 39626.80186599067, 41148.0, 41312.0, 41386.17227655449, 45003.549964500344, 37432.81283593585, 37847.62152378476, 37912.24175516488, 37931.43102853459, 37947.0, 7767.184445633212, 7924.643391047388, 7939.0870049944415, 7946.721864734735, 7947.205279472064, 7746.38028957682, 7827.217278272185, 7847.489913155655, 7852.0, 7852.3325517331095, 7860.68557257709, 7888.56612886291, 7903.920960790376, 7907.6046197690175, 7909.683612655487, 7817.257360550739, 7849.68601255949, 7864.2922137007945, 7869.134395216522, 7880.211978802131, 7698.923010769877, 7799.766007019798, 7801.414893882981, 7804.687812487494, 7805.141434442206, 38611.227775444335, 38882.0, 38908.416373754415, 38934.415983760264, 39188.0, 3013.442513135077, 3031.848407579634, 3055.1751027222526, 3321.9003429897143, 3381.7294616430627, 29468.410631787458, 29597.408051838844, 29637.962671306617, 30122.24694382636, 30304.393912121635, 3476.387178774272, 3530.9117272068147, 3551.058969373118, 3556.199855032597, 3582.2119133790525, 267734.0, 268929.0, 269522.0, 270541.0, 271156.0, 47917.520824792, 48089.75955120202, 48342.27486587634, 48363.5163648359, 49759.25361119586, 53034.0, 53597.73201133969, 54295.45704542904, 60580.0, 60734.69632651808, 13642.045056846124, 13748.106373085717, 13819.447222111205, 13823.585292441243, 13858.237796921065, 10002.399856008662, 10026.248031397674, 11099.778004439866, 11106.500207490682, 11174.050205732441, 14940.626484337978, 15472.458463953708, 15545.067295962279, 15547.533573992798, 15595.298211580279, 6135.459759777732, 6280.497560195265, 6352.809415717536, 6458.22501299847, 6483.805485835433, 829.8423299573062, 956.8851737791509, 989.3322007644811, 996.6113215845826, 1043.634727845248, 27584.0, 28730.138095857157, 28795.136145915658, 29136.0, 29185.708142918724, 990.277097718655, 999.6251405722854, 1005.6631028605353, 1109.5339957217989, 1133.9092872570175, 15063.623409414853, 15065.17141557203, 15223.199154654394, 15252.694946101015, 15535.145566993699, 372025.0, 373395.0, 375218.0, 376930.0, 377965.0, 49243.753781230866, 49484.257736133986, 49659.751701241265, 49705.75147124241, 49788.75105624521, 1038.776663017455, 1195.551668124436, 1197.0184448752113, 1224.906092191341, 1234.512367614787, 25125.74874251307, 29627.555586665785, 29770.553441698827, 29965.0, 30119.247018824706, 41335.586644133175, 46635.53364466448, 47810.28284575804, 47838.0, 52224.21663675124, 337286.0, 337405.0, 338597.0, 339418.0, 339778.0, 22115.341349399183, 24410.633840492053, 24601.507969840863, 25024.74975250224, 25546.616800748376, 42273.78863105665, 42985.140297194506, 42992.570074298856, 43898.0, 44196.11607767891, 36142.0, 36403.63596364002, 36584.268314633024, 36936.63063369332, 37504.0, 35605.28789424249, 35859.282814344086, 36173.638263617024, 36516.0, 36530.38246541633, 35596.11009724777, 35658.64341356553, 35675.0, 35991.64008359988, 36437.817810910776, 31584.210394739395, 32761.508577371835, 32955.0, 32974.83512582422, 33193.50209746904, 24482.265532034067, 24605.50788984246, 24915.127970520945, 24962.875185623954, 25147.49705005926], "value": 17604.589794675998, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.29819238024896, 379.30019114733324, 383.26413286489947, 387.2622653844424, 395.5451231084253, 16015.359385624575, 16290.42983495578, 17370.21834017469, 17400.30398784047, 17420.912895435536, 12027.338496382696, 15812.920935395307, 16598.419055333066, 16623.41818036369, 16638.750418743697, 4605.861824145281, 4786.545278198566, 4818.445878723939, 4825.7587120644, 4841.612670986313, 3437.415639341318, 5146.099432599281, 5411.648242864221, 5419.99999999998, 5509.559235261169, 5445.128779395298, 5471.288732464774, 5476.123820188771, 5492.615516913817, 5504.724763761796, 5244.921326180111, 5560.110382338847, 5567.0, 5637.23897273871, 5649.0, 3411.4712219606, 5054.469280725508, 5361.624686271963, 5367.382750983647, 5453.400125986138, 12447.564335248313, 12595.433205505775, 12615.053870959715, 19890.502737431536, 21298.82856442895, 1900.2683966672791, 2014.74815648044, 2039.5920815836744, 2142.7535833379047, 2397.412633904687, 9188.26493880488, 9233.815323693556, 9485.051494850528, 9626.855597166048, 12255.571055013032, 1678.7397953317256, 1712.1781544858538, 1713.477389396246, 1775.1390575570838, 1780.6705759434547, 66638.33361666418, 68806.31193688049, 68837.65581172112, 69892.30107698908, 69947.95078073884, 10163.796724065478, 10207.846882296772, 10260.97390260968, 10263.74340641475, 10503.264771466076, 14253.857461425254, 14338.068025578512, 14393.78409323861, 14408.063475874034, 14411.495597654031, 7150.421358788853, 7291.361785088881, 7323.890141647879, 7374.594397308092, 7380.376317210205, 6647.0, 6692.96653516739, 6763.086983257295, 6875.3124687531845, 7071.752488662872, 4444.688871778944, 4503.343027613723, 4517.367568540407, 4525.977370113194, 4592.402987611656, 1693.7120689482817, 1746.9912650436665, 1805.4493379519372, 1858.2659849359427, 1882.7646544181978, 877.4515927545284, 891.4829398948594, 911.261877878907, 929.762910457829, 952.9904700953041, 16246.593835153979, 16823.15884205785, 16846.410375636795, 16976.660466790596, 17140.0, 874.3835595904872, 882.6998820401094, 886.4814083760944, 905.4929239625831, 935.9204467620328, 9190.448573085636, 9590.520473976412, 9652.517374131265, 9667.564959576835, 9732.270079744048, 337998.0, 339356.0, 370395.0, 377657.0, 378868.0, 70884.0, 72923.0, 72945.27054729385, 73610.0, 73835.0, 920.7744102694749, 925.9629614815467, 928.9628414863465, 931.5575101826713, 932.4032619123766, 14732.484363047242, 14814.185219813015, 15102.39590416415, 15153.166575837993, 15254.0, 33555.83222083874, 34088.318233635684, 34259.828700856335, 34268.65731342655, 34778.82610586931, 42700.0, 46058.0, 46265.0, 46893.765531172125, 47285.2907206399, 7524.435667324863, 7685.807854803675, 7819.257170568787, 7830.804229894298, 7877.7636670899965, 8647.308215342884, 11252.549898003988, 11416.82874756896, 11487.483063262007, 11748.471318790504, 9951.950240248752, 9999.750006249902, 10063.949680251551, 10108.646197383056, 10214.029667181605, 9827.901720982698, 10024.198064154703, 10152.492375381054, 10186.337888037255, 10215.744606384602, 10193.643222487177, 10406.427646479517, 10430.739231519272, 10453.68638940833, 10510.632127875488, 7753.961230193813, 7834.529928204326, 7915.406344524299, 7949.8410031800195, 7987.960060199662, 9944.950275248577, 10029.648962286285, 10101.646442374482, 10117.696469105938, 10270.178385729274], "value": 8074.734936027515, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.3344477594046, 1415.5470249520151, 1463.626775172331, 1574.8818838587108, 1764.9382271620495, 6761.154855643044, 7596.544207347563, 7828.412869034823, 7838.294553490199, 8039.356851451885, 7765.06819181699, 7919.0, 7926.326262267699, 7938.722144724936, 7946.721864734735, 1887.9433616991512, 2146.162996431393, 2229.5429436965464, 2230.710007698997, 2253.6281513550243, 2255.548890221954, 2272.9772702273012, 2277.6241920083166, 2279.3389916924157, 2283.486215601493, 2200.8239340852692, 2201.7908298711595, 2249.6513040478753, 2251.4146321956246, 2252.628316327804, 1841.981580184201, 2141.1221399226242, 2174.4346469917778, 2199.0544066051593, 2200.548887478063, 2191.660292654641, 2201.8018378345864, 2211.756706762255, 2214.258223495115, 2217.7671344508753, 7812.843743125162, 7851.2148785121035, 7860.724874629418, 7861.56761378124, 7869.645865936047, 4560.270356742905, 4691.671582989192, 4696.225122854725, 4698.718076915396, 4700.130475861976, 2858.3568697043206, 2905.5205891027954, 2945.7937944343903, 2980.582718419423, 3019.0942717184757, 879.3317079019927, 892.8705337725993, 892.8973168085688, 899.0470101692227, 906.2206502407923, 10823.404712740796, 10860.022597966221, 10871.510782014828, 10887.945560272226, 10897.83653245202, 4285.100128972888, 4427.335899615084, 4459.554044595515, 4497.145542346943, 4511.210538155809, 9221.123993220634, 9245.491497967676, 9258.814823703487, 9267.953660231655, 9286.535673216313, 4687.484376718627, 4690.202665546865, 4690.6012988895645, 4690.6951048182445, 4691.507391723888, 3645.6172101929446, 3979.5622481527007, 4096.283150448659, 4112.9588704112575, 4164.979175104105, 3718.2377612589353, 3752.849886004584, 3816.885493435201, 3831.7700937943814, 3861.7489863158803, 1213.4600102954278, 1229.0843321725192, 1233.2045830439317, 1245.6698974771512, 1263.5261776833688, 834.4492634860962, 840.1682334488934, 847.1147650705028, 851.2636569367453, 857.4169564696067, 5088.898222035538, 5257.08001099806, 5281.683099013994, 5342.439043900412, 5345.893082138336, 414.9066460046465, 419.7418587568623, 420.694996127811, 425.33435173952665, 897.228383590112, 3666.5966743658178, 3757.5490941087246, 3796.734228603971, 3841.327767640652, 3891.0661441253897, 9738.805223895482, 9784.119429251401, 9796.363236389612, 9803.921568627446, 9919.454430006275, 9691.51542422876, 9750.707478775648, 9754.46350450718, 9793.118619324296, 9844.950775246078, 7054.012438258648, 7310.671019804122, 7311.305425984523, 7380.225076367012, 7521.962390188014, 5343.091674415281, 5351.554506296312, 5384.6769193848495, 5455.345358556999, 5585.0, 8867.068957759342, 8883.156100170474, 8939.821203575762, 8971.910280897107, 8976.730698079067, 8847.49592569252, 9244.630214791334, 9262.379083660468, 9270.582823772811, 9293.907060929305, 6119.694015299128, 6503.577267477599, 6562.573432726857, 6679.298673639198, 6704.195496540447, 7632.19861914491, 7684.11632662251, 7727.072751269883, 7742.961285193538, 7746.845063098819, 6595.505337099833, 6720.495962802712, 6775.525713200226, 6850.21222559412, 6911.136107986502, 6612.173478315211, 6681.29846366144, 6725.764598239038, 6735.966320168368, 6780.220274668279, 6142.078688196628, 6431.549791514736, 6473.643949582818, 6493.902591461227, 6497.18785151856, 4562.406887104655, 4595.448546174347, 4598.793054312497, 4704.811807527661, 4792.0, 4630.39804825384, 4778.593819525379, 4790.808367665394, 4880.389951256093, 4890.168671325954], "value": 4133.777410199979, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.97728181745447, 286.70326212370196, 295.6156995905324, 296.1249507704733, 301.26942165249267, 4814.614830813535, 4843.46721860596, 4859.663592512056, 4876.756162191885, 4893.755312234383, 4546.613537849286, 4582.633389328854, 4634.258518637019, 4764.1424543582225, 4789.544993225647, 3762.849486020556, 3766.4538641896906, 3806.200697853454, 3808.5620153682266, 3808.9238215235746, 3772.4152756322815, 3775.1128484806186, 3776.622337766215, 3795.715321350896, 3814.370628846237, 3595.478655594937, 3648.7810731356203, 3756.69946404287, 3789.393697008479, 3793.4499497572833, 3753.380692185786, 3756.7370284080125, 3772.5472943246714, 3777.9055523611855, 3785.5835858055593, 3359.6304406515264, 3361.277325375056, 3362.596488421405, 3364.411228035084, 3377.2738861144726, 4409.536998615164, 4566.20091483989, 4576.748278844662, 4623.306504024423, 4625.884352891171, 4308.82764689412, 4309.310510318334, 4331.415258940035, 4341.60925516705, 4343.522212556616, 4968.527989840959, 4978.82574109908, 4985.379751580751, 5017.623678224148, 5028.296038554606, 3662.890113296579, 3676.3933950898067, 3686.981565092184, 3687.612800655946, 3706.4625629283873, 5029.673071250357, 5218.191180367049, 5225.790968361299, 5231.450697676765, 5247.265382846366, 3730.7948062856804, 3735.14091758897, 3735.869244576427, 3740.5698344690163, 3763.8118094095184, 3801.8099095045136, 3805.771653700787, 3808.790516521618, 3816.904577385588, 3825.6939444844347, 4195.265828480004, 4237.533871274158, 4289.528151903288, 4306.267934451151, 4320.179165958521, 4967.5032496750755, 5007.724575148329, 5040.899182016339, 5045.8738531537, 5059.787035232654, 1251.949922003128, 1276.2215048820212, 1284.4348486665838, 1304.6868751499571, 1316.2892038299403, 851.3231980575457, 856.6573370651789, 871.0810095349341, 871.8605023196258, 873.7597160780709, 3954.762714237155, 3957.861474848424, 3981.343078392033, 3987.0, 3993.3228044221496, 4556.384888040138, 4579.793909274024, 4584.9541504585195, 4611.400517932714, 4626.68139580218, 3982.342913419311, 3988.561258261589, 3989.5611482736876, 3991.201759648082, 3994.800259987047, 2344.355302291883, 2449.9265022049367, 2461.839980401304, 2464.322311364389, 2475.504899020203, 4327.891802704958, 4374.803133858984, 4388.802503887333, 4419.447569053868, 4420.204363214652, 4650.627949764011, 4659.394278743742, 4670.439547254283, 4704.65969161892, 4726.243800991893, 4150.979245103756, 4271.935920961126, 4306.181825453154, 4333.609975102256, 4363.890902727394, 4548.56788605082, 4559.658025648156, 4562.908741825211, 4603.861884143481, 4621.2837010263465, 4457.8439754608435, 4464.53122422154, 4513.435820522434, 4516.683832131719, 4539.251023581072, 4156.58434156594, 4246.810892949979, 4351.630111440436, 4352.173087113439, 4376.0, 3408.5739282589675, 3432.9485057724655, 3435.7423193260106, 3449.6205417404567, 3465.653434656514, 4546.636269098398, 4573.748443835488, 4610.515895831024, 4614.2617181250835, 4990.301357809982, 4851.660383773102, 5011.273365361929, 5072.4673909239, 5074.314967479343, 5095.541401273903, 5032.547070763649, 5043.36957880265, 5047.520485553867, 5059.316992206005, 5071.264666623393, 4742.241241401359, 4912.901741965069, 4970.155073637563, 4976.880201954531, 4982.302477653059, 5025.899482010413, 5028.220625803006, 5033.421156566895, 5038.244263360525, 5068.417132029716, 4941.504540207173, 4958.57852082577, 4974.32846565709, 5004.499550044967, 5022.347094877642], "value": 3664.06463664247, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.7193926763323, 653.885125860408, 722.9024081748962, 743.9739609113682, 758.1887380502864, 12333.691657708561, 12449.502019919193, 12696.492140314376, 12699.492020319176, 12974.351282435875, 11883.168178227528, 11896.0, 11931.64205073847, 11940.985016273627, 12044.879551204485, 620.1782638004639, 769.0348612491316, 793.1869833420747, 806.3146325623203, 806.3549160671469, 788.585992354013, 800.7317548621219, 802.2819576479077, 802.3100133884855, 802.4262652203703, 851.0680804519042, 854.406187699549, 859.5143743784741, 859.7334826203866, 859.7334826203897, 656.3928366261189, 800.5076877720219, 832.7085520067989, 845.3913182508584, 846.5809424334932, 614.9969250153765, 615.4614712126889, 615.4891440104702, 615.7383112177307, 615.7444660465901, 11498.712532186679, 11582.47878845454, 11596.59411920587, 11612.941935290353, 11635.243709158878, 11832.290062596272, 12309.0, 12354.567590134391, 12451.190672606252, 12658.367081645973, 10993.230473866832, 10996.615118470894, 11125.165612579009, 11158.665240042812, 11304.434778261055, 4015.9397609035886, 4021.8592349267633, 4045.8583949561626, 4059.451973983504, 4140.585941405865, 43452.3482147768, 43888.780556097336, 44076.33885491752, 44086.33870491945, 44117.99999999968, 9097.454152750855, 9407.90592094084, 9991.650292239738, 10117.241206909364, 10193.74515637115, 16939.745903811454, 17095.230714617872, 17112.74330885038, 17131.74302385465, 17166.0, 6326.398992095744, 6326.5255105867245, 6432.388923052303, 6474.5791523550815, 6525.4779617630475, 3459.0, 3469.3061387722555, 3550.1302180965827, 3565.893023209308, 3640.927181456409, 2626.448445826398, 2689.596560515938, 2695.420484595782, 2701.6082668013028, 2703.7025927147997, 465.33457156266866, 482.29103218269086, 489.18550613229286, 491.56250936666396, 495.88842510434864, 494.7575687912969, 500.68456872171026, 504.3468708023046, 573.2604939627834, 601.2634522709645, 7803.68785248595, 7904.802379940547, 8136.389770767216, 8241.464304820167, 8301.709440169565, 455.257929574791, 561.5900392713343, 562.1258942344612, 577.6447484796904, 581.2098735893438, 3677.852885884588, 3722.3299806034634, 3791.33651610967, 3807.6573108420375, 3930.587288334741, 47473.28790068152, 48341.516584834404, 48608.756956214995, 53951.190732139046, 53963.730181348845, 47044.29433558499, 49349.272775452686, 49381.0, 50306.0, 50433.49566504288, 44176.116477670264, 44521.55478445239, 45255.0, 45330.546694533296, 46771.532284676716, 7991.360691144579, 8005.279524842559, 8017.198280171936, 8117.79705507367, 8222.095569487232, 31188.53217201789, 32024.199395015312, 32171.839140804146, 32267.677323227406, 33130.17174570655, 37909.0, 40841.0, 40893.0, 40968.38547421731, 41689.791551043265, 10025.598976040877, 10516.211284153565, 10550.103241224275, 10597.364158150536, 10650.094741947021, 6913.5506192099365, 7905.893174955396, 10728.356298622108, 11179.628808008252, 11667.708307292047, 9628.47043412619, 9989.850152247867, 10242.692719218137, 10369.377837329785, 10373.481325933522, 10915.235933484897, 10959.561617535528, 10989.285696429706, 11001.779964400826, 11291.322520648788, 9775.233354075917, 9912.35569687968, 9912.900870991198, 10145.695629131138, 10148.391096534231, 9037.77405564866, 9069.365144439826, 9110.579547597457, 9321.254299656146, 9410.058994100535, 8809.339299552432, 9082.863757043782, 9131.315151363542, 9151.679691210775, 9220.446773193362], "value": 5834.47847823646, "unit": "score"}], "name": "dromaeo_css", "value": 6223.9670694947035, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 117.0, 114.0, 115.0, 113.0, 114.0, 114.0, 113.0, 116.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 130.0, 152.0, 214.0, 131.0, 160.0, 165.0, 131.0, 192.0, 129.0], "value": 155.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 161.0, 161.0, 161.0, 160.0, 160.0, 162.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 150.0, 76.0, 74.0, 77.0, 76.0, 128.0, 76.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 155.0, 93.0, 94.0, 94.0, 129.0, 93.0, 94.0, 126.0], "value": 106.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 118.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 96.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 69.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 66.0, 65.0, 65.0, 69.0, 65.0, 64.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 86.0, 84.0, 84.0, 84.0, 83.0, 90.0, 145.0, 83.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 132.0, 130.0, 130.0, 131.0, 129.0, 127.0, 131.0, 149.0, 131.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 178.0, 171.0, 173.0, 170.0, 172.0, 174.0, 173.0, 172.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 66.0, 67.0, 65.0, 67.0, 65.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1574.9, "alertThreshold": 2.0}]} 18:25:13 INFO - Return code: 0 18:25:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:25:13 ERROR - # TBPL SUCCESS # 18:25:13 INFO - Running post-action listener: _resource_record_post_action 18:25:13 INFO - ##### 18:25:13 INFO - ##### Finished run-tests step (success) 18:25:13 INFO - ##### 18:25:13 INFO - Running post-run listener: _resource_record_post_run 18:25:13 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530518016; Read time: 20; Write time: 454944 18:25:13 INFO - TinderboxPrint: CPU usage
13.2% 18:25:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 18:25:13 INFO - TinderboxPrint: I/O write bytes / time
530,518,016 / 454,944 18:25:13 INFO - TinderboxPrint: CPU idle
8,320.6 (86.8%) 18:25:13 INFO - TinderboxPrint: CPU user
1,220.2 (12.7%) 18:25:13 INFO - TinderboxPrint: Swap in / out
0 / 0 18:25:13 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236748800; Read time: 0; Write time: 250668 18:25:13 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293769216; Read time: 20; Write time: 204276 18:25:14 INFO - Running post-run listener: _upload_blobber_files 18:25:14 INFO - Blob upload gear active. 18:25:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:25:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:25:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:25:14 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:25:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:25:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:15 INFO - (blobuploader) - INFO - Done attempting. 18:25:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:25:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:19 INFO - (blobuploader) - INFO - Done attempting. 18:25:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:25:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:19 INFO - (blobuploader) - INFO - Done attempting. 18:25:19 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:19 INFO - Return code: 0 18:25:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3be934d044332df21e65ddd5d565d1dc91d8fb5b2da945815798064b45869d884f2d2699fa8d70e88bfdef2c06dddeafb43ef154e683592b0f1393ea6a9f9192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/69c58af34626ae3aa65e356c95618c62231f20aa93ecc6cc6a84d700b0a06d26c07594ac832b84b49655029cb683a38e7dec724417ab8a04faffcad50b695e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5cc2ffb62973ca222842a1b2cffe58ae5ccfd8536319c818b6a83c053590bbaad5ea122b9ce15334be0b5adf454f4f60beae2c90fe374f5542ae656fb20ad849"} 18:25:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:19 INFO - Contents: 18:25:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3be934d044332df21e65ddd5d565d1dc91d8fb5b2da945815798064b45869d884f2d2699fa8d70e88bfdef2c06dddeafb43ef154e683592b0f1393ea6a9f9192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/69c58af34626ae3aa65e356c95618c62231f20aa93ecc6cc6a84d700b0a06d26c07594ac832b84b49655029cb683a38e7dec724417ab8a04faffcad50b695e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5cc2ffb62973ca222842a1b2cffe58ae5ccfd8536319c818b6a83c053590bbaad5ea122b9ce15334be0b5adf454f4f60beae2c90fe374f5542ae656fb20ad849"} 18:25:19 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:19 INFO - Copying logs to upload dir... 18:25:19 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.745784 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-19 18:25:19.715197) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 18:25:19.719076) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3be934d044332df21e65ddd5d565d1dc91d8fb5b2da945815798064b45869d884f2d2699fa8d70e88bfdef2c06dddeafb43ef154e683592b0f1393ea6a9f9192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/69c58af34626ae3aa65e356c95618c62231f20aa93ecc6cc6a84d700b0a06d26c07594ac832b84b49655029cb683a38e7dec724417ab8a04faffcad50b695e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5cc2ffb62973ca222842a1b2cffe58ae5ccfd8536319c818b6a83c053590bbaad5ea122b9ce15334be0b5adf454f4f60beae2c90fe374f5542ae656fb20ad849"} build_url:https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011681 build_url: 'https://queue.taskcluster.net/v1/task/PdE33pfTTEWwQBwKWIvGAQ/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/Fx-Team-Non-PGO/sha512/3be934d044332df21e65ddd5d565d1dc91d8fb5b2da945815798064b45869d884f2d2699fa8d70e88bfdef2c06dddeafb43ef154e683592b0f1393ea6a9f9192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/69c58af34626ae3aa65e356c95618c62231f20aa93ecc6cc6a84d700b0a06d26c07594ac832b84b49655029cb683a38e7dec724417ab8a04faffcad50b695e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5cc2ffb62973ca222842a1b2cffe58ae5ccfd8536319c818b6a83c053590bbaad5ea122b9ce15334be0b5adf454f4f60beae2c90fe374f5542ae656fb20ad849"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 18:25:19.748841) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:25:19.749263) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463704082.86846-264834000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 18:25:19.832872) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 18:25:19.833253) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 18:25:19.833636) ========= ========= Total master_lag: 0.18 =========