builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1464912369.68 results: success (0) buildid: 20160602143006 builduid: 9abe255c289f4eeab38fe4b7321a8100 revision: f76a0fdadbf053c3373961f9afbc966c011a50fe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:09.681048) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:09.681482) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:09.681799) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:09.701198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:09.701525) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-02 17:06:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.3M=0s 2016-06-02 17:06:10 (35.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109313 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:10.024554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:10.024864) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014233 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:10.064196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 17:06:10.067577) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f76a0fdadbf053c3373961f9afbc966c011a50fe --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 f76a0fdadbf053c3373961f9afbc966c011a50fe --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=a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-02 17:06:10,814 truncating revision to first 12 chars 2016-06-02 17:06:10,814 Setting DEBUG logging. 2016-06-02 17:06:10,814 attempt 1/10 2016-06-02 17:06:10,814 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f76a0fdadbf0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 17:06:12,025 unpacking tar archive at: fx-team-f76a0fdadbf0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.547587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 17:06:11.633710) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:11.634021) ========= script_repo_revision: f76a0fdadbf053c3373961f9afbc966c011a50fe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:11.634423) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:11.634880) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 17:06:11.642728) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-02 17:06:11.643014) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:06:12 INFO - MultiFileLogger online at 20160602 17:06:12 in /builds/slave/test 17:06:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:06:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:06:12 INFO - {'append_to_log': False, 17:06:12 INFO - 'base_work_dir': '/builds/slave/test', 17:06:12 INFO - 'blob_upload_branch': 'Fx-Team', 17:06:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:06:12 INFO - 'branch': 'Fx-Team', 17:06:12 INFO - 'buildbot_json_path': 'buildprops.json', 17:06:12 INFO - 'config_files': ('talos/linux_config.py',), 17:06:12 INFO - 'default_actions': ('clobber', 17:06:12 INFO - 'read-buildbot-config', 17:06:12 INFO - 'download-and-extract', 17:06:12 INFO - 'populate-webroot', 17:06:12 INFO - 'create-virtualenv', 17:06:12 INFO - 'install', 17:06:12 INFO - 'run-tests'), 17:06:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:06:12 INFO - 'download_minidump_stackwalk': True, 17:06:12 INFO - 'download_symbols': 'ondemand', 17:06:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:06:12 INFO - 'tooltool.py': '/tools/tooltool.py', 17:06:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:06:12 INFO - '/tools/misc-python/virtualenv.py')}, 17:06:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:06:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:06:12 INFO - 'installer_path': 'installer.exe', 17:06:12 INFO - 'log_level': 'info', 17:06:12 INFO - 'log_name': 'talos', 17:06:12 INFO - 'log_to_console': True, 17:06:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:06:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:06:12 INFO - 'opt_config_files': (), 17:06:12 INFO - 'pip_index': False, 17:06:12 INFO - 'sps_profile': False, 17:06:12 INFO - 'sps_profile_interval': 0, 17:06:12 INFO - 'suite': 'dromaeojs', 17:06:12 INFO - 'system_bits': '32', 17:06:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:06:12 INFO - 'title': 'talos-linux64-ix-064', 17:06:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:06:12 INFO - 'use_talos_json': True, 17:06:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:06:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:06:12 INFO - 'work_dir': 'build'} 17:06:12 INFO - ##### 17:06:12 INFO - ##### Running clobber step. 17:06:12 INFO - ##### 17:06:12 INFO - Running pre-action listener: _resource_record_pre_action 17:06:12 INFO - Running main action method: clobber 17:06:12 INFO - rmtree: /builds/slave/test/build 17:06:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:06:12 INFO - Running post-action listener: _resource_record_post_action 17:06:12 INFO - ##### 17:06:12 INFO - ##### Finished clobber step (success) 17:06:12 INFO - ##### 17:06:12 INFO - ##### 17:06:12 INFO - ##### Running read-buildbot-config step. 17:06:12 INFO - ##### 17:06:12 INFO - Running pre-action listener: _resource_record_pre_action 17:06:12 INFO - Running main action method: read_buildbot_config 17:06:12 INFO - Using buildbot properties: 17:06:12 INFO - { 17:06:12 INFO - "project": "", 17:06:12 INFO - "product": "firefox", 17:06:12 INFO - "script_repo_revision": "production", 17:06:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:06:12 INFO - "repository": "", 17:06:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:06:12 INFO - "buildid": "20160602143006", 17:06:12 INFO - "pgo_build": "True", 17:06:12 INFO - "basedir": "/builds/slave/test", 17:06:12 INFO - "buildnumber": 147, 17:06:12 INFO - "slavename": "talos-linux64-ix-064", 17:06:12 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:06:12 INFO - "platform": "ubuntu64_hw", 17:06:12 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 17:06:12 INFO - "branch": "fx-team", 17:06:12 INFO - "repo_path": "integration/fx-team", 17:06:12 INFO - "revision": "f76a0fdadbf053c3373961f9afbc966c011a50fe", 17:06:12 INFO - "stage_platform": "linux64-pgo", 17:06:12 INFO - "builduid": "9abe255c289f4eeab38fe4b7321a8100", 17:06:12 INFO - "slavebuilddir": "test" 17:06:12 INFO - } 17:06:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:06:12 INFO - Running post-action listener: _resource_record_post_action 17:06:12 INFO - ##### 17:06:12 INFO - ##### Finished read-buildbot-config step (success) 17:06:12 INFO - ##### 17:06:12 INFO - ##### 17:06:12 INFO - ##### Running download-and-extract step. 17:06:12 INFO - ##### 17:06:12 INFO - Running pre-action listener: _resource_record_pre_action 17:06:12 INFO - Running main action method: download_and_extract 17:06:12 INFO - mkdir: /builds/slave/test/build/tests 17:06:12 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')]} 17:06:12 INFO - https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:06:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:06:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:06:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:15 INFO - Downloaded 1361 bytes. 17:06:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:06:15 INFO - Using the following test package requirements: 17:06:15 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:15 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:06:15 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:06:15 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'jsshell-linux-x86_64.zip'], 17:06:15 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:06:15 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:15 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:06:15 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:06:15 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:06:15 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:06:15 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:15 INFO - https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:06:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:06:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:06:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:18 INFO - Downloaded 24439742 bytes. 17:06:18 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'] 17:06:18 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 17:06:20 INFO - Return code: 0 17:06:20 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 17:06:20 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')]} 17:06:20 INFO - https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:06:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:06:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:06:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:22 INFO - Downloaded 24439742 bytes. 17:06:22 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'] 17:06:22 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 17:06:24 INFO - Return code: 0 17:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:24 INFO - https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:06:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:25 INFO - Downloaded 10834055 bytes. 17:06:25 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'] 17:06:25 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 17:06:25 INFO - Return code: 0 17:06: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')]} 17:06:25 INFO - https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:06:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:06:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:06:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CP6W1QfjQYGO_9AmViwb9g/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 17:06:26 INFO - Downloaded 59046974 bytes. 17:06:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:06:26 INFO - mkdir: /builds/slave/test/properties 17:06:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:06:26 INFO - Writing to file /builds/slave/test/properties/build_url 17:06:26 INFO - Contents: 17:06:26 INFO - build_url:https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:06:27 INFO - Running post-action listener: _resource_record_post_action 17:06:27 INFO - Running post-action listener: set_extra_try_arguments 17:06:27 INFO - ##### 17:06:27 INFO - ##### Finished download-and-extract step (success) 17:06:27 INFO - ##### 17:06:27 INFO - ##### 17:06:27 INFO - ##### Running populate-webroot step. 17:06:27 INFO - ##### 17:06:27 INFO - Running pre-action listener: _resource_record_pre_action 17:06:27 INFO - Running main action method: populate_webroot 17:06:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:06:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:06:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:06:27 INFO - '--tppagecycles', 17:06:27 INFO - '7'], 17:06:27 INFO - 'tests': ['tsvgm']}}, 17:06:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:06:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:06:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:06:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:06:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['damp', 'tps']}, 17:06:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'tests': ['damp', 'tps']}, 17:06:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['dromaeo_dom']}, 17:06:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:06:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['a11yr', 17:06:27 INFO - 'ts_paint', 17:06:27 INFO - 'tpaint', 17:06:27 INFO - 'sessionrestore', 17:06:27 INFO - 'sessionrestore_no_auto_restore', 17:06:27 INFO - 'tabpaint']}, 17:06:27 INFO - 'other-e10s': {'tests': ['a11yr', 17:06:27 INFO - 'ts_paint', 17:06:27 INFO - 'tpaint', 17:06:27 INFO - 'sessionrestore', 17:06:27 INFO - 'sessionrestore_no_auto_restore', 17:06:27 INFO - 'tabpaint']}, 17:06:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:06:27 INFO - 'ts_paint', 17:06:27 INFO - 'tpaint', 17:06:27 INFO - 'sessionrestore', 17:06:27 INFO - 'sessionrestore_no_auto_restore', 17:06:27 INFO - 'tabpaint']}, 17:06:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:06:27 INFO - 'ts_paint', 17:06:27 INFO - 'tpaint', 17:06:27 INFO - 'sessionrestore', 17:06:27 INFO - 'sessionrestore_no_auto_restore', 17:06:27 INFO - 'tabpaint']}, 17:06:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['a11yr', 17:06:27 INFO - 'ts_paint', 17:06:27 INFO - 'tpaint', 17:06:27 INFO - 'sessionrestore', 17:06:27 INFO - 'sessionrestore_no_auto_restore', 17:06:27 INFO - 'tabpaint']}, 17:06:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['a11yr', 17:06:27 INFO - 'ts_paint', 17:06:27 INFO - 'tpaint', 17:06:27 INFO - 'sessionrestore', 17:06:27 INFO - 'sessionrestore_no_auto_restore', 17:06:27 INFO - 'tabpaint']}, 17:06:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['tsvgx', 17:06:27 INFO - 'tsvgr_opacity', 17:06:27 INFO - 'tart', 17:06:27 INFO - 'tscrollx', 17:06:27 INFO - 'cart']}, 17:06:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:06:27 INFO - 'tsvgr_opacity', 17:06:27 INFO - 'tart', 17:06:27 INFO - 'tscrollx', 17:06:27 INFO - 'cart']}, 17:06:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'talos_options': ['--disable-e10s'], 17:06:27 INFO - 'tests': ['tp5o']}, 17:06:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'tests': ['tp5o']}, 17:06:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'talos_options': ['--disable-e10s', 17:06:27 INFO - '--xperf_path', 17:06:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:27 INFO - 'tests': ['tp5n']}, 17:06:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:27 INFO - 'talos_options': ['--xperf_path', 17:06:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:27 INFO - 'tests': ['tp5n']}}, 17:06:27 INFO - 'talos.zip': {'path': '', 17:06:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:06:27 INFO - Running post-action listener: _resource_record_post_action 17:06:27 INFO - ##### 17:06:27 INFO - ##### Finished populate-webroot step (success) 17:06:27 INFO - ##### 17:06:27 INFO - ##### 17:06:27 INFO - ##### Running create-virtualenv step. 17:06:27 INFO - ##### 17:06:27 INFO - Running pre-action listener: _resource_record_pre_action 17:06:27 INFO - Running main action method: create_virtualenv 17:06:27 INFO - Creating virtualenv /builds/slave/test/build/venv 17:06:27 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 17:06:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:06:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:06:27 INFO - Using real prefix '/usr' 17:06:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:06:28 INFO - Installing distribute.............................................................................................................................................................................................done. 17:06:30 INFO - Installing pip.................done. 17:06:30 INFO - Return code: 0 17:06:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:06:30 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')]} 17:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:30 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')]} 17:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:30 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 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:30 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 17:06:30 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 17:06:30 INFO - Using env: {'DISPLAY': ':0', 17:06:30 INFO - 'HOME': '/home/cltbld', 17:06:30 INFO - 'LANG': 'en_US.UTF-8', 17:06:30 INFO - 'LANGUAGE': 'en_US:en', 17:06:30 INFO - 'LOGNAME': 'cltbld', 17:06:30 INFO - 'MAIL': '/var/mail/cltbld', 17:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:30 INFO - 'MOZ_NO_REMOTE': '1', 17:06:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:30 INFO - 'NO_EM_RESTART': '1', 17:06:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:30 INFO - 'PWD': '/builds/slave/test', 17:06:30 INFO - 'SHELL': '/bin/bash', 17:06:30 INFO - 'SHLVL': '1', 17:06:30 INFO - 'TERM': 'linux', 17:06:30 INFO - 'TMOUT': '86400', 17:06:30 INFO - 'USER': 'cltbld', 17:06:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:30 INFO - '_': '/tools/buildbot/bin/python'} 17:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:30 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 17:06:30 INFO - Cleaning up... 17:06:30 INFO - Return code: 0 17:06:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:06:30 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')]} 17:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:30 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')]} 17:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:30 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 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:30 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 17:06:30 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 17:06:30 INFO - Using env: {'DISPLAY': ':0', 17:06:30 INFO - 'HOME': '/home/cltbld', 17:06:30 INFO - 'LANG': 'en_US.UTF-8', 17:06:30 INFO - 'LANGUAGE': 'en_US:en', 17:06:30 INFO - 'LOGNAME': 'cltbld', 17:06:30 INFO - 'MAIL': '/var/mail/cltbld', 17:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:30 INFO - 'MOZ_NO_REMOTE': '1', 17:06:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:30 INFO - 'NO_EM_RESTART': '1', 17:06:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:30 INFO - 'PWD': '/builds/slave/test', 17:06:30 INFO - 'SHELL': '/bin/bash', 17:06:30 INFO - 'SHLVL': '1', 17:06:30 INFO - 'TERM': 'linux', 17:06:30 INFO - 'TMOUT': '86400', 17:06:30 INFO - 'USER': 'cltbld', 17:06:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:30 INFO - '_': '/tools/buildbot/bin/python'} 17:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:30 INFO - Downloading/unpacking psutil>=3.1.1 17:06:30 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 17:06:30 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 17:06:30 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 17:06:30 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 17:06:30 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 17:06:30 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 17:06:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:06:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:32 INFO - Installing collected packages: psutil 17:06:32 INFO - Running setup.py install for psutil 17:06:32 INFO - building 'psutil._psutil_linux' extension 17:06:32 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 17:06:32 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 17:06:32 INFO - building 'psutil._psutil_posix' extension 17:06:32 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 17:06:33 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 17:06:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:33 INFO - Successfully installed psutil 17:06:33 INFO - Cleaning up... 17:06:33 INFO - Return code: 0 17:06:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:06:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:06:33 INFO - Using env: {'DISPLAY': ':0', 17:06:33 INFO - 'HOME': '/home/cltbld', 17:06:33 INFO - 'LANG': 'en_US.UTF-8', 17:06:33 INFO - 'LANGUAGE': 'en_US:en', 17:06:33 INFO - 'LOGNAME': 'cltbld', 17:06:33 INFO - 'MAIL': '/var/mail/cltbld', 17:06:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:33 INFO - 'MOZ_NO_REMOTE': '1', 17:06:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:33 INFO - 'NO_EM_RESTART': '1', 17:06:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:33 INFO - 'PWD': '/builds/slave/test', 17:06:33 INFO - 'SHELL': '/bin/bash', 17:06:33 INFO - 'SHLVL': '1', 17:06:33 INFO - 'TERM': 'linux', 17:06:33 INFO - 'TMOUT': '86400', 17:06:33 INFO - 'USER': 'cltbld', 17:06:33 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:33 INFO - '_': '/tools/buildbot/bin/python'} 17:06:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:06:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:06:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:06:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:06:35 INFO - Installing collected packages: mozsystemmonitor 17:06:35 INFO - Running setup.py install for mozsystemmonitor 17:06:35 INFO - Successfully installed mozsystemmonitor 17:06:35 INFO - Cleaning up... 17:06:35 INFO - Return code: 0 17:06:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:35 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 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:35 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 17:06:35 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 17:06:35 INFO - Using env: {'DISPLAY': ':0', 17:06:35 INFO - 'HOME': '/home/cltbld', 17:06:35 INFO - 'LANG': 'en_US.UTF-8', 17:06:35 INFO - 'LANGUAGE': 'en_US:en', 17:06:35 INFO - 'LOGNAME': 'cltbld', 17:06:35 INFO - 'MAIL': '/var/mail/cltbld', 17:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:35 INFO - 'MOZ_NO_REMOTE': '1', 17:06:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:35 INFO - 'NO_EM_RESTART': '1', 17:06:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:35 INFO - 'PWD': '/builds/slave/test', 17:06:35 INFO - 'SHELL': '/bin/bash', 17:06:35 INFO - 'SHLVL': '1', 17:06:35 INFO - 'TERM': 'linux', 17:06:35 INFO - 'TMOUT': '86400', 17:06:35 INFO - 'USER': 'cltbld', 17:06:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:35 INFO - '_': '/tools/buildbot/bin/python'} 17:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:35 INFO - Downloading/unpacking blobuploader==1.2.4 17:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:37 INFO - Downloading blobuploader-1.2.4.tar.gz 17:06:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:06:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:06:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:38 INFO - Downloading docopt-0.6.1.tar.gz 17:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:06:38 INFO - Installing collected packages: blobuploader, requests, docopt 17:06:38 INFO - Running setup.py install for blobuploader 17:06:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:06:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:06:38 INFO - Running setup.py install for requests 17:06:38 INFO - Running setup.py install for docopt 17:06:38 INFO - Successfully installed blobuploader requests docopt 17:06:38 INFO - Cleaning up... 17:06:38 INFO - Return code: 0 17:06:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:38 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')]} 17:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:38 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')]} 17:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:38 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 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:38 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 17:06:38 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 17:06:38 INFO - Using env: {'DISPLAY': ':0', 17:06:38 INFO - 'HOME': '/home/cltbld', 17:06:38 INFO - 'LANG': 'en_US.UTF-8', 17:06:38 INFO - 'LANGUAGE': 'en_US:en', 17:06:38 INFO - 'LOGNAME': 'cltbld', 17:06:38 INFO - 'MAIL': '/var/mail/cltbld', 17:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:38 INFO - 'MOZ_NO_REMOTE': '1', 17:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:38 INFO - 'NO_EM_RESTART': '1', 17:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:38 INFO - 'PWD': '/builds/slave/test', 17:06:38 INFO - 'SHELL': '/bin/bash', 17:06:38 INFO - 'SHLVL': '1', 17:06:38 INFO - 'TERM': 'linux', 17:06:38 INFO - 'TMOUT': '86400', 17:06:38 INFO - 'USER': 'cltbld', 17:06:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:38 INFO - '_': '/tools/buildbot/bin/python'} 17:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:39 INFO - Running setup.py (path:/tmp/pip-orTDKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:39 INFO - Running setup.py (path:/tmp/pip-qmB34G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:06:39 INFO - Running setup.py (path:/tmp/pip-7WJ8Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:06:39 INFO - Running setup.py (path:/tmp/pip-ivJUY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:06:39 INFO - Running setup.py (path:/tmp/pip-nY2ydR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:06:39 INFO - Running setup.py (path:/tmp/pip-3vm5SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:06:39 INFO - Running setup.py (path:/tmp/pip-2DY0tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:06:39 INFO - Running setup.py (path:/tmp/pip-ZXb5_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:06:39 INFO - Running setup.py (path:/tmp/pip-OYWrby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:06:39 INFO - Running setup.py (path:/tmp/pip-MpIdJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:06:39 INFO - Running setup.py (path:/tmp/pip-NDWBEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:06:39 INFO - Running setup.py (path:/tmp/pip-Cn7F66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:06:40 INFO - Running setup.py (path:/tmp/pip-ese1QO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:06:40 INFO - Running setup.py (path:/tmp/pip-cOzmPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:40 INFO - Running setup.py (path:/tmp/pip-r9pdbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:06:40 INFO - Running setup.py (path:/tmp/pip-ekHzrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:06:40 INFO - Running setup.py (path:/tmp/pip-XpTuVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:06:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:06:40 INFO - Running setup.py install for manifestparser 17:06:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozcrash 17:06:40 INFO - Running setup.py install for mozdebug 17:06:40 INFO - Running setup.py install for mozdevice 17:06:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozfile 17:06:40 INFO - Running setup.py install for mozhttpd 17:06:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozinfo 17:06:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for mozInstall 17:06:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for mozleak 17:06:41 INFO - Running setup.py install for mozlog 17:06:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for moznetwork 17:06:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for mozprocess 17:06:41 INFO - Running setup.py install for mozprofile 17:06:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for mozrunner 17:06:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for mozscreenshot 17:06:41 INFO - Running setup.py install for moztest 17:06:42 INFO - Running setup.py install for mozversion 17:06:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:06:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:06:42 INFO - Cleaning up... 17:06:42 INFO - Return code: 0 17:06:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:42 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')]} 17:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:42 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')]} 17:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:42 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 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:42 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 17:06:42 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 17:06:42 INFO - Using env: {'DISPLAY': ':0', 17:06:42 INFO - 'HOME': '/home/cltbld', 17:06:42 INFO - 'LANG': 'en_US.UTF-8', 17:06:42 INFO - 'LANGUAGE': 'en_US:en', 17:06:42 INFO - 'LOGNAME': 'cltbld', 17:06:42 INFO - 'MAIL': '/var/mail/cltbld', 17:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:42 INFO - 'MOZ_NO_REMOTE': '1', 17:06:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:42 INFO - 'NO_EM_RESTART': '1', 17:06:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:42 INFO - 'PWD': '/builds/slave/test', 17:06:42 INFO - 'SHELL': '/bin/bash', 17:06:42 INFO - 'SHLVL': '1', 17:06:42 INFO - 'TERM': 'linux', 17:06:42 INFO - 'TMOUT': '86400', 17:06:42 INFO - 'USER': 'cltbld', 17:06:42 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:42 INFO - '_': '/tools/buildbot/bin/python'} 17:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:42 INFO - Running setup.py (path:/tmp/pip-H7o7OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:42 INFO - Running setup.py (path:/tmp/pip-5XnlzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:06:42 INFO - Running setup.py (path:/tmp/pip-spGoTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:06:42 INFO - Running setup.py (path:/tmp/pip-N8ngqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:06:42 INFO - Running setup.py (path:/tmp/pip-58zzXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:06:42 INFO - Running setup.py (path:/tmp/pip-vbc9Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:06:42 INFO - Running setup.py (path:/tmp/pip-qCHYOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:06:42 INFO - Running setup.py (path:/tmp/pip-EPZs0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:06:42 INFO - Running setup.py (path:/tmp/pip-2V2flP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:06:43 INFO - Running setup.py (path:/tmp/pip-kGwCUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:06:43 INFO - Running setup.py (path:/tmp/pip-rf3V00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:06:43 INFO - Running setup.py (path:/tmp/pip-3pDahd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:06:43 INFO - Running setup.py (path:/tmp/pip-NbsY6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:06:43 INFO - Running setup.py (path:/tmp/pip-i04Prw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:43 INFO - Running setup.py (path:/tmp/pip-hi6m2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:06:43 INFO - Running setup.py (path:/tmp/pip-IRFwWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:06:43 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)) 17:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:06:43 INFO - Running setup.py (path:/tmp/pip-ziuKBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:06:43 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)) 17:06:43 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)) 17:06:43 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)) 17:06:43 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)) 17:06:43 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)) 17:06:43 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)) 17:06:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:45 INFO - Downloading blessings-1.6.tar.gz 17:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:06:45 INFO - Installing collected packages: blessings 17:06:45 INFO - Running setup.py install for blessings 17:06:45 INFO - Successfully installed blessings 17:06:45 INFO - Cleaning up... 17:06:45 INFO - Return code: 0 17:06:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:06:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:06:45 INFO - Reading from file tmpfile_stdout 17:06:45 INFO - Current package versions: 17:06:45 INFO - argparse == 1.2.1 17:06:45 INFO - blessings == 1.6 17:06:45 INFO - blobuploader == 1.2.4 17:06:45 INFO - docopt == 0.6.1 17:06:45 INFO - manifestparser == 1.1 17:06:45 INFO - mozInstall == 1.12 17:06:45 INFO - mozcrash == 0.17 17:06:45 INFO - mozdebug == 0.1 17:06:45 INFO - mozdevice == 0.48 17:06:45 INFO - mozfile == 1.2 17:06:45 INFO - mozhttpd == 0.7 17:06:45 INFO - mozinfo == 0.9 17:06:45 INFO - mozleak == 0.1 17:06:45 INFO - mozlog == 3.2 17:06:45 INFO - moznetwork == 0.27 17:06:45 INFO - mozprocess == 0.22 17:06:45 INFO - mozprofile == 0.28 17:06:45 INFO - mozrunner == 6.11 17:06:45 INFO - mozscreenshot == 0.1 17:06:45 INFO - mozsystemmonitor == 0.3 17:06:45 INFO - moztest == 0.7 17:06:45 INFO - mozversion == 1.4 17:06:45 INFO - psutil == 3.1.1 17:06:45 INFO - requests == 1.2.3 17:06:45 INFO - wsgiref == 0.1.2 17:06:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:45 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 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:45 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 17:06:45 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 17:06:45 INFO - Using env: {'DISPLAY': ':0', 17:06:45 INFO - 'HOME': '/home/cltbld', 17:06:45 INFO - 'LANG': 'en_US.UTF-8', 17:06:45 INFO - 'LANGUAGE': 'en_US:en', 17:06:45 INFO - 'LOGNAME': 'cltbld', 17:06:45 INFO - 'MAIL': '/var/mail/cltbld', 17:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:45 INFO - 'MOZ_NO_REMOTE': '1', 17:06:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:45 INFO - 'NO_EM_RESTART': '1', 17:06:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:45 INFO - 'PWD': '/builds/slave/test', 17:06:45 INFO - 'SHELL': '/bin/bash', 17:06:45 INFO - 'SHLVL': '1', 17:06:45 INFO - 'TERM': 'linux', 17:06:45 INFO - 'TMOUT': '86400', 17:06:45 INFO - 'USER': 'cltbld', 17:06:45 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:45 INFO - '_': '/tools/buildbot/bin/python'} 17:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 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)) 17:06:46 INFO - Cleaning up... 17:06:46 INFO - Return code: 0 17:06:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:06:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:06:46 INFO - Using env: {'DISPLAY': ':0', 17:06:46 INFO - 'HOME': '/home/cltbld', 17:06:46 INFO - 'LANG': 'en_US.UTF-8', 17:06:46 INFO - 'LANGUAGE': 'en_US:en', 17:06:46 INFO - 'LOGNAME': 'cltbld', 17:06:46 INFO - 'MAIL': '/var/mail/cltbld', 17:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:46 INFO - 'MOZ_NO_REMOTE': '1', 17:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:46 INFO - 'NO_EM_RESTART': '1', 17:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:46 INFO - 'PWD': '/builds/slave/test', 17:06:46 INFO - 'SHELL': '/bin/bash', 17:06:46 INFO - 'SHLVL': '1', 17:06:46 INFO - 'TERM': 'linux', 17:06:46 INFO - 'TMOUT': '86400', 17:06:46 INFO - 'USER': 'cltbld', 17:06:46 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:46 INFO - '_': '/tools/buildbot/bin/python'} 17:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:46 INFO - Downloading/unpacking jsonschema 17:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:06:48 INFO - Downloading/unpacking functools32 (from jsonschema) 17:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:48 INFO - Downloading functools32-3.2.3-2.tar.gz 17:06:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:06:48 INFO - warning: no files found matching '*.txt' 17:06:48 INFO - no previously-included directories found matching 'build' 17:06:48 INFO - no previously-included directories found matching 'dist' 17:06:48 INFO - no previously-included directories found matching '.git*' 17:06:48 INFO - Installing collected packages: jsonschema, functools32 17:06:48 INFO - Running setup.py install for functools32 17:06:48 INFO - warning: no files found matching '*.txt' 17:06:48 INFO - no previously-included directories found matching 'build' 17:06:48 INFO - no previously-included directories found matching 'dist' 17:06:48 INFO - no previously-included directories found matching '.git*' 17:06:48 INFO - Successfully installed jsonschema functools32 17:06:48 INFO - Cleaning up... 17:06:48 INFO - Return code: 0 17:06:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:06:48 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')]} 17:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:48 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')]} 17:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:48 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 0x7ffc941cbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', '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 17:06:48 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 17:06:48 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 17:06:48 INFO - Using env: {'DISPLAY': ':0', 17:06:48 INFO - 'HOME': '/home/cltbld', 17:06:48 INFO - 'LANG': 'en_US.UTF-8', 17:06:48 INFO - 'LANGUAGE': 'en_US:en', 17:06:48 INFO - 'LOGNAME': 'cltbld', 17:06:48 INFO - 'MAIL': '/var/mail/cltbld', 17:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:48 INFO - 'MOZ_NO_REMOTE': '1', 17:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:48 INFO - 'NO_EM_RESTART': '1', 17:06:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:48 INFO - 'PWD': '/builds/slave/test', 17:06:48 INFO - 'SHELL': '/bin/bash', 17:06:48 INFO - 'SHLVL': '1', 17:06:48 INFO - 'TERM': 'linux', 17:06:48 INFO - 'TMOUT': '86400', 17:06:48 INFO - 'USER': 'cltbld', 17:06:48 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:48 INFO - '_': '/tools/buildbot/bin/python'} 17:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:48 INFO - Downloading/unpacking flake8 17:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:06:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:06:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:06:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:06:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:06:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:06:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:06:51 INFO - no previously-included directories found matching 'docs/_build' 17:06:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:06:51 INFO - Running setup.py install for pep8 17:06:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:06:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:06:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:06:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:06:51 INFO - no previously-included directories found matching 'docs/_build' 17:06:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:06:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:06:51 INFO - Cleaning up... 17:06:51 INFO - Return code: 0 17:06:51 INFO - Running post-action listener: _resource_record_post_action 17:06:51 INFO - Running post-action listener: _start_resource_monitoring 17:06:51 INFO - Starting resource monitoring. 17:06:51 INFO - ##### 17:06:51 INFO - ##### Finished create-virtualenv step (success) 17:06:51 INFO - ##### 17:06:51 INFO - ##### 17:06:51 INFO - ##### Running install step. 17:06:51 INFO - ##### 17:06:51 INFO - Running pre-action listener: _resource_record_pre_action 17:06:51 INFO - Running main action method: install 17:06:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:06:52 INFO - Reading from file tmpfile_stdout 17:06:52 INFO - Detecting whether we're running mozinstall >=1.0... 17:06:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:06:52 INFO - Reading from file tmpfile_stdout 17:06:52 INFO - Output received: 17:06:52 INFO - Usage: mozinstall [options] installer 17:06:52 INFO - Options: 17:06:52 INFO - -h, --help show this help message and exit 17:06:52 INFO - -d DEST, --destination=DEST 17:06:52 INFO - Directory to install application into. [default: 17:06:52 INFO - "/builds/slave/test"] 17:06:52 INFO - --app=APP Application being installed. [default: firefox] 17:06:52 INFO - mkdir: /builds/slave/test/build/application 17:06:52 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'] 17:06:52 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 17:07:08 INFO - Reading from file tmpfile_stdout 17:07:08 INFO - Output received: 17:07:08 INFO - /builds/slave/test/build/application/firefox/firefox 17:07:08 INFO - Running post-action listener: _resource_record_post_action 17:07:08 INFO - ##### 17:07:08 INFO - ##### Finished install step (success) 17:07:08 INFO - ##### 17:07:08 INFO - ##### 17:07:08 INFO - ##### Running run-tests step. 17:07:08 INFO - ##### 17:07:08 INFO - Running pre-action listener: _resource_record_pre_action 17:07:08 INFO - Running main action method: run_tests 17:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:07:08 INFO - Python 2.7.3 17:07:08 INFO - Return code: 0 17:07:08 INFO - grabbing minidump binary from tooltool 17:07: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')]} 17:07:08 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 0x10629c0>, '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 0x10638c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:07:08 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 17:07:08 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 17:07:08 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 17:07:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:07:08 INFO - Return code: 0 17:07:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:07:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:07:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:07:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:07:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:07:09 INFO - Return code: 0 17:07:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 17:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 17:07:09 INFO - Using env: {'DISPLAY': ':0', 17:07:09 INFO - 'HOME': '/home/cltbld', 17:07:09 INFO - 'LANG': 'en_US.UTF-8', 17:07:09 INFO - 'LANGUAGE': 'en_US:en', 17:07:09 INFO - 'LOGNAME': 'cltbld', 17:07:09 INFO - 'MAIL': '/var/mail/cltbld', 17:07:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:07:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:09 INFO - 'MOZ_NO_REMOTE': '1', 17:07:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:07:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:09 INFO - 'NO_EM_RESTART': '1', 17:07:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:09 INFO - 'PWD': '/builds/slave/test', 17:07:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:07:09 INFO - 'SHELL': '/bin/bash', 17:07:09 INFO - 'SHLVL': '1', 17:07:09 INFO - 'TERM': 'linux', 17:07:09 INFO - 'TMOUT': '86400', 17:07:09 INFO - 'USER': 'cltbld', 17:07:09 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326', 17:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:09 INFO - '_': '/tools/buildbot/bin/python'} 17:07:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 17:07:10 INFO - mozversion application_buildid: 20160602143006 17:07:10 INFO - mozversion application_changeset: f76a0fdadbf053c3373961f9afbc966c011a50fe 17:07:10 INFO - mozversion application_display_name: Nightly 17:07:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:07:10 INFO - mozversion application_name: Firefox 17:07:10 INFO - mozversion application_remotingname: firefox 17:07:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:07:10 INFO - mozversion application_vendor: Mozilla 17:07:10 INFO - mozversion application_version: 49.0a1 17:07:10 INFO - mozversion platform_buildid: 20160602143006 17:07:10 INFO - mozversion platform_changeset: f76a0fdadbf053c3373961f9afbc966c011a50fe 17:07:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:07:10 INFO - mozversion platform_version: 49.0a1 17:07:10 INFO - using testdate: 1464912430 17:07:10 INFO - actual date: 1464912430 17:07:10 INFO - starting webserver on 'localhost:49379' 17:07:10 INFO - SUITE-START | Running 2 tests 17:07:10 INFO - TEST-START | dromaeo_css 17:07:10 INFO - operating with platform_type : linux_ 17:07:10 INFO - Initialising browser for dromaeo_css test... 17:07:10 INFO - TEST-INFO | started process 31105 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf8mKE0/profile http://localhost:49379/getInfo.html) 17:07:14 INFO - PROCESS | 31105 | __metrics Screen width/height:1600/1200 17:07:14 INFO - PROCESS | 31105 | colorDepth:24 17:07:14 INFO - PROCESS | 31105 | Browser inner width/height: 1024/697 17:07:14 INFO - PROCESS | 31105 | __metrics 17:07:15 ERROR - PROCESS | 31105 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:07:16 INFO - TEST-INFO | 31105: exit 0 17:07:16 INFO - Browser initialized. 17:07:16 INFO - Running cycle 1/1 for dromaeo_css test... 17:07:16 INFO - TEST-INFO | started process 31187 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf8mKE0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:07:28 INFO - PROCESS | 31187 | RSS: Main: 155938816 17:07:28 INFO - PROCESS | 31187 | 17:08:19 INFO - PROCESS | 31187 | 1464912499499 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] 17:10:27 INFO - PROCESS | 31187 | Cycle 1(1): loaded http://localhost:49379/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49379/tests/dromaeo/cssquery-ext.html) 17:10:28 INFO - PROCESS | 31187 | RSS: Main: 242692096 17:10:28 INFO - PROCESS | 31187 | 17:13:30 INFO - PROCESS | 31187 | Cycle 1(1): loaded http://localhost:49379/tests/dromaeo/cssquery-ext.html (next: http://localhost:49379/tests/dromaeo/cssquery-jquery.html) 17:13:31 INFO - PROCESS | 31187 | RSS: Main: 239271936 17:13:31 INFO - PROCESS | 31187 | 17:16:32 INFO - PROCESS | 31187 | Cycle 1(1): loaded http://localhost:49379/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49379/tests/dromaeo/cssquery-mootools.html) 17:16:33 INFO - PROCESS | 31187 | RSS: Main: 235417600 17:16:33 INFO - PROCESS | 31187 | 17:19:35 INFO - PROCESS | 31187 | Cycle 1(1): loaded http://localhost:49379/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49379/tests/dromaeo/cssquery-prototype.html) 17:19:36 INFO - PROCESS | 31187 | RSS: Main: 240775168 17:19:36 INFO - PROCESS | 31187 | 17:22:38 INFO - PROCESS | 31187 | Cycle 1(1): loaded http://localhost:49379/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49379/tests/dromaeo/cssquery-yui.html) 17:22:39 INFO - PROCESS | 31187 | RSS: Main: 229253120 17:22:39 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | Cycle 1(1): loaded http://localhost:49379/tests/dromaeo/cssquery-yui.html (next: http://localhost:49379/tests/dromaeo/cssquery-dojo.html) 17:25:42 INFO - PROCESS | 31187 | RSS: Main: 271441920 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | __start_tp_report 17:25:42 INFO - PROCESS | 31187 | _x_x_mozilla_page_load 17:25:42 INFO - PROCESS | 31187 | _x_x_mozilla_page_load_details 17:25:42 INFO - PROCESS | 31187 | |i|pagename|runs| 17:25:42 INFO - PROCESS | 31187 | |0;dojo.html;3048.26841558026;3087.475129228031;3091.9845400773;3107.2697915989747;3111.7355024822887;5232.241325007872;5242.528172464477;5275.577953763699;5333.439988801173;5364.973175134129;5253.185756207784;5273.050850846846;5286.497782710646;5309.840704778853;5350.678959262437;4823.107725070856;4847.830325938593;5001.749912504379;5056.039352523027;5071.644984851062;5010.72441015744;5012.448630650644;5023.7236951967625;5030;5040.747962601874;4762.3332733417;4791.784369703371;4822.180229361017;4847.781849816767;4954.1330267203275;4833.130036593412;4840.782164802592;4848.5151484851585;4881.926771098437;4909.901801963976;4770.427548694179;4794.976025119887;4837.830675926326;4847.95152048482;4891.902161956777;5158.045761534128;5160.870978225536;5217.921731174036;5221.71280579568;5295.152775555893;5560.110382338807;5583.972080139573;5593.944060559383;5659.971700141514;5666.943330566724;5650.124230744241;5686.800961966312;5689.089745640677;5689.6017278790505;5691.174779656968;5177.275181474596;5334.466553344673;5409.648372855791;5412.648177868466;5480.725963701879;5983.760649574056;5995.310539287951;6009.729562169713;6011.639301641915;6011.789587364421;5749.338826034976;5757.050086735641;5767.971160144256;5772.365039845614;5772.682502462405;5606.6636001840025;5654.717264136859;5729.770809167587;5741.081426971665;5770.278715160605;5709.771609135588;5722.685252311163;5723.227364305849;5731.083026715789;5737.254156959652;5489.39616642169;5705.286839145107;5738.713064346766;5746.166805813217;5752.683602401824;4304.677149213821;4331.913361732748;4336.132773445323;4349.369341445536;4357.085012147484;3881.514810648669;3885.417187421909;3893.124047089439;3917.5690674025836;3933.6263055009726;5195.19474481456;5245.921311180336;5352.063388906926;5365.08793505105;5405.837824865261;5664.20701101846;5704.600677952503;5706.3722990471015;5724.79963201286;5742.397048309951;5330.653507521999;5338.866528336823;5339.359276886798;5341.679499230058;5344.278522399504;4827.61379089672;4842.249451335048;4948.331975183376;4949.430815456197;4981.352424184833;5823.475887170091;5897.882042359129;5899.646021238739;5909.409059094056;5957.1362152487645;5677.5457963362005;5683.090705487102;5684.7441865117;5689.317281926195;5690.573207009408;5775.162601422854;5794.884102317846;5838.737256823369;5914.733836977261;5935.436133567304;5593.272874526286;5617.831465056055;5622.072358061037;5624.493795558419;5673.205751194919;5675.7445914933105;5677;5710.914336285042;5722.513586345207;5727.599068065196;5472.863178420572;5504.91742623869;5549.667019978814;5566.109422492382;5577.247071645276;5560;5562.610617256915;5569.136783798518;5570.944290557205;5573.247611572386;5490.725463726878;5506.531944784738;5509.862253443696;5512.944870551243;5553.500184983371;5508.118701007819;5517.36550296721;5557.277553918127;5567.66594004361;5631.662100273997;5458.1266997280245;5466.3713672928125;5473.06957817164;5473.8357849264585;5512.751926163252 17:25:42 INFO - PROCESS | 31187 | |1;ext.html;589.9675517846516;632.44976870123;633.2654121219387;647.3946859686193;648.2383199740304;30966;41866;42408.78795606025;42438.99999999996;42505.57494425063;44419.333709994295;44884.775576122076;44961.77519112408;44999.77500112503;45093.323600146025;8693.347998900092;8860.512671803048;8865.734027979139;8866.379353445262;8868.556572171397;8790.47257164569;8923.821523569524;8935.865962010575;8943.508107054142;8946.552672366388;8937.285017198641;8994.68518601849;8995.95502022489;8997.730068097935;9002.144796244347;8865.822683546325;8917.687880924137;8920.197182253629;8931.240844528224;8933.419327743708;8781.7804554886;8884.689035883714;8889.244414224799;8892.644294228223;8902.643894244222;39236.607633923864;39244.80377598122;39577.80211098955;39578.60421395807;39605.60394396048;3479.2693534357813;3591.4612808078737;3651.1602331463646;3704.85180592776;4225.873223803291;29963.550546741815;33287.334253314795;33338;33917.6608233917;33965.490517642254;4053.088055187589;4074.2055299216663;4112.115895082542;4115.1975364803575;4227.661787057028;346793;352438;353838.99999999744;354277;355270;55797.44202557922;60310.39689603136;63945.12246948898;64680.35319646837;64721;63576.68211658912;69200;69224.30775692179;69233;69258;15356.232188390535;15380.077195368314;15479.613009674624;15494.5351639451;15513;10904.836427453594;11879.643610691694;11958;11963.76072478563;11966.22219555726;20080.49798755043;20561.69157462639;20696.58606827885;21386;21448.142074316853;7369.77890663281;7631.885521717179;7635.618219089023;7791.805204869924;7831.804204894809;1045.4929359260905;1058.5924419098549;1080.1520806167277;1111.3220935229504;1115.8047341715167;31828.363432731217;32869.67130328714;33068;33135.337293254;33164.50253246155;1130.3726431830248;1144.1075960750627;1170.0990237517235;1180.4889741131365;1183.3077649574923;16868;17116.486505404857;17417.65164696699;17506.474805755846;17602;565912;567579;568404;569081;569449;58637;59045.40954590399;59288.40711592915;59308.406915931155;59368;1332.593558964525;1349.6355983884296;1357.3145561491383;1379.7792353223435;1406.5358431717714;31837.36325273528;31900.5214921781;32917.50623740694;33778.66221337755;34367.312653747285;52086.73956630193;57614.7119264401;57709.13436298375;57899;57946;493678;498624;498813;499326;690539.2838214862;45147;45375;45611.54388456073;47667.76166119147;49000;50113.74943125261;50222.246666300765;50255.246171308194;51226;51303.48696512987;41785;42029.579704202566;42536.57463425326;42559.78720106503;43618.12763744771;41331.38002929898;41590.58409415988;41630.167396652505;41794.1641167181;42354;41219;41890.79054604707;41900;42210.78894605507;42432.36351454669;35701.28597427985;37120.44319335158;37790.62209377981;37887.211278872994;38158;28686.282842929093;28815.057038017192;29033.12900612985;29042.709572904;29049.7095029047 17:25:42 INFO - PROCESS | 31187 | |2;jquery.html;396.78573570272056;403.5802765124271;408.9591040895909;410.85620032988453;423.0734691026651;17916.19377128029;19446.222151113958;19476.41570752878;19646.607067858633;20412.285570005053;16123.435679751212;16662.666746665058;16667.666646667058;17830.286788528476;18924.432267031978;5185.766640501177;5348.438413966536;5354.678719276836;5852.034414321632;5861.5603829712945;5621.522170615503;5716.485516303532;5733.713314334266;5761.222234998263;5820.447057529528;5567.554595632339;5695.259616249882;5713.8285851424525;5861.970690146543;5873;5929.940700593003;5932.703364831741;5942.2275104236605;5952.404759524013;5961.701914904259;5573.804916827891;5818.41815818419;5822.592418530705;5824.068149096167;5827.388124246935;13669.248191349472;13911.095778774346;17281.654366912717;22658.886705566365;22682.319530414115;2381.9285421437385;2635.8418494890366;2744.3962328287744;2840.602315675807;2869.239651492356;10570.704531084344;10853.559850466896;11224.831627525593;12028.819567706492;14585.562433127023;1910.923563057476;1914;1985.067018501302;1989.631918095157;2037.663785475395;77753;78982;79192.60403697945;79223;79291;10152.39085654863;10310.43292618913;10618.893811061791;10820.513076911559;10982.560697572168;15519.5344139676;15641.765373519407;15646.765298520531;15655.530334089995;15657;7131.144262688511;7651.1583725790115;7735.535867847947;7791.298783109547;7825.1783562725095;7219.314165154302;7312.305330993547;7377.372923301579;7427.591482468516;7445.218252083561;4362.912741745147;4417.977910110429;4446.444194475691;4572.88567785808;4846.75766211688;1912.2159914435015;1916.9904150479438;1949.1618604000416;1956.4815323939167;1965.36125759126;1016.6441745389054;1026.1728936779125;1049.4857519815237;1058.8888166742536;1065.9360438373722;18658.813411865707;18678.34625788091;18688;19461.221551137794;19626.31307904245;984.9015098490094;999.3104757717152;1005.8692369992001;1017.3743147963949;1032.2670903658486;8564.400491965627;10581.947090264499;10803.675889723321;10943.726406839893;10985.725356866142;450041;462353;463075;463294;475444;63168;76775.61612191904;78249;84360.74312924844;86288;1029.505837198149;1035.7773078788098;1052.573707648419;1062.102523367753;1066.9970227985668;13540;17310.13449327554;17505.21226544783;17524.386646467312;17761.378351758143;38763.22473550457;38779.22441551209;40427.59572404238;40647.1870562593;41728.3740743883;52894.47105528895;54211.45788542222;54260;54324;54922;5165.509276618715;5437.646552974203;8068.709006558923;8504.787380315542;8863.911360886308;12307.507699691912;12797.872021279669;12810.615681529569;13035.608931732064;13141.802872957105;11278.887211127783;11592.5362985483;11791.823122653339;11973.76052478963;12081.516739330329;11416;11623.360715160747;11747.882521174679;12120.090993175723;12135.453904574491;11709.121815864015;11812.881871181178;11861;11861.525538978343;12026;8721.433106848035;8735.868961965703;8801.207891289558;8857.689980850639;9005.234555062892;8539.103394143525;10314.535845887103;10832;10982.450877455936;11261.066024999023 17:25:42 INFO - PROCESS | 31187 | |3;mootools.html;1360.9251491167981;1468.119128522886;1480.1563109027854;1638.2054703468816;1790.1765188013505;8079.111297757249;8404.41169118162;8410.95794521028;8665.480071195718;8691.174338437839;8442.451240669352;8633.913660863389;8652.351073669468;8655.43739656922;8658.95670521648;1971.3494546799593;2163.5023944492777;2250;2302.9078836846506;2332.1021406758437;2293.8050265727434;2298.8275879309035;2300.64340027296;2302.5164715409783;2306.5041016181517;2252.583272094668;2258.8644681319092;2268.727752669682;2270.137347807831;2280.042382199472;1907.3133671878113;2172.8587641803233;2206.293985924505;2225.7440394354694;2238.8656680599215;2191.605511008018;2217.090992692988;2234.1733558583264;2234.832387570939;2238.675392068157;8536.829263414696;8557.529335886524;8570.785730356729;8579.184977427134;8600.5699715014;4964.453910069889;5112.309838171836;5112.667676601046;5114.872128196787;5117.769700363493;2899.695531969155;2941.0735618280387;2947.5873377727135;2948.20398492408;2971.06411480383;998.3560603410832;1009.2430676992255;1016.0702956794524;1031.0823367203195;1032.3135115148418;11753.647390578297;11884.40577971098;11895.821562676567;11920.642380728592;11950.28298302096;4260.2970509866145;4300.505441874162;4363.869083927487;4368.978155109204;4397.670174736908;9291;9301.674441394518;9320.90679093214;9322.580483878242;9323.347365684473;5069.239614057921;5070.087384270793;5071.644984851099;5072.847814565569;5084.13569693153;4190.224808410393;4531.27499600069;4611.515790841913;4637.304404339308;4685.109829132454;3724.348239058154;3789.204267103939;3816.065064059323;3820.4269359596283;3895.5714871364125;1137.0676045642495;1151.3494875395374;1159.2001518951897;1170.4440390814295;1172.1501911114374;837.8031162676766;952.552300418802;953.2659851914126;975.1175186456269;978.3298440568234;5901.232839730807;5924.437178468039;6024.457798798042;6113.38866113394;6136.8772624547255;426.73115936960136;428.08603631247513;434.27692891336204;435.4382846128522;450.84220522817407;4208.494980602347;4211.55778643238;4217.704760666724;4227.767472789026;4262.914741705148;10338.0695737384;10562.102221311121;10589;10608.734781630366;10682.1988350874;10415.583376664848;10429.113525350278;10504.054635082879;10522.842157367646;10538.789224215472;7723.729669461542;7975.322097621767;7978.242067003625;7993.480423772437;8165.265126138557;5962.493188079062;6101.298350689727;6152.723127459186;6174.135621013152;6248.250209974832;9692.273079518924;9933.15568176713;10144.898551014396;10184.287099903231;10197.592096316064;10105.090541850974;10201.081902628799;10224.386536807815;10230.437325947145;10242.231832612435;6964.860702786017;7475.962620186864;7583.734569290048;7642.4650274480255;7721.6139193039;8485.830283394174;8537.18896704812;8616.138386161334;8619.569021549023;8621.180987806149;7495.138059123271;7654.119776225805;7659.617019149132;7712.113106992768;7788.376929845485;7457.925420745723;7472.439567032603;7492.363149132385;7603.353714934293;7648.770536883902;6875.759348422781;6927.965360173369;6962.477814164059;6967.930320696728;6973.511854170362;5037.0681423936685;5065.924011139762;5144.6655967362;5171.9482805171465;5176.482351764794;5120.4111527174855;5238.842834714965;5254;5325.360956685223;5400.05499037659 17:25:42 INFO - PROCESS | 31187 | |4;prototype.html;303.91946134274406;315.37713016791855;328.79450343535274;329.2115383656144;336.67342677602716;5185.507376799198;5334.759401051558;5372.516473517383;5380.650257733254;5393;5313.920291195635;5324.509137441513;5330.466953304667;5337.386200586933;5380.704061276629;4181.560936101711;4182.644475219602;4188.434561334211;4188.560201178878;4192.790360481979;4022.5575186729584;4170.4578404807335;4198.578158800583;4201.579842015789;4207.495100587934;4084.9387259191135;4097.6887396033135;4123.525794533637;4124.6700263978955;4132.5454200037975;4088.3867419886956;4126.711130220886;4129.814158362866;4141.503019637633;4142.233686767958;3673.559172899269;3697.2605478904325;3707.6848467880263;3715.6655900969045;3725.795081270529;5077.39071311445;5116.132611596778;5146.794128234867;5156.226566015091;5157.5616072633875;4637.165310244155;4654.511276316006;4677.7894994724975;4697.718136911796;4703.50613185614;5487.972560137214;5493.725313734337;5508.228847961289;5536.806211782608;5583.3300003999375;3743.625637436262;3974.841006359742;3994.8827121625586;4014.116894283253;4048.9797551012352;5553.278073850414;5737.2254745609225;5755.676194475294;5851.332370274494;5902.291724993029;3880.3015457217407;3933.7639741615594;3939.8227079781477;3943.743656662365;4010.5588385277597;3840.6927445804267;3995.1210733638554;4032;4040.777757223381;4066.2274167908595;4521.982553925341;4683.836065737751;4688;4692.788824502905;4694.7887345068875;5525.475079867406;5527.55779537636;5540.833774986676;5541.806036788693;5553.6390134641115;1339.7521458530202;1391.2348208485496;1424.8076509671062;1426.7717165253716;1438.6906815034818;888.9511076890833;899.5592159841631;914.3645166609276;914.5427286356821;917.7109210598641;4773.331733557305;4782.569568738831;4801.5355316628065;4805.231163013901;4807.927881081787;5045.974770126126;5068.949310506921;5103.591712662978;5124.821291102992;5180.274761533388;4561.429821272341;4703.764811759398;4748.575427371844;4767.928481072787;4814.374131362901;2705.580635001578;2708.972910270872;2717.2255907066788;2719.986400068027;2724.6185534025253;5174.53429191367;5212.687238765686;5214.32213812202;5233.607479439057;5250.842474725764;4794.328793968847;5025.211007890583;5126.974365128151;5182.611304152204;5192.3769147702515;5194.714290714047;5198.558122559549;5242.5281724644965;5246.396664383568;5273.947260527346;5026.371703537058;5066.923996139987;5074.949250507448;5075.5383638934345;5090.643654944119;4940.888300132441;5144.871378215575;5423.918641220306;5518.530924871431;5601.915971260516;5883.794067207627;5892.6759028252145;5897.174395584536;5976.760929562769;5988.401159883977;4022.8994275143355;4046.5144182698264;4061;4069.491313585802;4071.69948978826;5592.972035139798;5720.542356611544;5747.281589801275;5750.769969201185;5791.652500849962;5554.305711786027;5578.748956297058;5581.469760372758;5590.7763689453595;5618.49433550966;5395.865103372447;5524.226608274925;5541.418151094077;5607.607467477237;5623.943760562505;5473.72631368422;5496.351094671662;5496.780128794963;5502.80740174092;5505.421930697219;5574.9721251393485;5592.259792701629;5604.85987850307;5653.717314134195;5674.233978412862;5260.158374660036;5487.368952570346;5506.476884695948;5511.944880551304;5523.6409633373705 17:25:42 INFO - PROCESS | 31187 | |5;yui.html;688.7313947560449;731.9048523691919;750.7560042986031;757.1330826203997;796.4186144114798;14377.568672939802;15573.53279401617;15628.765568516481;16029.919850400745;16323.673526529463;15337.463188788395;15408.3836646534;15446.69106617867;15491.457798977039;15551.066935983848;674.7840690978888;794.7774623105539;801.4229754576695;813.9186081391844;816.4162623724036;809.8623234050197;809.8663720486143;811.4117264982899;812.0296245986049;812.1107387410776;833.0212000898949;838.174398217755;839.49630221867;840.2018082821313;841.2680967558233;702.4555969123919;815.9306458950996;832.883396324515;833.4582521361134;845.3448577352601;618.3754408047859;622.3092367472102;622.498888394842;623.1276213301414;623.3953065526432;15660.373585056584;15672.764908526382;15702.528924132293;15704.528864134094;15706.764398534031;12410.255384676948;12668.303243321614;12907.225566466042;13149.474021039146;13312.068155229137;11638;11806.704832379173;12227.877721222852;12307.630771076882;12332.75334493314;4004.2592120457516;4015.959840401588;4079.224947260011;4089.427480152781;4170.145120250371;73015;73485;74359.62820185919;74398;74981;10216.233782466345;10359.430231337197;10416.218783591261;10477.47612619366;10514.26400151982;19623.803761962197;19769.60460790776;20619.278325258543;20688.379348619565;20705.792942070686;7569.167391586921;7627.389808815282;7824.843503129906;7885.723999659985;8116.715914942949;4266.445362102907;4307.5261721210645;4351.173277077409;4384.517703052662;4433.689641725113;3341.565596472443;3457.429524128541;3459.446488561818;3460.7058400035776;3460.8442620082155;560.436761055137;577.3850848845966;578.780063575847;579.2556564814194;580.5906835680835;627.3820287017285;638.355261186196;645.7384759172543;657.0932113683086;666.6699983508098;8925.196732293995;8991.28069754431;8991.82016359669;8997.775055623531;8997.865032024525;596.627108057464;611.9235095613049;621.6674079367517;628.6573817269599;632.2160520954076;3672.3573374659863;3733.1227161617;3881.6700580450415;4353.25994580922;4463.330500424897;92279;94992;95634.52182739181;106119.46940265404;107958.46020770003;91020.54489727509;91139.54430227939;92015.53992229995;92206;93023;54676.17985730217;74207.62896185485;85278.14721852826;86037.56981215179;86453.56773216094;9302.348835581444;9306.627734890528;9607.231421486125;9778.364406313567;9781.119699227103;48214.75892620515;48266.75866620644;48748;48898.511014889395;50130.24804628007;46932.7653361731;72532.27467725256;72597.6370118146;73168.26831731615;74958;12448.315342655878;12529.93735031319;12898;12954.02844786626;13040.608781736562;14274.785878212042;14491.637709057359;14534.854651453774;14740;15056.548303550911;10294.279400441897;12100.636980890587;12538.937305313415;13562.186268823902;13871.514496992557;13380.330983450594;13505.729885402041;13880.652983675085;13955.162690238618;14150.929245353707;13725.725485490035;13836.930815345859;13881.652958676113;14362.13827170373;14660.193689347188;11160.88839111631;11305.943470282926;11505.654830355103;11567.652970410902;12026.639200823989;11039.503222355173;11127.499262533043;11222.563016394832;11303.717407064889;11379.943100284445 17:25:42 INFO - PROCESS | 31187 | __end_tp_report 17:25:42 INFO - PROCESS | 31187 | __start_cc_report 17:25:42 INFO - PROCESS | 31187 | _x_x_mozilla_cycle_collect,951 17:25:42 INFO - PROCESS | 31187 | __end_cc_report 17:25:42 INFO - PROCESS | 31187 | __startTimestamp1464913542324__endTimestamp 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | ------- Summary: start ------- 17:25:42 INFO - PROCESS | 31187 | Number of tests: 6 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | [#0] dojo.html Cycles:165 Average:5298.53 Median:5504.92 stddev:599.50 (10.9%) stddev-sans-first:574.76 17:25:42 INFO - PROCESS | 31187 | Values: 3048.3 3087.5 3092.0 3107.3 3111.7 5232.2 5242.5 5275.6 5333.4 5365.0 5253.2 5273.1 5286.5 5309.8 5350.7 4823.1 4847.8 5001.7 5056.0 5071.6 5010.7 5012.4 5023.7 5030.0 5040.7 4762.3 4791.8 4822.2 4847.8 4954.1 4833.1 4840.8 4848.5 4881.9 4909.9 4770.4 4795.0 4837.8 4848.0 4891.9 5158.0 5160.9 5217.9 5221.7 5295.2 5560.1 5584.0 5593.9 5660.0 5666.9 5650.1 5686.8 5689.1 5689.6 5691.2 5177.3 5334.5 5409.6 5412.6 5480.7 5983.8 5995.3 6009.7 6011.6 6011.8 5749.3 5757.1 5768.0 5772.4 5772.7 5606.7 5654.7 5729.8 5741.1 5770.3 5709.8 5722.7 5723.2 5731.1 5737.3 5489.4 5705.3 5738.7 5746.2 5752.7 4304.7 4331.9 4336.1 4349.4 4357.1 3881.5 3885.4 3893.1 3917.6 3933.6 5195.2 5245.9 5352.1 5365.1 5405.8 5664.2 5704.6 5706.4 5724.8 5742.4 5330.7 5338.9 5339.4 5341.7 5344.3 4827.6 4842.2 4948.3 4949.4 4981.4 5823.5 5897.9 5899.6 5909.4 5957.1 5677.5 5683.1 5684.7 5689.3 5690.6 5775.2 5794.9 5838.7 5914.7 5935.4 5593.3 5617.8 5622.1 5624.5 5673.2 5675.7 5677.0 5710.9 5722.5 5727.6 5472.9 5504.9 5549.7 5566.1 5577.2 5560.0 5562.6 5569.1 5570.9 5573.2 5490.7 5506.5 5509.9 5512.9 5553.5 5508.1 5517.4 5557.3 5567.7 5631.7 5458.1 5466.4 5473.1 5473.8 5512.8 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | [#1] ext.html Cycles:180 Average:65132.78 Median:4226.77 stddev:132156.42 (3126.7%) stddev-sans-first:132438.31 17:25:42 INFO - PROCESS | 31187 | Values: 590.0 632.4 633.3 647.4 648.2 30966.0 41866.0 42408.8 42439.0 42505.6 44419.3 44884.8 44961.8 44999.8 45093.3 8693.3 8860.5 8865.7 8866.4 8868.6 8790.5 8923.8 8935.9 8943.5 8946.6 8937.3 8994.7 8996.0 8997.7 9002.1 8865.8 8917.7 8920.2 8931.2 8933.4 8781.8 8884.7 8889.2 8892.6 8902.6 39236.6 39244.8 39577.8 39578.6 39605.6 3479.3 3591.5 3651.2 3704.9 4225.9 29963.6 33287.3 33338.0 33917.7 33965.5 4053.1 4074.2 4112.1 4115.2 4227.7 346793.0 352438.0 353839.0 354277.0 355270.0 55797.4 60310.4 63945.1 64680.4 64721.0 63576.7 69200.0 69224.3 69233.0 69258.0 15356.2 15380.1 15479.6 15494.5 15513.0 10904.8 11879.6 11958.0 11963.8 11966.2 20080.5 20561.7 20696.6 21386.0 21448.1 7369.8 7631.9 7635.6 7791.8 7831.8 1045.5 1058.6 1080.2 1111.3 1115.8 31828.4 32869.7 33068.0 33135.3 33164.5 1130.4 1144.1 1170.1 1180.5 1183.3 16868.0 17116.5 17417.7 17506.5 17602.0 565912.0 567579.0 568404.0 569081.0 569449.0 58637.0 59045.4 59288.4 59308.4 59368.0 1332.6 1349.6 1357.3 1379.8 1406.5 31837.4 31900.5 32917.5 33778.7 34367.3 52086.7 57614.7 57709.1 57899.0 57946.0 493678.0 498624.0 498813.0 499326.0 690539.3 45147.0 45375.0 45611.5 47667.8 49000.0 50113.7 50222.2 50255.2 51226.0 51303.5 41785.0 42029.6 42536.6 42559.8 43618.1 41331.4 41590.6 41630.2 41794.2 42354.0 41219.0 41890.8 41900.0 42210.8 42432.4 35701.3 37120.4 37790.6 37887.2 38158.0 28686.3 28815.1 29033.1 29042.7 29049.7 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | [#2] jquery.html Cycles:180 Average:27375.27 Median:12532.12 stddev:76148.52 (607.6%) stddev-sans-first:76335.20 17:25:42 INFO - PROCESS | 31187 | Values: 396.8 403.6 409.0 410.9 423.1 17916.2 19446.2 19476.4 19646.6 20412.3 16123.4 16662.7 16667.7 17830.3 18924.4 5185.8 5348.4 5354.7 5852.0 5861.6 5621.5 5716.5 5733.7 5761.2 5820.4 5567.6 5695.3 5713.8 5862.0 5873.0 5929.9 5932.7 5942.2 5952.4 5961.7 5573.8 5818.4 5822.6 5824.1 5827.4 13669.2 13911.1 17281.7 22658.9 22682.3 2381.9 2635.8 2744.4 2840.6 2869.2 10570.7 10853.6 11224.8 12028.8 14585.6 1910.9 1914.0 1985.1 1989.6 2037.7 77753.0 78982.0 79192.6 79223.0 79291.0 10152.4 10310.4 10618.9 10820.5 10982.6 15519.5 15641.8 15646.8 15655.5 15657.0 7131.1 7651.2 7735.5 7791.3 7825.2 7219.3 7312.3 7377.4 7427.6 7445.2 4362.9 4418.0 4446.4 4572.9 4846.8 1912.2 1917.0 1949.2 1956.5 1965.4 1016.6 1026.2 1049.5 1058.9 1065.9 18658.8 18678.3 18688.0 19461.2 19626.3 984.9 999.3 1005.9 1017.4 1032.3 8564.4 10581.9 10803.7 10943.7 10985.7 450041.0 462353.0 463075.0 463294.0 475444.0 63168.0 76775.6 78249.0 84360.7 86288.0 1029.5 1035.8 1052.6 1062.1 1067.0 13540.0 17310.1 17505.2 17524.4 17761.4 38763.2 38779.2 40427.6 40647.2 41728.4 52894.5 54211.5 54260.0 54324.0 54922.0 5165.5 5437.6 8068.7 8504.8 8863.9 12307.5 12797.9 12810.6 13035.6 13141.8 11278.9 11592.5 11791.8 11973.8 12081.5 11416.0 11623.4 11747.9 12120.1 12135.5 11709.1 11812.9 11861.0 11861.5 12026.0 8721.4 8735.9 8801.2 8857.7 9005.2 8539.1 10314.5 10832.0 10982.5 11261.1 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | [#3] mootools.html Cycles:180 Average:5639.70 Median:4661.21 stddev:3237.01 (69.4%) stddev-sans-first:3230.12 17:25:42 INFO - PROCESS | 31187 | Values: 1360.9 1468.1 1480.2 1638.2 1790.2 8079.1 8404.4 8411.0 8665.5 8691.2 8442.5 8633.9 8652.4 8655.4 8659.0 1971.3 2163.5 2250.0 2302.9 2332.1 2293.8 2298.8 2300.6 2302.5 2306.5 2252.6 2258.9 2268.7 2270.1 2280.0 1907.3 2172.9 2206.3 2225.7 2238.9 2191.6 2217.1 2234.2 2234.8 2238.7 8536.8 8557.5 8570.8 8579.2 8600.6 4964.5 5112.3 5112.7 5114.9 5117.8 2899.7 2941.1 2947.6 2948.2 2971.1 998.4 1009.2 1016.1 1031.1 1032.3 11753.6 11884.4 11895.8 11920.6 11950.3 4260.3 4300.5 4363.9 4369.0 4397.7 9291.0 9301.7 9320.9 9322.6 9323.3 5069.2 5070.1 5071.6 5072.8 5084.1 4190.2 4531.3 4611.5 4637.3 4685.1 3724.3 3789.2 3816.1 3820.4 3895.6 1137.1 1151.3 1159.2 1170.4 1172.2 837.8 952.6 953.3 975.1 978.3 5901.2 5924.4 6024.5 6113.4 6136.9 426.7 428.1 434.3 435.4 450.8 4208.5 4211.6 4217.7 4227.8 4262.9 10338.1 10562.1 10589.0 10608.7 10682.2 10415.6 10429.1 10504.1 10522.8 10538.8 7723.7 7975.3 7978.2 7993.5 8165.3 5962.5 6101.3 6152.7 6174.1 6248.3 9692.3 9933.2 10144.9 10184.3 10197.6 10105.1 10201.1 10224.4 10230.4 10242.2 6964.9 7476.0 7583.7 7642.5 7721.6 8485.8 8537.2 8616.1 8619.6 8621.2 7495.1 7654.1 7659.6 7712.1 7788.4 7457.9 7472.4 7492.4 7603.4 7648.8 6875.8 6928.0 6962.5 6967.9 6973.5 5037.1 5065.9 5144.7 5171.9 5176.5 5120.4 5238.8 5254.0 5325.4 5400.1 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | [#4] prototype.html Cycles:180 Average:4539.03 Median:5097.12 stddev:1324.69 (26.0%) stddev-sans-first:1289.71 17:25:42 INFO - PROCESS | 31187 | Values: 303.9 315.4 328.8 329.2 336.7 5185.5 5334.8 5372.5 5380.7 5393.0 5313.9 5324.5 5330.5 5337.4 5380.7 4181.6 4182.6 4188.4 4188.6 4192.8 4022.6 4170.5 4198.6 4201.6 4207.5 4084.9 4097.7 4123.5 4124.7 4132.5 4088.4 4126.7 4129.8 4141.5 4142.2 3673.6 3697.3 3707.7 3715.7 3725.8 5077.4 5116.1 5146.8 5156.2 5157.6 4637.2 4654.5 4677.8 4697.7 4703.5 5488.0 5493.7 5508.2 5536.8 5583.3 3743.6 3974.8 3994.9 4014.1 4049.0 5553.3 5737.2 5755.7 5851.3 5902.3 3880.3 3933.8 3939.8 3943.7 4010.6 3840.7 3995.1 4032.0 4040.8 4066.2 4522.0 4683.8 4688.0 4692.8 4694.8 5525.5 5527.6 5540.8 5541.8 5553.6 1339.8 1391.2 1424.8 1426.8 1438.7 889.0 899.6 914.4 914.5 917.7 4773.3 4782.6 4801.5 4805.2 4807.9 5046.0 5068.9 5103.6 5124.8 5180.3 4561.4 4703.8 4748.6 4767.9 4814.4 2705.6 2709.0 2717.2 2720.0 2724.6 5174.5 5212.7 5214.3 5233.6 5250.8 4794.3 5025.2 5127.0 5182.6 5192.4 5194.7 5198.6 5242.5 5246.4 5273.9 5026.4 5066.9 5074.9 5075.5 5090.6 4940.9 5144.9 5423.9 5518.5 5601.9 5883.8 5892.7 5897.2 5976.8 5988.4 4022.9 4046.5 4061.0 4069.5 4071.7 5593.0 5720.5 5747.3 5750.8 5791.7 5554.3 5578.7 5581.5 5590.8 5618.5 5395.9 5524.2 5541.4 5607.6 5623.9 5473.7 5496.4 5496.8 5502.8 5505.4 5575.0 5592.3 5604.9 5653.7 5674.2 5260.2 5487.4 5506.5 5511.9 5523.6 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - PROCESS | 31187 | [#5] yui.html Cycles:180 Average:19472.86 Median:4448.51 stddev:27204.45 (611.5%) stddev-sans-first:27244.20 17:25:42 INFO - PROCESS | 31187 | Values: 688.7 731.9 750.8 757.1 796.4 14377.6 15573.5 15628.8 16029.9 16323.7 15337.5 15408.4 15446.7 15491.5 15551.1 674.8 794.8 801.4 813.9 816.4 809.9 809.9 811.4 812.0 812.1 833.0 838.2 839.5 840.2 841.3 702.5 815.9 832.9 833.5 845.3 618.4 622.3 622.5 623.1 623.4 15660.4 15672.8 15702.5 15704.5 15706.8 12410.3 12668.3 12907.2 13149.5 13312.1 11638.0 11806.7 12227.9 12307.6 12332.8 4004.3 4016.0 4079.2 4089.4 4170.1 73015.0 73485.0 74359.6 74398.0 74981.0 10216.2 10359.4 10416.2 10477.5 10514.3 19623.8 19769.6 20619.3 20688.4 20705.8 7569.2 7627.4 7824.8 7885.7 8116.7 4266.4 4307.5 4351.2 4384.5 4433.7 3341.6 3457.4 3459.4 3460.7 3460.8 560.4 577.4 578.8 579.3 580.6 627.4 638.4 645.7 657.1 666.7 8925.2 8991.3 8991.8 8997.8 8997.9 596.6 611.9 621.7 628.7 632.2 3672.4 3733.1 3881.7 4353.3 4463.3 92279.0 94992.0 95634.5 106119.5 107958.5 91020.5 91139.5 92015.5 92206.0 93023.0 54676.2 74207.6 85278.1 86037.6 86453.6 9302.3 9306.6 9607.2 9778.4 9781.1 48214.8 48266.8 48748.0 48898.5 50130.2 46932.8 72532.3 72597.6 73168.3 74958.0 12448.3 12529.9 12898.0 12954.0 13040.6 14274.8 14491.6 14534.9 14740.0 15056.5 10294.3 12100.6 12538.9 13562.2 13871.5 13380.3 13505.7 13880.7 13955.2 14150.9 13725.7 13836.9 13881.7 14362.1 14660.2 11160.9 11305.9 11505.7 11567.7 12026.6 11039.5 11127.5 11222.6 11303.7 11379.9 17:25:42 INFO - PROCESS | 31187 | -------- Summary: end -------- 17:25:42 INFO - PROCESS | 31187 | 17:25:42 INFO - TEST-INFO | 31187: exit 0 17:25:42 INFO - TEST-OK | dromaeo_css | took 1112923ms 17:25:42 INFO - TEST-START | kraken 17:25:42 INFO - operating with platform_type : linux_ 17:25:42 INFO - Initialising browser for kraken test... 17:25:43 INFO - TEST-INFO | started process 31367 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeIJ5M6/profile http://localhost:49379/getInfo.html) 17:25:47 INFO - PROCESS | 31367 | __metrics Screen width/height:1600/1200 17:25:47 INFO - PROCESS | 31367 | colorDepth:24 17:25:47 INFO - PROCESS | 31367 | Browser inner width/height: 1024/697 17:25:47 INFO - PROCESS | 31367 | __metrics 17:25:48 INFO - PROCESS | 31367 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:25:48 ERROR - PROCESS | 31367 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:25:49 INFO - TEST-INFO | 31367: exit 0 17:25:49 INFO - Browser initialized. 17:25:49 INFO - Running cycle 1/1 for kraken test... 17:25:49 INFO - TEST-INFO | started process 31448 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeIJ5M6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:01 INFO - PROCESS | 31448 | RSS: Main: 155725824 17:26:01 INFO - PROCESS | 31448 | 17:26:03 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49379/tests/kraken/driver.html?testName=audio-beat-detection) 17:26:04 INFO - PROCESS | 31448 | RSS: Main: 214364160 17:26:04 INFO - PROCESS | 31448 | 17:26:07 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49379/tests/kraken/driver.html?testName=audio-dft) 17:26:07 INFO - PROCESS | 31448 | RSS: Main: 201568256 17:26:07 INFO - PROCESS | 31448 | 17:26:11 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49379/tests/kraken/driver.html?testName=audio-fft) 17:26:11 INFO - PROCESS | 31448 | RSS: Main: 267669504 17:26:11 INFO - PROCESS | 31448 | 17:26:13 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49379/tests/kraken/driver.html?testName=audio-oscillator) 17:26:14 INFO - PROCESS | 31448 | RSS: Main: 178667520 17:26:14 INFO - PROCESS | 31448 | 17:26:16 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49379/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:26:17 INFO - PROCESS | 31448 | RSS: Main: 183463936 17:26:17 INFO - PROCESS | 31448 | 17:26:20 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49379/tests/kraken/driver.html?testName=imaging-darkroom) 17:26:20 INFO - PROCESS | 31448 | RSS: Main: 217595904 17:26:20 INFO - PROCESS | 31448 | 17:26:24 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49379/tests/kraken/driver.html?testName=imaging-desaturate) 17:26:24 INFO - PROCESS | 31448 | RSS: Main: 220037120 17:26:24 INFO - PROCESS | 31448 | 17:26:27 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49379/tests/kraken/driver.html?testName=json-parse-financial) 17:26:28 INFO - PROCESS | 31448 | RSS: Main: 220364800 17:26:28 INFO - PROCESS | 31448 | 17:26:29 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49379/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:26:30 INFO - PROCESS | 31448 | RSS: Main: 205983744 17:26:30 INFO - PROCESS | 31448 | 17:26:32 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:26:32 INFO - PROCESS | 31448 | RSS: Main: 209448960 17:26:32 INFO - PROCESS | 31448 | 17:26:34 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:26:35 INFO - PROCESS | 31448 | RSS: Main: 215912448 17:26:35 INFO - PROCESS | 31448 | 17:26:37 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:26:38 INFO - PROCESS | 31448 | RSS: Main: 221847552 17:26:38 INFO - PROCESS | 31448 | 17:26:41 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:26:41 INFO - PROCESS | 31448 | RSS: Main: 218435584 17:26:41 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | Cycle 1(1): loaded http://localhost:49379/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49379/tests/kraken/driver.html?testName=ai-astar) 17:26:43 INFO - PROCESS | 31448 | RSS: Main: 261136384 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | __start_tp_report 17:26:43 INFO - PROCESS | 31448 | _x_x_mozilla_page_load 17:26:43 INFO - PROCESS | 31448 | _x_x_mozilla_page_load_details 17:26:43 INFO - PROCESS | 31448 | |i|pagename|runs| 17:26:43 INFO - PROCESS | 31448 | |0;ai-astar;111;105;110;110;113;109;109;110;109;110 17:26:43 INFO - PROCESS | 31448 | |1;audio-beat-detection;152;126;131;184;127;135;151;125;162;127 17:26:43 INFO - PROCESS | 31448 | |2;audio-dft;163;161;161;161;162;161;161;162;162;160 17:26:43 INFO - PROCESS | 31448 | |3;audio-fft;75;74;75;131;75;75;75;74;97;75 17:26:43 INFO - PROCESS | 31448 | |4;audio-oscillator;84;85;115;84;83;83;119;83;83;131 17:26:43 INFO - PROCESS | 31448 | |5;imaging-gaussian-blur;116;117;117;116;117;116;117;116;117;119 17:26:43 INFO - PROCESS | 31448 | |6;imaging-darkroom;147;147;146;147;147;147;147;146;146;146 17:26:43 INFO - PROCESS | 31448 | |7;imaging-desaturate;94;95;95;95;95;94;95;95;94;95 17:26:43 INFO - PROCESS | 31448 | |8;json-parse-financial;52;52;52;54;56;54;56;52;53;52 17:26:43 INFO - PROCESS | 31448 | |9;json-stringify-tinderbox;63;62;62;62;62;62;62;62;62;64 17:26:43 INFO - PROCESS | 31448 | |10;stanford-crypto-aes;85;80;79;85;85;84;87;87;114;81 17:26:43 INFO - PROCESS | 31448 | |11;stanford-crypto-ccm;123;125;123;121;120;119;127;119;129;132 17:26:43 INFO - PROCESS | 31448 | |12;stanford-crypto-pbkdf2;158;160;161;159;159;159;163;160;161;159 17:26:43 INFO - PROCESS | 31448 | |13;stanford-crypto-sha256-iterative;63;65;63;62;63;71;67;62;62;60 17:26:43 INFO - PROCESS | 31448 | __end_tp_report 17:26:43 INFO - PROCESS | 31448 | __start_cc_report 17:26:43 INFO - PROCESS | 31448 | _x_x_mozilla_cycle_collect,1593 17:26:43 INFO - PROCESS | 31448 | __end_cc_report 17:26:43 INFO - PROCESS | 31448 | __startTimestamp1464913603743__endTimestamp 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | ------- Summary: start ------- 17:26:43 INFO - PROCESS | 31448 | Number of tests: 14 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:2.01 (1.8%) stddev-sans-first:2.07 17:26:43 INFO - PROCESS | 31448 | Values: 111.0 105.0 110.0 110.0 113.0 109.0 109.0 110.0 109.0 110.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:143.00 stddev:19.75 (13.8%) stddev-sans-first:20.61 17:26:43 INFO - PROCESS | 31448 | Values: 152.0 126.0 131.0 184.0 127.0 135.0 151.0 125.0 162.0 127.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.67 17:26:43 INFO - PROCESS | 31448 | Values: 163.0 161.0 161.0 161.0 162.0 161.0 161.0 162.0 162.0 160.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:18.39 (24.5%) stddev-sans-first:19.30 17:26:43 INFO - PROCESS | 31448 | Values: 75.0 74.0 75.0 131.0 75.0 75.0 75.0 74.0 97.0 75.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:83.00 stddev:18.83 (22.7%) stddev-sans-first:19.54 17:26:43 INFO - PROCESS | 31448 | Values: 84.0 85.0 115.0 84.0 83.0 83.0 119.0 83.0 83.0 131.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 17:26:43 INFO - PROCESS | 31448 | Values: 116.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 119.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:26:43 INFO - PROCESS | 31448 | Values: 147.0 147.0 146.0 147.0 147.0 147.0 147.0 146.0 146.0 146.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 17:26:43 INFO - PROCESS | 31448 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:1.64 (3.1%) stddev-sans-first:1.67 17:26:43 INFO - PROCESS | 31448 | Values: 52.0 52.0 52.0 54.0 56.0 54.0 56.0 52.0 53.0 52.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 17:26:43 INFO - PROCESS | 31448 | Values: 63.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 64.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:9.99 (11.8%) stddev-sans-first:10.58 17:26:43 INFO - PROCESS | 31448 | Values: 85.0 80.0 79.0 85.0 85.0 84.0 87.0 87.0 114.0 81.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:124.00 stddev:4.42 (3.6%) stddev-sans-first:4.68 17:26:43 INFO - PROCESS | 31448 | Values: 123.0 125.0 123.0 121.0 120.0 119.0 127.0 119.0 129.0 132.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.90 Median:160.00 stddev:1.45 (0.9%) stddev-sans-first:1.36 17:26:43 INFO - PROCESS | 31448 | Values: 158.0 160.0 161.0 159.0 159.0 159.0 163.0 160.0 161.0 159.0 17:26:43 INFO - PROCESS | 31448 | 17:26:43 INFO - PROCESS | 31448 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:63.00 stddev:3.16 (5.0%) stddev-sans-first:3.33 17:26:43 INFO - PROCESS | 31448 | Values: 63.0 65.0 63.0 62.0 63.0 71.0 67.0 62.0 62.0 60.0 17:26:43 INFO - PROCESS | 31448 | -------- Summary: end -------- 17:26:43 INFO - PROCESS | 31448 | 17:26:44 INFO - TEST-INFO | 31448: exit 0 17:26:44 INFO - TEST-OK | kraken | took 61775ms 17:26:44 INFO - SUITE-END | took 1174s 17:26:44 INFO - Completed test suite (00:19:34) 17:26:44 INFO - javascript benchmark 17:26:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3048.26841558026, 3087.475129228031, 3091.9845400773, 3107.2697915989747, 3111.7355024822887, 5232.241325007872, 5242.528172464477, 5275.577953763699, 5333.439988801173, 5364.973175134129, 5253.185756207784, 5273.050850846846, 5286.497782710646, 5309.840704778853, 5350.678959262437, 4823.107725070856, 4847.830325938593, 5001.749912504379, 5056.039352523027, 5071.644984851062, 5010.72441015744, 5012.448630650644, 5023.7236951967625, 5030.0, 5040.747962601874, 4762.3332733417, 4791.784369703371, 4822.180229361017, 4847.781849816767, 4954.1330267203275, 4833.130036593412, 4840.782164802592, 4848.5151484851585, 4881.926771098437, 4909.901801963976, 4770.427548694179, 4794.976025119887, 4837.830675926326, 4847.95152048482, 4891.902161956777, 5158.045761534128, 5160.870978225536, 5217.921731174036, 5221.71280579568, 5295.152775555893, 5560.110382338807, 5583.972080139573, 5593.944060559383, 5659.971700141514, 5666.943330566724, 5650.124230744241, 5686.800961966312, 5689.089745640677, 5689.6017278790505, 5691.174779656968, 5177.275181474596, 5334.466553344673, 5409.648372855791, 5412.648177868466, 5480.725963701879, 5983.760649574056, 5995.310539287951, 6009.729562169713, 6011.639301641915, 6011.789587364421, 5749.338826034976, 5757.050086735641, 5767.971160144256, 5772.365039845614, 5772.682502462405, 5606.6636001840025, 5654.717264136859, 5729.770809167587, 5741.081426971665, 5770.278715160605, 5709.771609135588, 5722.685252311163, 5723.227364305849, 5731.083026715789, 5737.254156959652, 5489.39616642169, 5705.286839145107, 5738.713064346766, 5746.166805813217, 5752.683602401824, 4304.677149213821, 4331.913361732748, 4336.132773445323, 4349.369341445536, 4357.085012147484, 3881.514810648669, 3885.417187421909, 3893.124047089439, 3917.5690674025836, 3933.6263055009726, 5195.19474481456, 5245.921311180336, 5352.063388906926, 5365.08793505105, 5405.837824865261, 5664.20701101846, 5704.600677952503, 5706.3722990471015, 5724.79963201286, 5742.397048309951, 5330.653507521999, 5338.866528336823, 5339.359276886798, 5341.679499230058, 5344.278522399504, 4827.61379089672, 4842.249451335048, 4948.331975183376, 4949.430815456197, 4981.352424184833, 5823.475887170091, 5897.882042359129, 5899.646021238739, 5909.409059094056, 5957.1362152487645, 5677.5457963362005, 5683.090705487102, 5684.7441865117, 5689.317281926195, 5690.573207009408, 5775.162601422854, 5794.884102317846, 5838.737256823369, 5914.733836977261, 5935.436133567304, 5593.272874526286, 5617.831465056055, 5622.072358061037, 5624.493795558419, 5673.205751194919, 5675.7445914933105, 5677.0, 5710.914336285042, 5722.513586345207, 5727.599068065196, 5472.863178420572, 5504.91742623869, 5549.667019978814, 5566.109422492382, 5577.247071645276, 5560.0, 5562.610617256915, 5569.136783798518, 5570.944290557205, 5573.247611572386, 5490.725463726878, 5506.531944784738, 5509.862253443696, 5512.944870551243, 5553.500184983371, 5508.118701007819, 5517.36550296721, 5557.277553918127, 5567.66594004361, 5631.662100273997, 5458.1266997280245, 5466.3713672928125, 5473.06957817164, 5473.8357849264585, 5512.751926163252], "value": 5258.197287169505, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.9675517846516, 632.44976870123, 633.2654121219387, 647.3946859686193, 648.2383199740304, 30966.0, 41866.0, 42408.78795606025, 42438.99999999996, 42505.57494425063, 44419.333709994295, 44884.775576122076, 44961.77519112408, 44999.77500112503, 45093.323600146025, 8693.347998900092, 8860.512671803048, 8865.734027979139, 8866.379353445262, 8868.556572171397, 8790.47257164569, 8923.821523569524, 8935.865962010575, 8943.508107054142, 8946.552672366388, 8937.285017198641, 8994.68518601849, 8995.95502022489, 8997.730068097935, 9002.144796244347, 8865.822683546325, 8917.687880924137, 8920.197182253629, 8931.240844528224, 8933.419327743708, 8781.7804554886, 8884.689035883714, 8889.244414224799, 8892.644294228223, 8902.643894244222, 39236.607633923864, 39244.80377598122, 39577.80211098955, 39578.60421395807, 39605.60394396048, 3479.2693534357813, 3591.4612808078737, 3651.1602331463646, 3704.85180592776, 4225.873223803291, 29963.550546741815, 33287.334253314795, 33338.0, 33917.6608233917, 33965.490517642254, 4053.088055187589, 4074.2055299216663, 4112.115895082542, 4115.1975364803575, 4227.661787057028, 346793.0, 352438.0, 353838.99999999744, 354277.0, 355270.0, 55797.44202557922, 60310.39689603136, 63945.12246948898, 64680.35319646837, 64721.0, 63576.68211658912, 69200.0, 69224.30775692179, 69233.0, 69258.0, 15356.232188390535, 15380.077195368314, 15479.613009674624, 15494.5351639451, 15513.0, 10904.836427453594, 11879.643610691694, 11958.0, 11963.76072478563, 11966.22219555726, 20080.49798755043, 20561.69157462639, 20696.58606827885, 21386.0, 21448.142074316853, 7369.77890663281, 7631.885521717179, 7635.618219089023, 7791.805204869924, 7831.804204894809, 1045.4929359260905, 1058.5924419098549, 1080.1520806167277, 1111.3220935229504, 1115.8047341715167, 31828.363432731217, 32869.67130328714, 33068.0, 33135.337293254, 33164.50253246155, 1130.3726431830248, 1144.1075960750627, 1170.0990237517235, 1180.4889741131365, 1183.3077649574923, 16868.0, 17116.486505404857, 17417.65164696699, 17506.474805755846, 17602.0, 565912.0, 567579.0, 568404.0, 569081.0, 569449.0, 58637.0, 59045.40954590399, 59288.40711592915, 59308.406915931155, 59368.0, 1332.593558964525, 1349.6355983884296, 1357.3145561491383, 1379.7792353223435, 1406.5358431717714, 31837.36325273528, 31900.5214921781, 32917.50623740694, 33778.66221337755, 34367.312653747285, 52086.73956630193, 57614.7119264401, 57709.13436298375, 57899.0, 57946.0, 493678.0, 498624.0, 498813.0, 499326.0, 690539.2838214862, 45147.0, 45375.0, 45611.54388456073, 47667.76166119147, 49000.0, 50113.74943125261, 50222.246666300765, 50255.246171308194, 51226.0, 51303.48696512987, 41785.0, 42029.579704202566, 42536.57463425326, 42559.78720106503, 43618.12763744771, 41331.38002929898, 41590.58409415988, 41630.167396652505, 41794.1641167181, 42354.0, 41219.0, 41890.79054604707, 41900.0, 42210.78894605507, 42432.36351454669, 35701.28597427985, 37120.44319335158, 37790.62209377981, 37887.211278872994, 38158.0, 28686.282842929093, 28815.057038017192, 29033.12900612985, 29042.709572904, 29049.7095029047], "value": 20903.781044054478, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.78573570272056, 403.5802765124271, 408.9591040895909, 410.85620032988453, 423.0734691026651, 17916.19377128029, 19446.222151113958, 19476.41570752878, 19646.607067858633, 20412.285570005053, 16123.435679751212, 16662.666746665058, 16667.666646667058, 17830.286788528476, 18924.432267031978, 5185.766640501177, 5348.438413966536, 5354.678719276836, 5852.034414321632, 5861.5603829712945, 5621.522170615503, 5716.485516303532, 5733.713314334266, 5761.222234998263, 5820.447057529528, 5567.554595632339, 5695.259616249882, 5713.8285851424525, 5861.970690146543, 5873.0, 5929.940700593003, 5932.703364831741, 5942.2275104236605, 5952.404759524013, 5961.701914904259, 5573.804916827891, 5818.41815818419, 5822.592418530705, 5824.068149096167, 5827.388124246935, 13669.248191349472, 13911.095778774346, 17281.654366912717, 22658.886705566365, 22682.319530414115, 2381.9285421437385, 2635.8418494890366, 2744.3962328287744, 2840.602315675807, 2869.239651492356, 10570.704531084344, 10853.559850466896, 11224.831627525593, 12028.819567706492, 14585.562433127023, 1910.923563057476, 1914.0, 1985.067018501302, 1989.631918095157, 2037.663785475395, 77753.0, 78982.0, 79192.60403697945, 79223.0, 79291.0, 10152.39085654863, 10310.43292618913, 10618.893811061791, 10820.513076911559, 10982.560697572168, 15519.5344139676, 15641.765373519407, 15646.765298520531, 15655.530334089995, 15657.0, 7131.144262688511, 7651.1583725790115, 7735.535867847947, 7791.298783109547, 7825.1783562725095, 7219.314165154302, 7312.305330993547, 7377.372923301579, 7427.591482468516, 7445.218252083561, 4362.912741745147, 4417.977910110429, 4446.444194475691, 4572.88567785808, 4846.75766211688, 1912.2159914435015, 1916.9904150479438, 1949.1618604000416, 1956.4815323939167, 1965.36125759126, 1016.6441745389054, 1026.1728936779125, 1049.4857519815237, 1058.8888166742536, 1065.9360438373722, 18658.813411865707, 18678.34625788091, 18688.0, 19461.221551137794, 19626.31307904245, 984.9015098490094, 999.3104757717152, 1005.8692369992001, 1017.3743147963949, 1032.2670903658486, 8564.400491965627, 10581.947090264499, 10803.675889723321, 10943.726406839893, 10985.725356866142, 450041.0, 462353.0, 463075.0, 463294.0, 475444.0, 63168.0, 76775.61612191904, 78249.0, 84360.74312924844, 86288.0, 1029.505837198149, 1035.7773078788098, 1052.573707648419, 1062.102523367753, 1066.9970227985668, 13540.0, 17310.13449327554, 17505.21226544783, 17524.386646467312, 17761.378351758143, 38763.22473550457, 38779.22441551209, 40427.59572404238, 40647.1870562593, 41728.3740743883, 52894.47105528895, 54211.45788542222, 54260.0, 54324.0, 54922.0, 5165.509276618715, 5437.646552974203, 8068.709006558923, 8504.787380315542, 8863.911360886308, 12307.507699691912, 12797.872021279669, 12810.615681529569, 13035.608931732064, 13141.802872957105, 11278.887211127783, 11592.5362985483, 11791.823122653339, 11973.76052478963, 12081.516739330329, 11416.0, 11623.360715160747, 11747.882521174679, 12120.090993175723, 12135.453904574491, 11709.121815864015, 11812.881871181178, 11861.0, 11861.525538978343, 12026.0, 8721.433106848035, 8735.868961965703, 8801.207891289558, 8857.689980850639, 9005.234555062892, 8539.103394143525, 10314.535845887103, 10832.0, 10982.450877455936, 11261.066024999023], "value": 9022.942653716183, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.9251491167981, 1468.119128522886, 1480.1563109027854, 1638.2054703468816, 1790.1765188013505, 8079.111297757249, 8404.41169118162, 8410.95794521028, 8665.480071195718, 8691.174338437839, 8442.451240669352, 8633.913660863389, 8652.351073669468, 8655.43739656922, 8658.95670521648, 1971.3494546799593, 2163.5023944492777, 2250.0, 2302.9078836846506, 2332.1021406758437, 2293.8050265727434, 2298.8275879309035, 2300.64340027296, 2302.5164715409783, 2306.5041016181517, 2252.583272094668, 2258.8644681319092, 2268.727752669682, 2270.137347807831, 2280.042382199472, 1907.3133671878113, 2172.8587641803233, 2206.293985924505, 2225.7440394354694, 2238.8656680599215, 2191.605511008018, 2217.090992692988, 2234.1733558583264, 2234.832387570939, 2238.675392068157, 8536.829263414696, 8557.529335886524, 8570.785730356729, 8579.184977427134, 8600.5699715014, 4964.453910069889, 5112.309838171836, 5112.667676601046, 5114.872128196787, 5117.769700363493, 2899.695531969155, 2941.0735618280387, 2947.5873377727135, 2948.20398492408, 2971.06411480383, 998.3560603410832, 1009.2430676992255, 1016.0702956794524, 1031.0823367203195, 1032.3135115148418, 11753.647390578297, 11884.40577971098, 11895.821562676567, 11920.642380728592, 11950.28298302096, 4260.2970509866145, 4300.505441874162, 4363.869083927487, 4368.978155109204, 4397.670174736908, 9291.0, 9301.674441394518, 9320.90679093214, 9322.580483878242, 9323.347365684473, 5069.239614057921, 5070.087384270793, 5071.644984851099, 5072.847814565569, 5084.13569693153, 4190.224808410393, 4531.27499600069, 4611.515790841913, 4637.304404339308, 4685.109829132454, 3724.348239058154, 3789.204267103939, 3816.065064059323, 3820.4269359596283, 3895.5714871364125, 1137.0676045642495, 1151.3494875395374, 1159.2001518951897, 1170.4440390814295, 1172.1501911114374, 837.8031162676766, 952.552300418802, 953.2659851914126, 975.1175186456269, 978.3298440568234, 5901.232839730807, 5924.437178468039, 6024.457798798042, 6113.38866113394, 6136.8772624547255, 426.73115936960136, 428.08603631247513, 434.27692891336204, 435.4382846128522, 450.84220522817407, 4208.494980602347, 4211.55778643238, 4217.704760666724, 4227.767472789026, 4262.914741705148, 10338.0695737384, 10562.102221311121, 10589.0, 10608.734781630366, 10682.1988350874, 10415.583376664848, 10429.113525350278, 10504.054635082879, 10522.842157367646, 10538.789224215472, 7723.729669461542, 7975.322097621767, 7978.242067003625, 7993.480423772437, 8165.265126138557, 5962.493188079062, 6101.298350689727, 6152.723127459186, 6174.135621013152, 6248.250209974832, 9692.273079518924, 9933.15568176713, 10144.898551014396, 10184.287099903231, 10197.592096316064, 10105.090541850974, 10201.081902628799, 10224.386536807815, 10230.437325947145, 10242.231832612435, 6964.860702786017, 7475.962620186864, 7583.734569290048, 7642.4650274480255, 7721.6139193039, 8485.830283394174, 8537.18896704812, 8616.138386161334, 8619.569021549023, 8621.180987806149, 7495.138059123271, 7654.119776225805, 7659.617019149132, 7712.113106992768, 7788.376929845485, 7457.925420745723, 7472.439567032603, 7492.363149132385, 7603.353714934293, 7648.770536883902, 6875.759348422781, 6927.965360173369, 6962.477814164059, 6967.930320696728, 6973.511854170362, 5037.0681423936685, 5065.924011139762, 5144.6655967362, 5171.9482805171465, 5176.482351764794, 5120.4111527174855, 5238.842834714965, 5254.0, 5325.360956685223, 5400.05499037659], "value": 4408.8335346933045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.91946134274406, 315.37713016791855, 328.79450343535274, 329.2115383656144, 336.67342677602716, 5185.507376799198, 5334.759401051558, 5372.516473517383, 5380.650257733254, 5393.0, 5313.920291195635, 5324.509137441513, 5330.466953304667, 5337.386200586933, 5380.704061276629, 4181.560936101711, 4182.644475219602, 4188.434561334211, 4188.560201178878, 4192.790360481979, 4022.5575186729584, 4170.4578404807335, 4198.578158800583, 4201.579842015789, 4207.495100587934, 4084.9387259191135, 4097.6887396033135, 4123.525794533637, 4124.6700263978955, 4132.5454200037975, 4088.3867419886956, 4126.711130220886, 4129.814158362866, 4141.503019637633, 4142.233686767958, 3673.559172899269, 3697.2605478904325, 3707.6848467880263, 3715.6655900969045, 3725.795081270529, 5077.39071311445, 5116.132611596778, 5146.794128234867, 5156.226566015091, 5157.5616072633875, 4637.165310244155, 4654.511276316006, 4677.7894994724975, 4697.718136911796, 4703.50613185614, 5487.972560137214, 5493.725313734337, 5508.228847961289, 5536.806211782608, 5583.3300003999375, 3743.625637436262, 3974.841006359742, 3994.8827121625586, 4014.116894283253, 4048.9797551012352, 5553.278073850414, 5737.2254745609225, 5755.676194475294, 5851.332370274494, 5902.291724993029, 3880.3015457217407, 3933.7639741615594, 3939.8227079781477, 3943.743656662365, 4010.5588385277597, 3840.6927445804267, 3995.1210733638554, 4032.0, 4040.777757223381, 4066.2274167908595, 4521.982553925341, 4683.836065737751, 4688.0, 4692.788824502905, 4694.7887345068875, 5525.475079867406, 5527.55779537636, 5540.833774986676, 5541.806036788693, 5553.6390134641115, 1339.7521458530202, 1391.2348208485496, 1424.8076509671062, 1426.7717165253716, 1438.6906815034818, 888.9511076890833, 899.5592159841631, 914.3645166609276, 914.5427286356821, 917.7109210598641, 4773.331733557305, 4782.569568738831, 4801.5355316628065, 4805.231163013901, 4807.927881081787, 5045.974770126126, 5068.949310506921, 5103.591712662978, 5124.821291102992, 5180.274761533388, 4561.429821272341, 4703.764811759398, 4748.575427371844, 4767.928481072787, 4814.374131362901, 2705.580635001578, 2708.972910270872, 2717.2255907066788, 2719.986400068027, 2724.6185534025253, 5174.53429191367, 5212.687238765686, 5214.32213812202, 5233.607479439057, 5250.842474725764, 4794.328793968847, 5025.211007890583, 5126.974365128151, 5182.611304152204, 5192.3769147702515, 5194.714290714047, 5198.558122559549, 5242.5281724644965, 5246.396664383568, 5273.947260527346, 5026.371703537058, 5066.923996139987, 5074.949250507448, 5075.5383638934345, 5090.643654944119, 4940.888300132441, 5144.871378215575, 5423.918641220306, 5518.530924871431, 5601.915971260516, 5883.794067207627, 5892.6759028252145, 5897.174395584536, 5976.760929562769, 5988.401159883977, 4022.8994275143355, 4046.5144182698264, 4061.0, 4069.491313585802, 4071.69948978826, 5592.972035139798, 5720.542356611544, 5747.281589801275, 5750.769969201185, 5791.652500849962, 5554.305711786027, 5578.748956297058, 5581.469760372758, 5590.7763689453595, 5618.49433550966, 5395.865103372447, 5524.226608274925, 5541.418151094077, 5607.607467477237, 5623.943760562505, 5473.72631368422, 5496.351094671662, 5496.780128794963, 5502.80740174092, 5505.421930697219, 5574.9721251393485, 5592.259792701629, 5604.85987850307, 5653.717314134195, 5674.233978412862, 5260.158374660036, 5487.368952570346, 5506.476884695948, 5511.944880551304, 5523.6409633373705], "value": 4115.858460538723, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.7313947560449, 731.9048523691919, 750.7560042986031, 757.1330826203997, 796.4186144114798, 14377.568672939802, 15573.53279401617, 15628.765568516481, 16029.919850400745, 16323.673526529463, 15337.463188788395, 15408.3836646534, 15446.69106617867, 15491.457798977039, 15551.066935983848, 674.7840690978888, 794.7774623105539, 801.4229754576695, 813.9186081391844, 816.4162623724036, 809.8623234050197, 809.8663720486143, 811.4117264982899, 812.0296245986049, 812.1107387410776, 833.0212000898949, 838.174398217755, 839.49630221867, 840.2018082821313, 841.2680967558233, 702.4555969123919, 815.9306458950996, 832.883396324515, 833.4582521361134, 845.3448577352601, 618.3754408047859, 622.3092367472102, 622.498888394842, 623.1276213301414, 623.3953065526432, 15660.373585056584, 15672.764908526382, 15702.528924132293, 15704.528864134094, 15706.764398534031, 12410.255384676948, 12668.303243321614, 12907.225566466042, 13149.474021039146, 13312.068155229137, 11638.0, 11806.704832379173, 12227.877721222852, 12307.630771076882, 12332.75334493314, 4004.2592120457516, 4015.959840401588, 4079.224947260011, 4089.427480152781, 4170.145120250371, 73015.0, 73485.0, 74359.62820185919, 74398.0, 74981.0, 10216.233782466345, 10359.430231337197, 10416.218783591261, 10477.47612619366, 10514.26400151982, 19623.803761962197, 19769.60460790776, 20619.278325258543, 20688.379348619565, 20705.792942070686, 7569.167391586921, 7627.389808815282, 7824.843503129906, 7885.723999659985, 8116.715914942949, 4266.445362102907, 4307.5261721210645, 4351.173277077409, 4384.517703052662, 4433.689641725113, 3341.565596472443, 3457.429524128541, 3459.446488561818, 3460.7058400035776, 3460.8442620082155, 560.436761055137, 577.3850848845966, 578.780063575847, 579.2556564814194, 580.5906835680835, 627.3820287017285, 638.355261186196, 645.7384759172543, 657.0932113683086, 666.6699983508098, 8925.196732293995, 8991.28069754431, 8991.82016359669, 8997.775055623531, 8997.865032024525, 596.627108057464, 611.9235095613049, 621.6674079367517, 628.6573817269599, 632.2160520954076, 3672.3573374659863, 3733.1227161617, 3881.6700580450415, 4353.25994580922, 4463.330500424897, 92279.0, 94992.0, 95634.52182739181, 106119.46940265404, 107958.46020770003, 91020.54489727509, 91139.54430227939, 92015.53992229995, 92206.0, 93023.0, 54676.17985730217, 74207.62896185485, 85278.14721852826, 86037.56981215179, 86453.56773216094, 9302.348835581444, 9306.627734890528, 9607.231421486125, 9778.364406313567, 9781.119699227103, 48214.75892620515, 48266.75866620644, 48748.0, 48898.511014889395, 50130.24804628007, 46932.7653361731, 72532.27467725256, 72597.6370118146, 73168.26831731615, 74958.0, 12448.315342655878, 12529.93735031319, 12898.0, 12954.02844786626, 13040.608781736562, 14274.785878212042, 14491.637709057359, 14534.854651453774, 14740.0, 15056.548303550911, 10294.279400441897, 12100.636980890587, 12538.937305313415, 13562.186268823902, 13871.514496992557, 13380.330983450594, 13505.729885402041, 13880.652983675085, 13955.162690238618, 14150.929245353707, 13725.725485490035, 13836.930815345859, 13881.652958676113, 14362.13827170373, 14660.193689347188, 11160.88839111631, 11305.943470282926, 11505.654830355103, 11567.652970410902, 12026.639200823989, 11039.503222355173, 11127.499262533043, 11222.563016394832, 11303.717407064889, 11379.943100284445], "value": 7252.315002241393, "unit": "score"}], "name": "dromaeo_css", "value": 7122.296369720629, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 110.0, 113.0, 109.0, 109.0, 110.0, 109.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 126.0, 131.0, 184.0, 127.0, 135.0, 151.0, 125.0, 162.0, 127.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 161.0, 162.0, 161.0, 161.0, 162.0, 162.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 131.0, 75.0, 75.0, 75.0, 74.0, 97.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 115.0, 84.0, 83.0, 83.0, 119.0, 83.0, 83.0, 131.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 119.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 54.0, 56.0, 54.0, 56.0, 52.0, 53.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 64.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 79.0, 85.0, 85.0, 84.0, 87.0, 87.0, 114.0, 81.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 125.0, 123.0, 121.0, 120.0, 119.0, 127.0, 119.0, 129.0, 132.0], "value": 123.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 161.0, 159.0, 159.0, 159.0, 163.0, 160.0, 161.0, 159.0], "value": 159.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 62.0, 63.0, 71.0, 67.0, 62.0, 62.0, 60.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1498.5, "alertThreshold": 2.0}]} 17:26:44 INFO - Return code: 0 17:26:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:26:45 ERROR - # TBPL SUCCESS # 17:26:45 INFO - Running post-action listener: _resource_record_post_action 17:26:45 INFO - ##### 17:26:45 INFO - ##### Finished run-tests step (success) 17:26:45 INFO - ##### 17:26:45 INFO - Running post-run listener: _resource_record_post_run 17:26:45 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 98304; Write bytes: 539279360; Read time: 320; Write time: 539700 17:26:45 INFO - TinderboxPrint: CPU usage
13.2% 17:26:45 INFO - TinderboxPrint: I/O read bytes / time
98,304 / 320 17:26:45 INFO - TinderboxPrint: I/O write bytes / time
539,279,360 / 539,700 17:26:45 INFO - TinderboxPrint: CPU idle
8,264.7 (86.8%) 17:26:45 INFO - TinderboxPrint: CPU user
1,208.7 (12.7%) 17:26:45 INFO - TinderboxPrint: Swap in / out
0 / 0 17:26:45 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247668736; Read time: 0; Write time: 304696 17:26:45 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 98304; Write bytes: 291610624; Read time: 320; Write time: 235004 17:26:45 INFO - Running post-run listener: _upload_blobber_files 17:26:45 INFO - Blob upload gear active. 17:26:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:26:45 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 17:26:45 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:26:45 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:26:46 INFO - (blobuploader) - INFO - Open directory for files ... 17:26:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:26:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:26:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:46 INFO - (blobuploader) - INFO - Done attempting. 17:26:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:26:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:26:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:51 INFO - (blobuploader) - INFO - Done attempting. 17:26:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:26:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:26:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:52 INFO - (blobuploader) - INFO - Done attempting. 17:26:52 INFO - (blobuploader) - INFO - Iteration through files over. 17:26:52 INFO - Return code: 0 17:26:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:26:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:26:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b61ff72b58fb2181b348b123674abcc38af59c8a0411199c6d02af7612221fdf026b6d0d16cd3017aea032de60d58cb648934170da9cccf1e961da49a5e9f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9880bd4daf39788752514c7bf9f497023125bcc77135d251ce1f9ded1272a9794c4d5619d4283f317d24f9d063a76529a087ae46798799f03c2a6f1ffb834f33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/526ec0c92918f40e2309c8772386b402a2e6fa36ab802b35923adbaca8747a68d00c07d7189d8eba219b143bc21249b3d862d5b8cc7a308fda70ce65082b3c7b"} 17:26:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:26:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:26:52 INFO - Contents: 17:26:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b61ff72b58fb2181b348b123674abcc38af59c8a0411199c6d02af7612221fdf026b6d0d16cd3017aea032de60d58cb648934170da9cccf1e961da49a5e9f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9880bd4daf39788752514c7bf9f497023125bcc77135d251ce1f9ded1272a9794c4d5619d4283f317d24f9d063a76529a087ae46798799f03c2a6f1ffb834f33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/526ec0c92918f40e2309c8772386b402a2e6fa36ab802b35923adbaca8747a68d00c07d7189d8eba219b143bc21249b3d862d5b8cc7a308fda70ce65082b3c7b"} 17:26:52 INFO - Running post-run listener: copy_logs_to_upload_dir 17:26:52 INFO - Copying logs to upload dir... 17:26:52 INFO - mkdir: /builds/slave/test/build/upload/logs 17:26:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.357798 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-02 17:26:51.993559) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 17:26:51.997256) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326 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/sha512/5b61ff72b58fb2181b348b123674abcc38af59c8a0411199c6d02af7612221fdf026b6d0d16cd3017aea032de60d58cb648934170da9cccf1e961da49a5e9f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9880bd4daf39788752514c7bf9f497023125bcc77135d251ce1f9ded1272a9794c4d5619d4283f317d24f9d063a76529a087ae46798799f03c2a6f1ffb834f33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/526ec0c92918f40e2309c8772386b402a2e6fa36ab802b35923adbaca8747a68d00c07d7189d8eba219b143bc21249b3d862d5b8cc7a308fda70ce65082b3c7b"} build_url:https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011244 build_url: 'https://queue.taskcluster.net/v1/task/CP6W1QfjQYGO_9AmViwb9g/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/sha512/5b61ff72b58fb2181b348b123674abcc38af59c8a0411199c6d02af7612221fdf026b6d0d16cd3017aea032de60d58cb648934170da9cccf1e961da49a5e9f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9880bd4daf39788752514c7bf9f497023125bcc77135d251ce1f9ded1272a9794c4d5619d4283f317d24f9d063a76529a087ae46798799f03c2a6f1ffb834f33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/526ec0c92918f40e2309c8772386b402a2e6fa36ab802b35923adbaca8747a68d00c07d7189d8eba219b143bc21249b3d862d5b8cc7a308fda70ce65082b3c7b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 17:26:52.022226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 17:26:52.022546) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464912063.531399-444852326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 17:26:52.105325) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 17:26:52.105661) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 17:26:52.105937) ========= ========= Total master_lag: 0.34 =========