builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-044 starttime: 1464383207.06 results: success (0) buildid: 20160527113050 builduid: a337dc0e715e4f5e93f7b3efc2d68335 revision: eec76f75c79a0a597131357ec1eed49683183c30 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.062327) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.062813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.063091) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.077647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.077911) ========= 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=574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 14:06:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 126K=0.09s 2016-05-27 14:06:48 (126 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.215683 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.307186) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.307514) ========= 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=574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015245 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:47.357330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 14:06:47.357716) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eec76f75c79a0a597131357ec1eed49683183c30 --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 eec76f75c79a0a597131357ec1eed49683183c30 --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=574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 14:06:48,327 truncating revision to first 12 chars 2016-05-27 14:06:48,327 Setting DEBUG logging. 2016-05-27 14:06:48,327 attempt 1/10 2016-05-27 14:06:48,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eec76f75c79a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 14:06:49,245 unpacking tar archive at: fx-team-eec76f75c79a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.241744 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 14:06:48.613136) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:48.613537) ========= script_repo_revision: eec76f75c79a0a597131357ec1eed49683183c30 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:48.613923) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:48.614192) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 14:06:48.624515) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-27 14:06:48.624798) ========= /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=574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:06:49 INFO - MultiFileLogger online at 20160527 14:06:49 in /builds/slave/test 14:06:49 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 14:06:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:06:49 INFO - {'append_to_log': False, 14:06:49 INFO - 'base_work_dir': '/builds/slave/test', 14:06:49 INFO - 'blob_upload_branch': 'Fx-Team', 14:06:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:06:49 INFO - 'branch': 'Fx-Team', 14:06:49 INFO - 'buildbot_json_path': 'buildprops.json', 14:06:49 INFO - 'config_files': ('talos/linux_config.py',), 14:06:49 INFO - 'default_actions': ('clobber', 14:06:49 INFO - 'read-buildbot-config', 14:06:49 INFO - 'download-and-extract', 14:06:49 INFO - 'populate-webroot', 14:06:49 INFO - 'create-virtualenv', 14:06:49 INFO - 'install', 14:06:49 INFO - 'run-tests'), 14:06:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:06:49 INFO - 'download_minidump_stackwalk': True, 14:06:49 INFO - 'download_symbols': 'ondemand', 14:06:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:06:49 INFO - 'tooltool.py': '/tools/tooltool.py', 14:06:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:06:49 INFO - '/tools/misc-python/virtualenv.py')}, 14:06:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:06:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:06:49 INFO - 'installer_path': 'installer.exe', 14:06:49 INFO - 'log_level': 'info', 14:06:49 INFO - 'log_name': 'talos', 14:06:49 INFO - 'log_to_console': True, 14:06:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:06:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:06:49 INFO - 'opt_config_files': (), 14:06:49 INFO - 'pip_index': False, 14:06:49 INFO - 'sps_profile': False, 14:06:49 INFO - 'sps_profile_interval': 0, 14:06:49 INFO - 'suite': 'dromaeojs', 14:06:49 INFO - 'system_bits': '32', 14:06:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:06:49 INFO - 'title': 'talos-linux64-ix-044', 14:06:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:06:49 INFO - 'use_talos_json': True, 14:06:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:06:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:06:49 INFO - 'work_dir': 'build'} 14:06:49 INFO - ##### 14:06:49 INFO - ##### Running clobber step. 14:06:49 INFO - ##### 14:06:49 INFO - Running pre-action listener: _resource_record_pre_action 14:06:49 INFO - Running main action method: clobber 14:06:49 INFO - rmtree: /builds/slave/test/build 14:06:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:06:50 INFO - Running post-action listener: _resource_record_post_action 14:06:50 INFO - ##### 14:06:50 INFO - ##### Finished clobber step (success) 14:06:50 INFO - ##### 14:06:50 INFO - ##### 14:06:50 INFO - ##### Running read-buildbot-config step. 14:06:50 INFO - ##### 14:06:50 INFO - Running pre-action listener: _resource_record_pre_action 14:06:50 INFO - Running main action method: read_buildbot_config 14:06:50 INFO - Using buildbot properties: 14:06:50 INFO - { 14:06:50 INFO - "project": "", 14:06:50 INFO - "product": "firefox", 14:06:50 INFO - "script_repo_revision": "production", 14:06:50 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:06:50 INFO - "repository": "", 14:06:50 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 14:06:50 INFO - "buildid": "20160527113050", 14:06:50 INFO - "pgo_build": "True", 14:06:50 INFO - "basedir": "/builds/slave/test", 14:06:50 INFO - "buildnumber": 135, 14:06:50 INFO - "slavename": "talos-linux64-ix-044", 14:06:50 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:06:50 INFO - "platform": "ubuntu64_hw", 14:06:50 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 14:06:50 INFO - "branch": "fx-team", 14:06:50 INFO - "repo_path": "integration/fx-team", 14:06:50 INFO - "revision": "eec76f75c79a0a597131357ec1eed49683183c30", 14:06:50 INFO - "stage_platform": "linux64-pgo", 14:06:50 INFO - "builduid": "a337dc0e715e4f5e93f7b3efc2d68335", 14:06:50 INFO - "slavebuilddir": "test" 14:06:50 INFO - } 14:06:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:06:50 INFO - Running post-action listener: _resource_record_post_action 14:06:50 INFO - ##### 14:06:50 INFO - ##### Finished read-buildbot-config step (success) 14:06:50 INFO - ##### 14:06:50 INFO - ##### 14:06:50 INFO - ##### Running download-and-extract step. 14:06:50 INFO - ##### 14:06:50 INFO - Running pre-action listener: _resource_record_pre_action 14:06:50 INFO - Running main action method: download_and_extract 14:06:50 INFO - mkdir: /builds/slave/test/build/tests 14:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:50 INFO - https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:06:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:06:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:06:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:50 INFO - Downloaded 1361 bytes. 14:06:50 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:06:50 INFO - Using the following test package requirements: 14:06:50 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:06:50 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:06:50 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:06:50 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'jsshell-linux-x86_64.zip'], 14:06:50 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:06:50 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:06:50 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:06:50 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:06:50 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:06:50 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:06:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:06:50 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:50 INFO - https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:06:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:06:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:06:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:52 INFO - Downloaded 24410787 bytes. 14:06:52 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'] 14:06:52 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 14:06:53 INFO - Return code: 0 14:06:53 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 14:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:53 INFO - https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:06:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:06:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:06:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:54 INFO - Downloaded 24410787 bytes. 14:06:54 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'] 14:06:54 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 14:06:55 INFO - Return code: 0 14:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:55 INFO - https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:06:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:06:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:06:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:56 INFO - Downloaded 10834034 bytes. 14:06:56 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'] 14:06:56 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 14:06:57 INFO - Return code: 0 14:06:57 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')]} 14:06:57 INFO - https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:06:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:06:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:06:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pidq7zV2R6yg41_GF640wA/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 14:06:58 INFO - Downloaded 58731181 bytes. 14:06:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:06:58 INFO - mkdir: /builds/slave/test/properties 14:06:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:06:58 INFO - Writing to file /builds/slave/test/properties/build_url 14:06:58 INFO - Contents: 14:06:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:06:59 INFO - Running post-action listener: _resource_record_post_action 14:06:59 INFO - Running post-action listener: set_extra_try_arguments 14:06:59 INFO - ##### 14:06:59 INFO - ##### Finished download-and-extract step (success) 14:06:59 INFO - ##### 14:06:59 INFO - ##### 14:06:59 INFO - ##### Running populate-webroot step. 14:06:59 INFO - ##### 14:06:59 INFO - Running pre-action listener: _resource_record_pre_action 14:06:59 INFO - Running main action method: populate_webroot 14:06:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:06:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:06:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:06:59 INFO - '--tppagecycles', 14:06:59 INFO - '7'], 14:06:59 INFO - 'tests': ['tsvgm']}}, 14:06:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:06:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:06:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:06:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:06:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['damp', 'tps']}, 14:06:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'tests': ['damp', 'tps']}, 14:06:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['dromaeo_dom']}, 14:06:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:06:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['a11yr', 14:06:59 INFO - 'ts_paint', 14:06:59 INFO - 'tpaint', 14:06:59 INFO - 'sessionrestore', 14:06:59 INFO - 'sessionrestore_no_auto_restore', 14:06:59 INFO - 'tabpaint']}, 14:06:59 INFO - 'other-e10s': {'tests': ['a11yr', 14:06:59 INFO - 'ts_paint', 14:06:59 INFO - 'tpaint', 14:06:59 INFO - 'sessionrestore', 14:06:59 INFO - 'sessionrestore_no_auto_restore', 14:06:59 INFO - 'tabpaint']}, 14:06:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:06:59 INFO - 'ts_paint', 14:06:59 INFO - 'tpaint', 14:06:59 INFO - 'sessionrestore', 14:06:59 INFO - 'sessionrestore_no_auto_restore', 14:06:59 INFO - 'tabpaint']}, 14:06:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:06:59 INFO - 'ts_paint', 14:06:59 INFO - 'tpaint', 14:06:59 INFO - 'sessionrestore', 14:06:59 INFO - 'sessionrestore_no_auto_restore', 14:06:59 INFO - 'tabpaint']}, 14:06:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['a11yr', 14:06:59 INFO - 'ts_paint', 14:06:59 INFO - 'tpaint', 14:06:59 INFO - 'sessionrestore', 14:06:59 INFO - 'sessionrestore_no_auto_restore', 14:06:59 INFO - 'tabpaint']}, 14:06:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['a11yr', 14:06:59 INFO - 'ts_paint', 14:06:59 INFO - 'tpaint', 14:06:59 INFO - 'sessionrestore', 14:06:59 INFO - 'sessionrestore_no_auto_restore', 14:06:59 INFO - 'tabpaint']}, 14:06:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['tsvgx', 14:06:59 INFO - 'tsvgr_opacity', 14:06:59 INFO - 'tart', 14:06:59 INFO - 'tscrollx', 14:06:59 INFO - 'cart']}, 14:06:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:06:59 INFO - 'tsvgr_opacity', 14:06:59 INFO - 'tart', 14:06:59 INFO - 'tscrollx', 14:06:59 INFO - 'cart']}, 14:06:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'talos_options': ['--disable-e10s'], 14:06:59 INFO - 'tests': ['tp5o']}, 14:06:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'tests': ['tp5o']}, 14:06:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'talos_options': ['--disable-e10s', 14:06:59 INFO - '--xperf_path', 14:06:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:59 INFO - 'tests': ['tp5n']}, 14:06:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:59 INFO - 'talos_options': ['--xperf_path', 14:06:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:59 INFO - 'tests': ['tp5n']}}, 14:06:59 INFO - 'talos.zip': {'path': '', 14:06:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:06:59 INFO - Running post-action listener: _resource_record_post_action 14:06:59 INFO - ##### 14:06:59 INFO - ##### Finished populate-webroot step (success) 14:06:59 INFO - ##### 14:06:59 INFO - ##### 14:06:59 INFO - ##### Running create-virtualenv step. 14:06:59 INFO - ##### 14:06:59 INFO - Running pre-action listener: _resource_record_pre_action 14:06:59 INFO - Running main action method: create_virtualenv 14:06:59 INFO - Creating virtualenv /builds/slave/test/build/venv 14:06:59 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 14:06:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:06:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:06:59 INFO - Using real prefix '/usr' 14:06:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:07:00 INFO - Installing distribute.............................................................................................................................................................................................done. 14:07:01 INFO - Installing pip.................done. 14:07:01 INFO - Return code: 0 14:07:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:07:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:07:01 INFO - Using env: {'DISPLAY': ':0', 14:07:01 INFO - 'HOME': '/home/cltbld', 14:07:01 INFO - 'LANG': 'en_US.UTF-8', 14:07:01 INFO - 'LANGUAGE': 'en_US:en', 14:07:01 INFO - 'LOGNAME': 'cltbld', 14:07:01 INFO - 'MAIL': '/var/mail/cltbld', 14:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:01 INFO - 'MOZ_NO_REMOTE': '1', 14:07:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:01 INFO - 'NO_EM_RESTART': '1', 14:07:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:01 INFO - 'PWD': '/builds/slave/test', 14:07:01 INFO - 'SHELL': '/bin/bash', 14:07:01 INFO - 'SHLVL': '1', 14:07:01 INFO - 'TERM': 'linux', 14:07:01 INFO - 'TMOUT': '86400', 14:07:01 INFO - 'USER': 'cltbld', 14:07:02 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:02 INFO - '_': '/tools/buildbot/bin/python'} 14:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:02 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 14:07:02 INFO - Cleaning up... 14:07:02 INFO - Return code: 0 14:07:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:07:02 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')]} 14:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:02 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')]} 14:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:02 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 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:02 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 14:07:02 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 14:07:02 INFO - Using env: {'DISPLAY': ':0', 14:07:02 INFO - 'HOME': '/home/cltbld', 14:07:02 INFO - 'LANG': 'en_US.UTF-8', 14:07:02 INFO - 'LANGUAGE': 'en_US:en', 14:07:02 INFO - 'LOGNAME': 'cltbld', 14:07:02 INFO - 'MAIL': '/var/mail/cltbld', 14:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:02 INFO - 'MOZ_NO_REMOTE': '1', 14:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:02 INFO - 'NO_EM_RESTART': '1', 14:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:02 INFO - 'PWD': '/builds/slave/test', 14:07:02 INFO - 'SHELL': '/bin/bash', 14:07:02 INFO - 'SHLVL': '1', 14:07:02 INFO - 'TERM': 'linux', 14:07:02 INFO - 'TMOUT': '86400', 14:07:02 INFO - 'USER': 'cltbld', 14:07:02 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:02 INFO - '_': '/tools/buildbot/bin/python'} 14:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:02 INFO - Downloading/unpacking psutil>=3.1.1 14:07:02 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 14:07:02 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 14:07:02 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 14:07:02 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 14:07:02 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 14:07:02 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 14:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:07:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:07:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:07:04 INFO - Installing collected packages: psutil 14:07:04 INFO - Running setup.py install for psutil 14:07:04 INFO - building 'psutil._psutil_linux' extension 14:07:04 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 14:07:04 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 14:07:04 INFO - building 'psutil._psutil_posix' extension 14:07:04 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 14:07:04 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 14:07:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:07:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:07:04 INFO - Successfully installed psutil 14:07:04 INFO - Cleaning up... 14:07:04 INFO - Return code: 0 14:07:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:07:04 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')]} 14:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:04 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')]} 14:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:04 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 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:04 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 14:07:04 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 14:07:04 INFO - Using env: {'DISPLAY': ':0', 14:07:04 INFO - 'HOME': '/home/cltbld', 14:07:04 INFO - 'LANG': 'en_US.UTF-8', 14:07:04 INFO - 'LANGUAGE': 'en_US:en', 14:07:04 INFO - 'LOGNAME': 'cltbld', 14:07:04 INFO - 'MAIL': '/var/mail/cltbld', 14:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:04 INFO - 'MOZ_NO_REMOTE': '1', 14:07:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:04 INFO - 'NO_EM_RESTART': '1', 14:07:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:04 INFO - 'PWD': '/builds/slave/test', 14:07:04 INFO - 'SHELL': '/bin/bash', 14:07:04 INFO - 'SHLVL': '1', 14:07:04 INFO - 'TERM': 'linux', 14:07:04 INFO - 'TMOUT': '86400', 14:07:04 INFO - 'USER': 'cltbld', 14:07:04 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:04 INFO - '_': '/tools/buildbot/bin/python'} 14:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:07:05 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 14:07:05 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 14:07:05 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 14:07:05 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 14:07:05 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 14:07:05 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 14:07:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:07:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:07:07 INFO - Installing collected packages: mozsystemmonitor 14:07:07 INFO - Running setup.py install for mozsystemmonitor 14:07:07 INFO - Successfully installed mozsystemmonitor 14:07:07 INFO - Cleaning up... 14:07:07 INFO - Return code: 0 14:07:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:07 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 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:07 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 14:07:07 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 14:07:07 INFO - Using env: {'DISPLAY': ':0', 14:07:07 INFO - 'HOME': '/home/cltbld', 14:07:07 INFO - 'LANG': 'en_US.UTF-8', 14:07:07 INFO - 'LANGUAGE': 'en_US:en', 14:07:07 INFO - 'LOGNAME': 'cltbld', 14:07:07 INFO - 'MAIL': '/var/mail/cltbld', 14:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:07 INFO - 'MOZ_NO_REMOTE': '1', 14:07:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:07 INFO - 'NO_EM_RESTART': '1', 14:07:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:07 INFO - 'PWD': '/builds/slave/test', 14:07:07 INFO - 'SHELL': '/bin/bash', 14:07:07 INFO - 'SHLVL': '1', 14:07:07 INFO - 'TERM': 'linux', 14:07:07 INFO - 'TMOUT': '86400', 14:07:07 INFO - 'USER': 'cltbld', 14:07:07 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:07 INFO - '_': '/tools/buildbot/bin/python'} 14:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:07 INFO - Downloading/unpacking blobuploader==1.2.4 14:07:07 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 14:07:07 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 14:07:07 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 14:07:07 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 14:07:07 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 14:07:07 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 14:07:09 INFO - Downloading blobuploader-1.2.4.tar.gz 14:07:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:07:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:07:09 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 14:07:09 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 14:07:09 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 14:07:09 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 14:07:09 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 14:07:09 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 14:07:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:07:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:07:09 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 14:07:09 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 14:07:09 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 14:07:09 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 14:07:09 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 14:07:09 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 14:07:10 INFO - Downloading docopt-0.6.1.tar.gz 14:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:07:10 INFO - Installing collected packages: blobuploader, requests, docopt 14:07:10 INFO - Running setup.py install for blobuploader 14:07:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:07:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:07:10 INFO - Running setup.py install for requests 14:07:10 INFO - Running setup.py install for docopt 14:07:10 INFO - Successfully installed blobuploader requests docopt 14:07:10 INFO - Cleaning up... 14:07:10 INFO - Return code: 0 14:07:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:10 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 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:10 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 14:07:10 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 14:07:10 INFO - Using env: {'DISPLAY': ':0', 14:07:10 INFO - 'HOME': '/home/cltbld', 14:07:10 INFO - 'LANG': 'en_US.UTF-8', 14:07:10 INFO - 'LANGUAGE': 'en_US:en', 14:07:10 INFO - 'LOGNAME': 'cltbld', 14:07:10 INFO - 'MAIL': '/var/mail/cltbld', 14:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:10 INFO - 'MOZ_NO_REMOTE': '1', 14:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:10 INFO - 'NO_EM_RESTART': '1', 14:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:10 INFO - 'PWD': '/builds/slave/test', 14:07:10 INFO - 'SHELL': '/bin/bash', 14:07:10 INFO - 'SHLVL': '1', 14:07:10 INFO - 'TERM': 'linux', 14:07:10 INFO - 'TMOUT': '86400', 14:07:10 INFO - 'USER': 'cltbld', 14:07:10 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:10 INFO - '_': '/tools/buildbot/bin/python'} 14:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:07:10 INFO - Running setup.py (path:/tmp/pip-nPNjCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:07:10 INFO - Running setup.py (path:/tmp/pip-uwc2qO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:07:11 INFO - Running setup.py (path:/tmp/pip-JPUEiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:07:11 INFO - Running setup.py (path:/tmp/pip-zVObO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:07:11 INFO - Running setup.py (path:/tmp/pip-mtKmCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:07:11 INFO - Running setup.py (path:/tmp/pip-1_geOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:07:11 INFO - Running setup.py (path:/tmp/pip-0WIkyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:07:11 INFO - Running setup.py (path:/tmp/pip-rzlYwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:07:11 INFO - Running setup.py (path:/tmp/pip-V4S9u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:07:11 INFO - Running setup.py (path:/tmp/pip-GQPu4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:07:11 INFO - Running setup.py (path:/tmp/pip-J9iTcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:07:11 INFO - Running setup.py (path:/tmp/pip-oLvczX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:07:11 INFO - Running setup.py (path:/tmp/pip-wIMMyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:07:11 INFO - Running setup.py (path:/tmp/pip-4XsNvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:11 INFO - Running setup.py (path:/tmp/pip-4obiWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:07:11 INFO - Running setup.py (path:/tmp/pip-9RLili-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:07:12 INFO - Running setup.py (path:/tmp/pip-YPz1dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:07:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:07:12 INFO - Running setup.py install for manifestparser 14:07:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Running setup.py install for mozcrash 14:07:12 INFO - Running setup.py install for mozdebug 14:07:12 INFO - Running setup.py install for mozdevice 14:07:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Running setup.py install for mozfile 14:07:12 INFO - Running setup.py install for mozhttpd 14:07:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Running setup.py install for mozinfo 14:07:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Running setup.py install for mozInstall 14:07:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:07:12 INFO - Running setup.py install for mozleak 14:07:13 INFO - Running setup.py install for mozlog 14:07:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:07:13 INFO - Running setup.py install for moznetwork 14:07:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:07:13 INFO - Running setup.py install for mozprocess 14:07:13 INFO - Running setup.py install for mozprofile 14:07:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:07:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:07:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:07:13 INFO - Running setup.py install for mozrunner 14:07:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:07:13 INFO - Running setup.py install for mozscreenshot 14:07:13 INFO - Running setup.py install for moztest 14:07:13 INFO - Running setup.py install for mozversion 14:07:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:07:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:07:13 INFO - Cleaning up... 14:07:13 INFO - Return code: 0 14:07:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:13 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')]} 14:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:13 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')]} 14:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:13 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 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:13 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 14:07:13 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 14:07:13 INFO - Using env: {'DISPLAY': ':0', 14:07:13 INFO - 'HOME': '/home/cltbld', 14:07:13 INFO - 'LANG': 'en_US.UTF-8', 14:07:13 INFO - 'LANGUAGE': 'en_US:en', 14:07:13 INFO - 'LOGNAME': 'cltbld', 14:07:13 INFO - 'MAIL': '/var/mail/cltbld', 14:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:13 INFO - 'MOZ_NO_REMOTE': '1', 14:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:13 INFO - 'NO_EM_RESTART': '1', 14:07:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:13 INFO - 'PWD': '/builds/slave/test', 14:07:13 INFO - 'SHELL': '/bin/bash', 14:07:13 INFO - 'SHLVL': '1', 14:07:13 INFO - 'TERM': 'linux', 14:07:13 INFO - 'TMOUT': '86400', 14:07:13 INFO - 'USER': 'cltbld', 14:07:13 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:13 INFO - '_': '/tools/buildbot/bin/python'} 14:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:07:14 INFO - Running setup.py (path:/tmp/pip-1tqVRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:07:14 INFO - Running setup.py (path:/tmp/pip-3AbtXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:07:14 INFO - Running setup.py (path:/tmp/pip-u8L1QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:07:14 INFO - Running setup.py (path:/tmp/pip-hvHl4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:07:14 INFO - Running setup.py (path:/tmp/pip-cPbIYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:07:14 INFO - Running setup.py (path:/tmp/pip-oM1tbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:07:14 INFO - Running setup.py (path:/tmp/pip-eKTk9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:07:14 INFO - Running setup.py (path:/tmp/pip-OAjXVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:07:14 INFO - Running setup.py (path:/tmp/pip-BmGQmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:07:14 INFO - Running setup.py (path:/tmp/pip-A4iqC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:07:14 INFO - Running setup.py (path:/tmp/pip-5JBNLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:07:14 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)) 14:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:07:14 INFO - Running setup.py (path:/tmp/pip-WarL53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:07:15 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)) 14:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:07:15 INFO - Running setup.py (path:/tmp/pip-uye0eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:07:15 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)) 14:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:07:15 INFO - Running setup.py (path:/tmp/pip-BEra8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:07:15 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)) 14:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:15 INFO - Running setup.py (path:/tmp/pip-tWiTmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:15 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)) 14:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:07:15 INFO - Running setup.py (path:/tmp/pip-hlGeNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:07:15 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)) 14:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:07:15 INFO - Running setup.py (path:/tmp/pip-o4PAlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:07:15 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)) 14:07:15 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)) 14:07:15 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)) 14:07:15 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)) 14:07:15 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)) 14:07:15 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)) 14:07:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:07:15 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 14:07:15 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 14:07:15 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 14:07:15 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 14:07:15 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 14:07:15 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 14:07:17 INFO - Downloading blessings-1.6.tar.gz 14:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:07:17 INFO - Installing collected packages: blessings 14:07:17 INFO - Running setup.py install for blessings 14:07:17 INFO - Successfully installed blessings 14:07:17 INFO - Cleaning up... 14:07:17 INFO - Return code: 0 14:07:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:07:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:17 INFO - Reading from file tmpfile_stdout 14:07:17 INFO - Current package versions: 14:07:17 INFO - argparse == 1.2.1 14:07:17 INFO - blessings == 1.6 14:07:17 INFO - blobuploader == 1.2.4 14:07:17 INFO - docopt == 0.6.1 14:07:17 INFO - manifestparser == 1.1 14:07:17 INFO - mozInstall == 1.12 14:07:17 INFO - mozcrash == 0.17 14:07:17 INFO - mozdebug == 0.1 14:07:17 INFO - mozdevice == 0.48 14:07:17 INFO - mozfile == 1.2 14:07:17 INFO - mozhttpd == 0.7 14:07:17 INFO - mozinfo == 0.9 14:07:17 INFO - mozleak == 0.1 14:07:17 INFO - mozlog == 3.2 14:07:17 INFO - moznetwork == 0.27 14:07:17 INFO - mozprocess == 0.22 14:07:17 INFO - mozprofile == 0.28 14:07:17 INFO - mozrunner == 6.11 14:07:17 INFO - mozscreenshot == 0.1 14:07:17 INFO - mozsystemmonitor == 0.3 14:07:17 INFO - moztest == 0.7 14:07:17 INFO - mozversion == 1.4 14:07:17 INFO - psutil == 3.1.1 14:07:17 INFO - requests == 1.2.3 14:07:17 INFO - wsgiref == 0.1.2 14:07:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:17 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 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:17 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 14:07:17 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 14:07:17 INFO - Using env: {'DISPLAY': ':0', 14:07:17 INFO - 'HOME': '/home/cltbld', 14:07:17 INFO - 'LANG': 'en_US.UTF-8', 14:07:17 INFO - 'LANGUAGE': 'en_US:en', 14:07:17 INFO - 'LOGNAME': 'cltbld', 14:07:17 INFO - 'MAIL': '/var/mail/cltbld', 14:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:17 INFO - 'MOZ_NO_REMOTE': '1', 14:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:17 INFO - 'NO_EM_RESTART': '1', 14:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:17 INFO - 'PWD': '/builds/slave/test', 14:07:17 INFO - 'SHELL': '/bin/bash', 14:07:17 INFO - 'SHLVL': '1', 14:07:17 INFO - 'TERM': 'linux', 14:07:17 INFO - 'TMOUT': '86400', 14:07:17 INFO - 'USER': 'cltbld', 14:07:17 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:17 INFO - '_': '/tools/buildbot/bin/python'} 14:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 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)) 14:07:17 INFO - Cleaning up... 14:07:17 INFO - Return code: 0 14:07:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:07:17 INFO - Using env: {'DISPLAY': ':0', 14:07:17 INFO - 'HOME': '/home/cltbld', 14:07:17 INFO - 'LANG': 'en_US.UTF-8', 14:07:17 INFO - 'LANGUAGE': 'en_US:en', 14:07:17 INFO - 'LOGNAME': 'cltbld', 14:07:17 INFO - 'MAIL': '/var/mail/cltbld', 14:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:17 INFO - 'MOZ_NO_REMOTE': '1', 14:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:17 INFO - 'NO_EM_RESTART': '1', 14:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:17 INFO - 'PWD': '/builds/slave/test', 14:07:17 INFO - 'SHELL': '/bin/bash', 14:07:17 INFO - 'SHLVL': '1', 14:07:17 INFO - 'TERM': 'linux', 14:07:17 INFO - 'TMOUT': '86400', 14:07:17 INFO - 'USER': 'cltbld', 14:07:17 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:17 INFO - '_': '/tools/buildbot/bin/python'} 14:07:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:18 INFO - Downloading/unpacking jsonschema 14:07:18 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 14:07:18 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 14:07:18 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 14:07:18 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 14:07:18 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 14:07:18 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 14:07:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:07:20 INFO - Downloading/unpacking functools32 (from jsonschema) 14:07:20 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 14:07:20 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 14:07:20 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 14:07:20 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 14:07:20 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 14:07:20 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 14:07:20 INFO - Downloading functools32-3.2.3-2.tar.gz 14:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:07:20 INFO - warning: no files found matching '*.txt' 14:07:20 INFO - no previously-included directories found matching 'build' 14:07:20 INFO - no previously-included directories found matching 'dist' 14:07:20 INFO - no previously-included directories found matching '.git*' 14:07:20 INFO - Installing collected packages: jsonschema, functools32 14:07:20 INFO - Running setup.py install for functools32 14:07:20 INFO - warning: no files found matching '*.txt' 14:07:20 INFO - no previously-included directories found matching 'build' 14:07:20 INFO - no previously-included directories found matching 'dist' 14:07:20 INFO - no previously-included directories found matching '.git*' 14:07:20 INFO - Successfully installed jsonschema functools32 14:07:20 INFO - Cleaning up... 14:07:20 INFO - Return code: 0 14:07:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:07: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')]} 14:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07: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')]} 14:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:20 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 0x7fbc10d01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2054770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, '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': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', '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 14:07:20 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 14:07:20 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 14:07:20 INFO - Using env: {'DISPLAY': ':0', 14:07:20 INFO - 'HOME': '/home/cltbld', 14:07:20 INFO - 'LANG': 'en_US.UTF-8', 14:07:20 INFO - 'LANGUAGE': 'en_US:en', 14:07:20 INFO - 'LOGNAME': 'cltbld', 14:07:20 INFO - 'MAIL': '/var/mail/cltbld', 14:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:20 INFO - 'MOZ_NO_REMOTE': '1', 14:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:20 INFO - 'NO_EM_RESTART': '1', 14:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:20 INFO - 'PWD': '/builds/slave/test', 14:07:20 INFO - 'SHELL': '/bin/bash', 14:07:20 INFO - 'SHLVL': '1', 14:07:20 INFO - 'TERM': 'linux', 14:07:20 INFO - 'TMOUT': '86400', 14:07:20 INFO - 'USER': 'cltbld', 14:07:20 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:20 INFO - '_': '/tools/buildbot/bin/python'} 14:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:20 INFO - Downloading/unpacking flake8 14:07:20 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 14:07:20 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 14:07:20 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 14:07:20 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 14:07:20 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 14:07:20 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 14:07:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:07:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:07:22 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 14:07:22 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 14:07:22 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 14:07:22 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 14:07:22 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 14:07:22 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 14:07:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:07:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:07:22 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 14:07:22 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 14:07:22 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 14:07:22 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 14:07:22 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 14:07:22 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 14:07:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:07:23 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 14:07:23 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 14:07:23 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 14:07:23 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 14:07:23 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 14:07:23 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 14:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:07:23 INFO - no previously-included directories found matching 'docs/_build' 14:07:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:07:23 INFO - Running setup.py install for pep8 14:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:07:23 INFO - no previously-included directories found matching 'docs/_build' 14:07:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:07:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:07:23 INFO - Cleaning up... 14:07:23 INFO - Return code: 0 14:07:23 INFO - Running post-action listener: _resource_record_post_action 14:07:23 INFO - Running post-action listener: _start_resource_monitoring 14:07:23 INFO - Starting resource monitoring. 14:07:23 INFO - ##### 14:07:23 INFO - ##### Finished create-virtualenv step (success) 14:07:23 INFO - ##### 14:07:23 INFO - ##### 14:07:23 INFO - ##### Running install step. 14:07:23 INFO - ##### 14:07:23 INFO - Running pre-action listener: _resource_record_pre_action 14:07:23 INFO - Running main action method: install 14:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:23 INFO - Reading from file tmpfile_stdout 14:07:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:07:23 INFO - Reading from file tmpfile_stdout 14:07:23 INFO - Output received: 14:07:23 INFO - Usage: mozinstall [options] installer 14:07:23 INFO - Options: 14:07:23 INFO - -h, --help show this help message and exit 14:07:23 INFO - -d DEST, --destination=DEST 14:07:23 INFO - Directory to install application into. [default: 14:07:23 INFO - "/builds/slave/test"] 14:07:23 INFO - --app=APP Application being installed. [default: firefox] 14:07:23 INFO - mkdir: /builds/slave/test/build/application 14:07:23 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'] 14:07:23 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 14:07:40 INFO - Reading from file tmpfile_stdout 14:07:40 INFO - Output received: 14:07:40 INFO - /builds/slave/test/build/application/firefox/firefox 14:07:40 INFO - Running post-action listener: _resource_record_post_action 14:07:40 INFO - ##### 14:07:40 INFO - ##### Finished install step (success) 14:07:40 INFO - ##### 14:07:40 INFO - ##### 14:07:40 INFO - ##### Running run-tests step. 14:07:40 INFO - ##### 14:07:40 INFO - Running pre-action listener: _resource_record_pre_action 14:07:40 INFO - Running main action method: run_tests 14:07:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:07:40 INFO - Python 2.7.3 14:07:40 INFO - Return code: 0 14:07:40 INFO - grabbing minidump binary from tooltool 14:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:40 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 0x20e39c0>, '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 0x20e48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e4d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:07:40 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 14:07:40 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 14:07:40 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 14:07:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:07:40 INFO - Return code: 0 14:07:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:07:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:07:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:07:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:07:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:07:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:07:41 INFO - Return code: 0 14:07:41 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/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 14:07:41 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/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --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 14:07:41 INFO - Using env: {'DISPLAY': ':0', 14:07:41 INFO - 'HOME': '/home/cltbld', 14:07:41 INFO - 'LANG': 'en_US.UTF-8', 14:07:41 INFO - 'LANGUAGE': 'en_US:en', 14:07:41 INFO - 'LOGNAME': 'cltbld', 14:07:41 INFO - 'MAIL': '/var/mail/cltbld', 14:07:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:07:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:07:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:41 INFO - 'MOZ_NO_REMOTE': '1', 14:07:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:07:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:41 INFO - 'NO_EM_RESTART': '1', 14:07:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:41 INFO - 'PWD': '/builds/slave/test', 14:07:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:07:41 INFO - 'SHELL': '/bin/bash', 14:07:41 INFO - 'SHLVL': '1', 14:07:41 INFO - 'TERM': 'linux', 14:07:41 INFO - 'TMOUT': '86400', 14:07:41 INFO - 'USER': 'cltbld', 14:07:41 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044', 14:07:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:41 INFO - '_': '/tools/buildbot/bin/python'} 14:07:41 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/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 14:07:41 INFO - mozversion application_buildid: 20160527113050 14:07:41 INFO - mozversion application_changeset: eec76f75c79a0a597131357ec1eed49683183c30 14:07:41 INFO - mozversion application_display_name: Nightly 14:07:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:07:41 INFO - mozversion application_name: Firefox 14:07:41 INFO - mozversion application_remotingname: firefox 14:07:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:07:41 INFO - mozversion application_vendor: Mozilla 14:07:41 INFO - mozversion application_version: 49.0a1 14:07:41 INFO - mozversion platform_buildid: 20160527113050 14:07:41 INFO - mozversion platform_changeset: eec76f75c79a0a597131357ec1eed49683183c30 14:07:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:07:41 INFO - mozversion platform_version: 49.0a1 14:07:41 INFO - using testdate: 1464383261 14:07:41 INFO - actual date: 1464383261 14:07:41 INFO - starting webserver on 'localhost:32787' 14:07:41 INFO - SUITE-START | Running 2 tests 14:07:41 INFO - TEST-START | dromaeo_css 14:07:41 INFO - operating with platform_type : linux_ 14:07:41 INFO - Initialising browser for dromaeo_css test... 14:07:41 INFO - TEST-INFO | started process 811 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkDdMAk/profile http://localhost:32787/getInfo.html) 14:07:46 INFO - PROCESS | 811 | __metrics Screen width/height:1600/1200 14:07:46 INFO - PROCESS | 811 | colorDepth:24 14:07:46 INFO - PROCESS | 811 | Browser inner width/height: 1024/697 14:07:46 INFO - PROCESS | 811 | __metrics 14:07:46 ERROR - PROCESS | 811 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:07:47 INFO - TEST-INFO | 811: exit 0 14:07:47 INFO - Browser initialized. 14:07:47 INFO - Running cycle 1/1 for dromaeo_css test... 14:07:47 INFO - TEST-INFO | started process 898 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkDdMAk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:07:59 INFO - PROCESS | 898 | RSS: Main: 156028928 14:07:59 INFO - PROCESS | 898 | 14:08:51 INFO - PROCESS | 898 | 1464383331067 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] 14:10:58 INFO - PROCESS | 898 | Cycle 1(1): loaded http://localhost:32787/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32787/tests/dromaeo/cssquery-ext.html) 14:11:00 INFO - PROCESS | 898 | RSS: Main: 275726336 14:11:00 INFO - PROCESS | 898 | 14:14:02 INFO - PROCESS | 898 | Cycle 1(1): loaded http://localhost:32787/tests/dromaeo/cssquery-ext.html (next: http://localhost:32787/tests/dromaeo/cssquery-jquery.html) 14:14:03 INFO - PROCESS | 898 | RSS: Main: 246697984 14:14:03 INFO - PROCESS | 898 | 14:17:05 INFO - PROCESS | 898 | Cycle 1(1): loaded http://localhost:32787/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32787/tests/dromaeo/cssquery-mootools.html) 14:17:06 INFO - PROCESS | 898 | RSS: Main: 247046144 14:17:06 INFO - PROCESS | 898 | 14:20:08 INFO - PROCESS | 898 | Cycle 1(1): loaded http://localhost:32787/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32787/tests/dromaeo/cssquery-prototype.html) 14:20:08 INFO - PROCESS | 898 | RSS: Main: 243961856 14:20:08 INFO - PROCESS | 898 | 14:23:11 INFO - PROCESS | 898 | Cycle 1(1): loaded http://localhost:32787/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32787/tests/dromaeo/cssquery-yui.html) 14:23:11 INFO - PROCESS | 898 | RSS: Main: 217845760 14:23:11 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | Cycle 1(1): loaded http://localhost:32787/tests/dromaeo/cssquery-yui.html (next: http://localhost:32787/tests/dromaeo/cssquery-dojo.html) 14:26:14 INFO - PROCESS | 898 | RSS: Main: 283701248 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | __start_tp_report 14:26:14 INFO - PROCESS | 898 | _x_x_mozilla_page_load 14:26:14 INFO - PROCESS | 898 | _x_x_mozilla_page_load_details 14:26:14 INFO - PROCESS | 898 | |i|pagename|runs| 14:26:14 INFO - PROCESS | 898 | |0;dojo.html;3136.294333774901;3141.073383351911;3168.6514483406827;3188.8246146461934;3205.8557364918574;5318.282031925689;5401.081816091264;5415.296011518507;5438.918416223755;5472.20653005314;5404.91892621611;5414.620976531644;5425.538829199513;5544.334679838415;5554.1391084381985;4901.705897646144;4918.877028074291;4921.975390123044;5005.6746311489815;5050.040492306468;4962.255661650764;5065.518775716301;5147.150720131192;5156.92264616031;5159.587233021368;4880.780364883589;4920.040592084509;4980.900381992376;5001.524855138756;5030.0694371541385;4946.35697359341;4953.752312384367;4975.228839529878;4977.7511124444;4982.701037937699;4938.827141050082;4940.184869496528;4943.9258411123865;4958.925616115761;5018.698878067291;5493.478119578634;5512.779488820443;5566.053770858964;5567.192757050205;5580.525655319303;5665.12190610456;5877.85305367365;5921.61509501881;5939.970300148515;5990.341062483167;5944.583879128462;5983.58114931955;5985.610935289235;5998.040313549855;5999.370066143036;5508.173773933942;5560.582956278296;5561.499465048165;5561.916571251434;5569.637973531748;5862;5954.612950158313;5979.850503737354;6002.879942401128;6058.379666401924;5811.15738217956;5862.794802181903;5866.6480011199465;5866.911996320058;5871.031279838864;5912.822615321547;5916;5959.791407300723;5962.61243019211;5963.135345374983;5682.460166284279;5715.31416230055;5717.571182161355;5754.913676294859;5855.765769369263;5730.684812335278;5787.479126878517;5790.536757059509;5804.6807425592;5807.970960145257;4338;4349.456317960255;4351.6518678505645;4351.956480435155;4361.084172323847;3846.442265871433;3849.711271654581;3861.4787003754695;3882.0683036071705;3906.5312162540677;5476.17857321405;5507.476789704971;5512.255845460892;5514.09017512114;5559.722013899289;5730.770769169187;5810.88378232433;5812.331581868055;5815.505682016991;5873.853153671193;5544.279243698374;5576.888462230732;5584.91622625661;5587.245721827582;5611.186377975171;5035.244713292962;5036.294918711384;5039.143345631252;5045.974770126126;5088.77100530477;5907.232059832194;6010.669413182317;6126.387361263838;6142.846428839315;6148.600340977911;5861.208736820475;5938.346781854079;5941.851453713692;5946.940530594812;5960.016597261403;6040.184575082308;6056.1824153740445;6057.515398768;6058.4244496772735;6137.048757442603;5662.178984047372;5706.857328566819;5742.856428589319;5815.534757219497;5826.970865145647;5899.321578018411;5914.201582786441;5916.881662366814;5922;5950.285965684146;5705.087186050212;5746.942530574641;5763.510101641407;5772.5959182856795;5787.797427090031;5525.005499010217;5702.230198923092;5747.942520574908;5749.310082790092;5752.9137062943255;5683.346415162309;5712;5713.028785106459;5718.942810571841;5719.628224165416;5842.941570584239;5855.502282306052;5893.410658934073;5899.115132730125;5907.261592300963;5515.806946756844;5516.972415137899;5614.101743720985;5628.212050313042;5641.4358564144895 14:26:14 INFO - PROCESS | 898 | |1;ext.html;587.623920690758;631.1794666932988;634.4290138875012;648.7632014314771;650.6258901131849;34901;45064.324035139456;46048;46114.999999999956;49647.751761241234;47716;48148;48152.51847481524;48169.7591512042;48282.517174828245;8130.0243970723595;8962.551872406386;9163.404378715395;9165.54172291386;9168.220701240403;10635.149188064976;10946.39794811285;10950.835737463944;11334.263272887278;11336.773264534704;10993.230473866832;11127.165462590337;11130;11136.220464567483;11140.053095486894;8227.341812654973;10833.295835770728;10982.890171098266;11053.226274160812;11119.054880335181;10493.68828896388;10945.945270273678;10979.725506862313;11044.668659940215;11057.668269951915;43057.56942430567;43718.12563748707;43839;43880.56119438797;44394.11211775746;3492.877749278763;3571.232185080194;3614.710823134143;3616;3651.488791569156;33275.33449331024;33511.32977340464;33823.83088084544;33993.6600633993;34050.65949340524;3739.813009349549;3791.545014598238;3855.3445914194654;3906.4140378943102;3913.706472014582;386812;388020;389229;389278.05360973295;391494;67048.6647566759;67623;67656.6617166911;67658;67700.66149669318;70504;71855.64072179605;71925;72090.27909720941;72111.27888721046;17318;17666;17676.381326653507;17679.87841459033;17684.911575442296;11860.347680877461;13147.802782958262;13419.463221471227;13423.597292081253;13446;20626.58746825055;20633.277835275992;20703.171873124906;20746.48133796637;20777;6379.330170332141;7272.182104395235;7294.8905766413545;7298.854022919512;7321.926780732231;1024.6823484719762;1043.7860238651058;1107.8670559532748;1159.2580748321082;1217.817327400897;30985.380292394475;32977;33551.49672754911;33593.32813343719;33619.3276134476;1113.432149603708;1126.487448211062;1148.4487446025787;1161.3032180691687;1233.5127624588238;16816.915915420588;17237.827621723623;17310.653786924442;17411;17418.12909354527;589428;594649;594841;594846;597220;60789.392106079256;62816;62944.68527657333;62968.68515657393;63050.36949630445;1289.9097063205486;1308.2739079810606;1322.9338533073114;1393.3869097597026;1406.1984668738721;25529.87235063813;33369.499457508646;34822;36248.27503449969;36413.63586364102;57114.7144264276;59110;59636.403635963085;59645.701771490865;64655.67672161609;415467;417304;420724;423427;424170;38322.808385959004;43413.348799767395;44513.332300016176;44600.10799784051;45014.549854502344;50492.74753626208;51374;51584.48415515797;52044.21933671074;52372.21441678454;41141.382879257435;42538;42706.359404609575;43449;44213.55786442223;42914.57085429106;43028.56971430246;43102.78448607737;43300.56699433091;44174.55825441705;43368.78315608402;43702.56297436985;43899.56100438955;44481;44674.55325446704;35887;37696.246075078896;37911.81044094762;38261.808690956364;38904;28710.282242944093;28718.856405718674;28824.567631485963;28869.27826804347;29143.708562914948 14:26:14 INFO - PROCESS | 898 | |2;jquery.html;370.9629037096291;373.723444650958;396.84919730502395;400.19360987609963;416.0991453503166;18161.909190454047;19666.70499942502;20634.484137896543;20636;20661.69007464887;18546.907265463688;18812.15345309461;18984.715229271573;19254.903725481352;19307.131179096938;3664.0839790052487;5500.28496295483;5620.9437905620825;5658.971705141468;5817.331006934193;3783.1298801275725;3844.788536630484;3850.4994350734364;7062.470314726416;7359.116905971264;7362.263773622649;7392.55644661322;7483.925160748405;7486.775396738107;7567.508111972732;7273.236310187407;7385.372243359321;7504.662290196954;7538.660760265801;7551.69793208271;7038.964805175992;7151.034610327643;7227;7249.601271930042;7252.3835473984245;25966;26223.606645900327;26476.073337433096;27012.189634311002;27341.863290683617;2534.873256337176;2584.715681275058;2585.5216784894856;2611.673540807399;2744.464829358276;15357.385704571803;15905.363785448568;16002.19989000557;16124.75812862808;16797;2044.2436298569476;2064.9984757392654;2186.464316242531;2243.876586787726;2423.3578101803037;80729;81717.59141204316;82152;93664;94472.52763736206;9171.908280917105;9724.854127188099;9806.411615303105;9990;10109.443980581138;13260.469581216836;13291.534796282083;13340.799888001688;13441.865581344256;13451.865481345061;7726.7681969541;7874.842503149905;7994.2405471480115;8074.192580741878;8134.471259368122;8001.279884810395;8047.839043219103;8051.154628764012;8081.515109093473;8109;3826.1582451860545;3878.84484620612;3954.1696243789124;4132.070284186034;4320.567943205654;1815.7161525753825;1903.961920761577;2010.5576773109892;2033.2781862438917;2068.204844111073;923.2198792020732;1024.5748014574017;1037.9792404151874;1095.7041598768287;1131.0386171753944;18605.90697046506;18866.434006979813;18929.148188331557;19473.123709432824;19839.801601984083;1015.4871789746131;1032.8554002439664;1064.0476773287864;1073.6456969200144;1082.1396989393452;10380.584776609003;10999.450027498593;11072.833907491395;11087.55649774;11095.778084438267;474897;483996;487456;491969;495638;84230.5788471066;86733.5663321692;91623.54188229016;95657.521712391;96928.82174985205;1037.7301901505664;1067.2529229539352;1070.464767616192;1074.8925107489345;1079.4386918802336;17292.827071729123;17295.30818767235;17676.558086047437;17700.91149544244;17948.910255449162;41857;41952;43147;44266.11467770691;44656.10687786161;59546.70226648839;60597;60740;60906.69546652238;62486.68756656188;7794.181610930771;7979.202079791974;8215;8244.752657420288;8493;9578.521073946415;12434.067444941486;12655.493780249055;12992.935035324763;13142.868571314164;11007.449627518432;11344.319340839576;11482.138839586898;11549.769004619693;11616;11087.22389432732;11205.159613029218;11464.140189485988;11518.136139789716;11627.418629068681;11006.559737610405;11429.371384573928;11444.82832757526;11546.538138474367;11727.120465965258;7925.643346049326;7969.840603187788;8001.519908805491;8116.83766324682;8133.511989280662;8668.436551624125;10075.899241007495;10295.691129266133;10320.896791031993;10358.896411035794 14:26:14 INFO - PROCESS | 898 | |3;mootools.html;1323.047405867776;1527.8624923756859;1543.020182184313;1683.9915800420997;1835.8806677565958;8450.028246751617;8806.955965220173;8814.4711317321;9087.500187489686;9106.590203440845;8840.557972101402;9033.412828166181;9039.774005649862;9055.185033346997;9064.773380665487;2016.5261163626535;2260.446190683285;2339.0526836631193;2376.595978683624;2405.518896220754;2393.0667039854384;2402.423418379585;2402.627592723131;2403.639454081884;2409.4458274596764;2370.644403339504;2373.264288070695;2403.9158629447975;2404.7475015123423;2407.2296865003204;1998.310582848922;2189.5730332585154;2234.2961966980347;2269.727632684072;2276.5885151206194;2248.921287754937;2266.5353602511445;2271.7955384015354;2287.7025986621634;2290.770922907696;8919.687810926587;8928.24109950655;8978.640854365818;8988.910110898938;9001.23489503393;5165.74171291434;5298.867528311785;5299.152135658276;5304.681719096867;5305.071612467841;3070.416620842033;3266.1181481000235;3282.78661886979;3308.123347312964;3348.6483919188377;1018.6689325969089;1045.6862941117633;1054.2462139570168;1107.4086637268829;1108.3959242213014;12688.111832171751;12703.745925081446;12721.04592155592;12760.872391276062;12817.42321595521;4809.134355815917;4823.831165909176;4890.413150421972;4894.657373983858;4935.333729946483;10698.304610200312;10714.946425267824;10724.195685323475;10786.73033174177;10794.568217271379;5392.218128371364;5392.40683524812;5396.19057141424;5405;5429.56563474921;4359.956400436018;4661.184292748755;4762.166620841339;4783.760811959388;4844.32179494871;4034.8873124120746;4044.8786536403954;4084.448599439097;4085.8569950051606;4110.979445102816;1244.4337826289197;1263.0148484182353;1267.6260503151593;1278.7122897348022;1281.8718128187106;811.2009670474578;949.7720547068653;1027.5786927359748;1032.7418145463635;1050.8791488978713;6292.685365731695;6366.554341196072;6393.168888044617;6470.805875823733;6546.8363290918105;457.90612924350427;473.1648640150138;1043.9269251152498;1122.5004872831676;1154.3131836557236;4206.915861682749;4262.190183865056;4398.252297109435;4425.557444255549;4471.284594464935;10943.83584246237;11253.943730281297;11312.264702794291;11327;11367.886321136682;11103.333799972028;11205.159613029055;11238.550457981753;11310.773784524428;11357.886421135683;8212.876806847782;8251.174882511701;8339.666413343399;8432.662693492315;8448.831023379498;5956.970215148896;5983.850403739942;6075.605085669417;6156.107364432043;6196.597221180609;9959.502024898871;10224.59101635956;10253.74365640865;10289.073983341535;10573.735656608646;10407.791844162923;10512.684619461428;10533.683989480327;10598.947005264925;10611.734706632396;6841.589504629738;7358.96320518394;7485.737999170003;7582.962085189539;7613.01030866006;8762.824743505222;8824.64701411962;8836.425632333878;8895.822083558163;8900.688475903313;7688.961555192188;8023.318017968538;8033.236842499953;8039.196080392148;8103.108658047492;7736.961315193388;7885.2114788520685;7888.092869319872;7947.125816160102;7992.320652744582;7006.614636195059;7196.712131514681;7283.781486555412;7285.963570182115;7295.562266264041;5382.596305277199;5391.865203369947;5439.646422982493;5449;5574.275344205227;5429;5542.584306177134;5588.748506317307;5747.371025609304;5749.597528172988 14:26:14 INFO - PROCESS | 898 | |4;prototype.html;313.21695760598504;317.8347259425099;323.8429361759547;324.56832412890856;325.88756878876796;5347.278117454142;5367.731613419323;5373.731313434322;5391.73041347933;5411.512963833259;5262.105442074847;5278.9208161877605;5289.814856480024;5311;5347.304850369446;4032.6169013943627;4143.233501802161;4160.355144952538;4162.292410290258;4176.164767046587;4107.240160570289;4118.176364727066;4132.607402296777;4154.6676265898805;4165.58344165584;4014.4781178446906;4060.309747342944;4066.593340665939;4094.222097801423;4139.606737359946;3897.9805100974595;3947.2894878921784;4057.9391309130388;4127.772972486512;4146.983988922718;3594.982025089858;3605.296967091418;3612.151144481045;3612.8916132516065;3615.349237137314;5108.591312694975;5129.461406552333;5138.922916156261;5163.1480805667015;5163.173892177234;4382.824687012516;4495.707778994355;4643.651726120521;4654.697444666086;4664.696794708333;5427.48438898304;5500.147477141009;5515.448455154493;5528.75120619573;5532.28080349556;3859.26673931952;3881.786501742403;3883.805809709503;3900.8829735107993;3901.356276214421;5390.146045577392;5696.316442026942;5729.398413166599;5754.798582049608;5781.248437703114;3808.3906574947873;3845.3270677631863;3866.9806650966566;3886.0867696091395;3906.687465002793;3971.225611005884;3972.900677483086;3973.8807835764974;3975;3984.6812255019527;4507.233770259064;4601.12578610063;4628.5371462853445;4637.449402828431;4643.582077613031;5594.608377413623;5605.86976948765;5611.663300202001;5612.1581762735095;5631.605787594829;1376.0986553807272;1376.8416632087437;1388.9930550347385;1394.9163050217019;1397.7623803953352;798.4730078148394;845.7778510053038;862.3618522293458;862.4566523090539;864.9870251946226;4747.454042785055;4753.857384278477;4774.3077253798;4802.711837289774;4819.819144309597;5022.447530771612;5042.193249080203;5054.4692807254705;5081.898362032739;5082;4597.540245975442;4838.475880097758;4838.95161048385;4856.465788763233;4861.9027619447415;2673.5187666220268;2692.3134600676703;2696.382170697605;2709.444563864403;2715.823471474348;5118.38579370478;5141.5886729061585;5160.303359046481;5189.495046436491;5203.230901493511;4932.260160975882;5073.847784566469;5114.590832733373;5181.222816577543;5238.345206849144;5257.6056795739705;5269.262303277467;5294.3117394737665;5305.522502974673;5305.575553955674;4868.732219727807;5107.978404319077;5115;5135.486451354835;5149.193689439189;4752.596029337545;5027.622928280321;5054.620903432184;5108.540231379194;5119.206522988943;5118.314813334393;5145.681035732006;5182.948170518246;5256.579473642176;5270.341207349082;3605.1167463971497;3625.6585063526663;3632.3643362410944;3633.8909832705062;3639.1993761372455;4736.502667219892;5121;5131.79472821083;5136.97431512855;5146;5001.399832020181;5018.297438358706;5022.5434623958645;5023.463151108666;5047.899042019212;4854.878628034327;4893.951060489349;4976.402831660224;5010.949890501194;5014.52362025607;4861.416630004422;4903.656744027884;5108.9233661495855;5584.413636568264;5602.59935016246;5475.589330800286;5512.944870551403;5653.236813030188;5658.122990936411;5671.376148623731;5491.697956612425;5508.421615730291;5523.7238138093735;5526.640768350045;5538 14:26:14 INFO - PROCESS | 898 | |5;yui.html;761.4061032394734;783.6316931042408;784.7763387434583;797.5493845977022;838.9286910612595;14514.63713407164;15340.616484587863;15353.15557644329;15385.692286154284;15405;13764.518241861537;14469.638259043502;14494.927525362385;14502.92748536256;14529.128252304843;680.1905732178692;798.7603718884341;823.0617096509983;833.9249467547919;836.4186890111375;807.6002378822491;814.1370147643516;814.6008455856647;814.7067055859885;816.8121332093622;863.7926897544606;866.774638593967;867.0462491259595;868.7524055644143;868.891388576428;699.3391245273219;816.603947085663;852.6291063387446;853.4793775796759;865.8008658008662;624.3662682377391;624.9125122482856;624.9968750156265;625.0124802811547;625.6308777821062;14403.927980360031;14410.783838242434;14440.783388249185;14469.20419376934;14477.203753793538;13435.529756458574;13694.178349298974;13702.588922332347;13988;14233.572992810232;11896.345700986441;11928.46321915516;11941.462634181482;12131.818022729665;12191.939040304742;4414.735115893057;4421.226285400042;4474.865754027384;4486.932696009562;4500.09998000398;55889;56821;57100.42899571034;57157.714211428676;57308;11088.22382433238;11133.888661113448;11211.831822522669;11311;11491.195616306946;20218;20293.492662683257;20351.084201210982;20386.28647997313;20395.082221300076;7767.650455729506;7800.804979875548;7909.762707118795;7966.681332746741;8446.366522510836;4508.774561271924;4516.932246016312;4604.263317869125;4677.251639737626;4809.374781278481;3365;3456.42968910127;3457.8789742358895;3459.584849818038;3459.6680278093118;661.7088481068411;702.424012309901;704.7075463682514;710.4068103133837;712.4692104382165;733.6368497593726;769.9076110866732;775.2983549887362;780.157429975625;789.3763926498002;9723.708288751348;9731.336753768433;9844.950775246078;9845.310828242098;9897.35667181631;646.3278190681639;726.5096060159372;751.612919346537;775.4804281131655;779.6062988190927;4704.767604229046;4882.609391248691;4921.44142886353;4921.827736029293;5000.824971125993;74840.62579687066;76334;76340.61829690928;76500.61749691216;76842;66305;66392;66432.33567664358;66543.66728166425;67470.66264668645;60561.69719151376;60892.086618700756;61078.0838287426;61745;62230.37769622246;8847.823043538965;9368.67209647659;9427.905720942703;9546.078685001394;9709.563069662023;36226.8188659055;36621.81689091644;36777.448338274415;36821.63178368182;38499.807500963434;50493.49506505035;50638.746806265735;51216.74391628167;51226.487735123665;51814;11293.43532823372;11299.548018079513;11478.483468244116;11525.88474115248;11841.28952262867;12174.330411827434;12455.314957677414;12504.812427813407;12627.242365458102;12858.807117893426;11788.174827761974;11986.280823150639;12308.446119924445;12620.621381358575;12710.555130570385;11496.597619083292;11738.594819975906;11886.227395219283;12196.390180491117;12350.505979760708;11874.58438954667;12232.388380581113;12552.686182845502;12557.811632825698;12602.369881505705;10240.030202475726;10244.500354815795;10296.382217067;10442.164626829683;10642.680719578426;9458.527073646152;9950;10154.289199755945;10170.389776613427;10262.794744104927 14:26:14 INFO - PROCESS | 898 | __end_tp_report 14:26:14 INFO - PROCESS | 898 | __start_cc_report 14:26:14 INFO - PROCESS | 898 | _x_x_mozilla_cycle_collect,1068 14:26:14 INFO - PROCESS | 898 | __end_cc_report 14:26:14 INFO - PROCESS | 898 | __startTimestamp1464384374912__endTimestamp 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | ------- Summary: start ------- 14:26:14 INFO - PROCESS | 898 | Number of tests: 6 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | [#0] dojo.html Cycles:165 Average:5448.01 Median:5665.12 stddev:640.95 (11.3%) stddev-sans-first:616.72 14:26:14 INFO - PROCESS | 898 | Values: 3136.3 3141.1 3168.7 3188.8 3205.9 5318.3 5401.1 5415.3 5438.9 5472.2 5404.9 5414.6 5425.5 5544.3 5554.1 4901.7 4918.9 4922.0 5005.7 5050.0 4962.3 5065.5 5147.2 5156.9 5159.6 4880.8 4920.0 4980.9 5001.5 5030.1 4946.4 4953.8 4975.2 4977.8 4982.7 4938.8 4940.2 4943.9 4958.9 5018.7 5493.5 5512.8 5566.1 5567.2 5580.5 5665.1 5877.9 5921.6 5940.0 5990.3 5944.6 5983.6 5985.6 5998.0 5999.4 5508.2 5560.6 5561.5 5561.9 5569.6 5862.0 5954.6 5979.9 6002.9 6058.4 5811.2 5862.8 5866.6 5866.9 5871.0 5912.8 5916.0 5959.8 5962.6 5963.1 5682.5 5715.3 5717.6 5754.9 5855.8 5730.7 5787.5 5790.5 5804.7 5808.0 4338.0 4349.5 4351.7 4352.0 4361.1 3846.4 3849.7 3861.5 3882.1 3906.5 5476.2 5507.5 5512.3 5514.1 5559.7 5730.8 5810.9 5812.3 5815.5 5873.9 5544.3 5576.9 5584.9 5587.2 5611.2 5035.2 5036.3 5039.1 5046.0 5088.8 5907.2 6010.7 6126.4 6142.8 6148.6 5861.2 5938.3 5941.9 5946.9 5960.0 6040.2 6056.2 6057.5 6058.4 6137.0 5662.2 5706.9 5742.9 5815.5 5827.0 5899.3 5914.2 5916.9 5922.0 5950.3 5705.1 5746.9 5763.5 5772.6 5787.8 5525.0 5702.2 5747.9 5749.3 5752.9 5683.3 5712.0 5713.0 5718.9 5719.6 5842.9 5855.5 5893.4 5899.1 5907.3 5515.8 5517.0 5614.1 5628.2 5641.4 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | [#1] ext.html Cycles:180 Average:64794.77 Median:214066.50 stddev:126280.88 (59.0%) stddev-sans-first:126543.12 14:26:14 INFO - PROCESS | 898 | Values: 587.6 631.2 634.4 648.8 650.6 34901.0 45064.3 46048.0 46115.0 49647.8 47716.0 48148.0 48152.5 48169.8 48282.5 8130.0 8962.6 9163.4 9165.5 9168.2 10635.1 10946.4 10950.8 11334.3 11336.8 10993.2 11127.2 11130.0 11136.2 11140.1 8227.3 10833.3 10982.9 11053.2 11119.1 10493.7 10945.9 10979.7 11044.7 11057.7 43057.6 43718.1 43839.0 43880.6 44394.1 3492.9 3571.2 3614.7 3616.0 3651.5 33275.3 33511.3 33823.8 33993.7 34050.7 3739.8 3791.5 3855.3 3906.4 3913.7 386812.0 388020.0 389229.0 389278.1 391494.0 67048.7 67623.0 67656.7 67658.0 67700.7 70504.0 71855.6 71925.0 72090.3 72111.3 17318.0 17666.0 17676.4 17679.9 17684.9 11860.3 13147.8 13419.5 13423.6 13446.0 20626.6 20633.3 20703.2 20746.5 20777.0 6379.3 7272.2 7294.9 7298.9 7321.9 1024.7 1043.8 1107.9 1159.3 1217.8 30985.4 32977.0 33551.5 33593.3 33619.3 1113.4 1126.5 1148.4 1161.3 1233.5 16816.9 17237.8 17310.7 17411.0 17418.1 589428.0 594649.0 594841.0 594846.0 597220.0 60789.4 62816.0 62944.7 62968.7 63050.4 1289.9 1308.3 1322.9 1393.4 1406.2 25529.9 33369.5 34822.0 36248.3 36413.6 57114.7 59110.0 59636.4 59645.7 64655.7 415467.0 417304.0 420724.0 423427.0 424170.0 38322.8 43413.3 44513.3 44600.1 45014.5 50492.7 51374.0 51584.5 52044.2 52372.2 41141.4 42538.0 42706.4 43449.0 44213.6 42914.6 43028.6 43102.8 43300.6 44174.6 43368.8 43702.6 43899.6 44481.0 44674.6 35887.0 37696.2 37911.8 38261.8 38904.0 28710.3 28718.9 28824.6 28869.3 29143.7 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | [#2] jquery.html Cycles:180 Average:29337.00 Median:27177.03 stddev:80395.46 (295.8%) stddev-sans-first:80591.57 14:26:14 INFO - PROCESS | 898 | Values: 371.0 373.7 396.8 400.2 416.1 18161.9 19666.7 20634.5 20636.0 20661.7 18546.9 18812.2 18984.7 19254.9 19307.1 3664.1 5500.3 5620.9 5659.0 5817.3 3783.1 3844.8 3850.5 7062.5 7359.1 7362.3 7392.6 7483.9 7486.8 7567.5 7273.2 7385.4 7504.7 7538.7 7551.7 7039.0 7151.0 7227.0 7249.6 7252.4 25966.0 26223.6 26476.1 27012.2 27341.9 2534.9 2584.7 2585.5 2611.7 2744.5 15357.4 15905.4 16002.2 16124.8 16797.0 2044.2 2065.0 2186.5 2243.9 2423.4 80729.0 81717.6 82152.0 93664.0 94472.5 9171.9 9724.9 9806.4 9990.0 10109.4 13260.5 13291.5 13340.8 13441.9 13451.9 7726.8 7874.8 7994.2 8074.2 8134.5 8001.3 8047.8 8051.2 8081.5 8109.0 3826.2 3878.8 3954.2 4132.1 4320.6 1815.7 1904.0 2010.6 2033.3 2068.2 923.2 1024.6 1038.0 1095.7 1131.0 18605.9 18866.4 18929.1 19473.1 19839.8 1015.5 1032.9 1064.0 1073.6 1082.1 10380.6 10999.5 11072.8 11087.6 11095.8 474897.0 483996.0 487456.0 491969.0 495638.0 84230.6 86733.6 91623.5 95657.5 96928.8 1037.7 1067.3 1070.5 1074.9 1079.4 17292.8 17295.3 17676.6 17700.9 17948.9 41857.0 41952.0 43147.0 44266.1 44656.1 59546.7 60597.0 60740.0 60906.7 62486.7 7794.2 7979.2 8215.0 8244.8 8493.0 9578.5 12434.1 12655.5 12992.9 13142.9 11007.4 11344.3 11482.1 11549.8 11616.0 11087.2 11205.2 11464.1 11518.1 11627.4 11006.6 11429.4 11444.8 11546.5 11727.1 7925.6 7969.8 8001.5 8116.8 8133.5 8668.4 10075.9 10295.7 10320.9 10358.9 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | [#3] mootools.html Cycles:180 Average:5943.37 Median:2464.60 stddev:3413.08 (138.5%) stddev-sans-first:3405.00 14:26:14 INFO - PROCESS | 898 | Values: 1323.0 1527.9 1543.0 1684.0 1835.9 8450.0 8807.0 8814.5 9087.5 9106.6 8840.6 9033.4 9039.8 9055.2 9064.8 2016.5 2260.4 2339.1 2376.6 2405.5 2393.1 2402.4 2402.6 2403.6 2409.4 2370.6 2373.3 2403.9 2404.7 2407.2 1998.3 2189.6 2234.3 2269.7 2276.6 2248.9 2266.5 2271.8 2287.7 2290.8 8919.7 8928.2 8978.6 8988.9 9001.2 5165.7 5298.9 5299.2 5304.7 5305.1 3070.4 3266.1 3282.8 3308.1 3348.6 1018.7 1045.7 1054.2 1107.4 1108.4 12688.1 12703.7 12721.0 12760.9 12817.4 4809.1 4823.8 4890.4 4894.7 4935.3 10698.3 10714.9 10724.2 10786.7 10794.6 5392.2 5392.4 5396.2 5405.0 5429.6 4360.0 4661.2 4762.2 4783.8 4844.3 4034.9 4044.9 4084.4 4085.9 4111.0 1244.4 1263.0 1267.6 1278.7 1281.9 811.2 949.8 1027.6 1032.7 1050.9 6292.7 6366.6 6393.2 6470.8 6546.8 457.9 473.2 1043.9 1122.5 1154.3 4206.9 4262.2 4398.3 4425.6 4471.3 10943.8 11253.9 11312.3 11327.0 11367.9 11103.3 11205.2 11238.6 11310.8 11357.9 8212.9 8251.2 8339.7 8432.7 8448.8 5957.0 5983.9 6075.6 6156.1 6196.6 9959.5 10224.6 10253.7 10289.1 10573.7 10407.8 10512.7 10533.7 10598.9 10611.7 6841.6 7359.0 7485.7 7583.0 7613.0 8762.8 8824.6 8836.4 8895.8 8900.7 7689.0 8023.3 8033.2 8039.2 8103.1 7737.0 7885.2 7888.1 7947.1 7992.3 7006.6 7196.7 7283.8 7286.0 7295.6 5382.6 5391.9 5439.6 5449.0 5574.3 5429.0 5542.6 5588.7 5747.4 5749.6 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | [#4] prototype.html Cycles:180 Average:4421.94 Median:5012.74 stddev:1283.83 (25.6%) stddev-sans-first:1249.84 14:26:14 INFO - PROCESS | 898 | Values: 313.2 317.8 323.8 324.6 325.9 5347.3 5367.7 5373.7 5391.7 5411.5 5262.1 5278.9 5289.8 5311.0 5347.3 4032.6 4143.2 4160.4 4162.3 4176.2 4107.2 4118.2 4132.6 4154.7 4165.6 4014.5 4060.3 4066.6 4094.2 4139.6 3898.0 3947.3 4057.9 4127.8 4147.0 3595.0 3605.3 3612.2 3612.9 3615.3 5108.6 5129.5 5138.9 5163.1 5163.2 4382.8 4495.7 4643.7 4654.7 4664.7 5427.5 5500.1 5515.4 5528.8 5532.3 3859.3 3881.8 3883.8 3900.9 3901.4 5390.1 5696.3 5729.4 5754.8 5781.2 3808.4 3845.3 3867.0 3886.1 3906.7 3971.2 3972.9 3973.9 3975.0 3984.7 4507.2 4601.1 4628.5 4637.4 4643.6 5594.6 5605.9 5611.7 5612.2 5631.6 1376.1 1376.8 1389.0 1394.9 1397.8 798.5 845.8 862.4 862.5 865.0 4747.5 4753.9 4774.3 4802.7 4819.8 5022.4 5042.2 5054.5 5081.9 5082.0 4597.5 4838.5 4839.0 4856.5 4861.9 2673.5 2692.3 2696.4 2709.4 2715.8 5118.4 5141.6 5160.3 5189.5 5203.2 4932.3 5073.8 5114.6 5181.2 5238.3 5257.6 5269.3 5294.3 5305.5 5305.6 4868.7 5108.0 5115.0 5135.5 5149.2 4752.6 5027.6 5054.6 5108.5 5119.2 5118.3 5145.7 5182.9 5256.6 5270.3 3605.1 3625.7 3632.4 3633.9 3639.2 4736.5 5121.0 5131.8 5137.0 5146.0 5001.4 5018.3 5022.5 5023.5 5047.9 4854.9 4894.0 4976.4 5010.9 5014.5 4861.4 4903.7 5108.9 5584.4 5602.6 5475.6 5512.9 5653.2 5658.1 5671.4 5491.7 5508.4 5523.7 5526.6 5538.0 14:26:14 INFO - PROCESS | 898 | 14:26:14 INFO - PROCESS | 898 | [#5] yui.html Cycles:180 Average:16191.02 Median:4921.63 stddev:20172.45 (409.9%) stddev-sans-first:20195.76 14:26:14 INFO - PROCESS | 898 | Values: 761.4 783.6 784.8 797.5 838.9 14514.6 15340.6 15353.2 15385.7 15405.0 13764.5 14469.6 14494.9 14502.9 14529.1 680.2 798.8 823.1 833.9 836.4 807.6 814.1 814.6 814.7 816.8 863.8 866.8 867.0 868.8 868.9 699.3 816.6 852.6 853.5 865.8 624.4 624.9 625.0 625.0 625.6 14403.9 14410.8 14440.8 14469.2 14477.2 13435.5 13694.2 13702.6 13988.0 14233.6 11896.3 11928.5 11941.5 12131.8 12191.9 4414.7 4421.2 4474.9 4486.9 4500.1 55889.0 56821.0 57100.4 57157.7 57308.0 11088.2 11133.9 11211.8 11311.0 11491.2 20218.0 20293.5 20351.1 20386.3 20395.1 7767.7 7800.8 7909.8 7966.7 8446.4 4508.8 4516.9 4604.3 4677.3 4809.4 3365.0 3456.4 3457.9 3459.6 3459.7 661.7 702.4 704.7 710.4 712.5 733.6 769.9 775.3 780.2 789.4 9723.7 9731.3 9845.0 9845.3 9897.4 646.3 726.5 751.6 775.5 779.6 4704.8 4882.6 4921.4 4921.8 5000.8 74840.6 76334.0 76340.6 76500.6 76842.0 66305.0 66392.0 66432.3 66543.7 67470.7 60561.7 60892.1 61078.1 61745.0 62230.4 8847.8 9368.7 9427.9 9546.1 9709.6 36226.8 36621.8 36777.4 36821.6 38499.8 50493.5 50638.7 51216.7 51226.5 51814.0 11293.4 11299.5 11478.5 11525.9 11841.3 12174.3 12455.3 12504.8 12627.2 12858.8 11788.2 11986.3 12308.4 12620.6 12710.6 11496.6 11738.6 11886.2 12196.4 12350.5 11874.6 12232.4 12552.7 12557.8 12602.4 10240.0 10244.5 10296.4 10442.2 10642.7 9458.5 9950.0 10154.3 10170.4 10262.8 14:26:14 INFO - PROCESS | 898 | -------- Summary: end -------- 14:26:14 INFO - PROCESS | 898 | 14:26:15 INFO - TEST-INFO | 898: exit 0 14:26:15 INFO - TEST-OK | dromaeo_css | took 1113839ms 14:26:15 INFO - TEST-START | kraken 14:26:15 INFO - operating with platform_type : linux_ 14:26:15 INFO - Initialising browser for kraken test... 14:26:15 INFO - TEST-INFO | started process 1089 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppW3WyU/profile http://localhost:32787/getInfo.html) 14:26:19 INFO - PROCESS | 1089 | __metrics Screen width/height:1600/1200 14:26:19 INFO - PROCESS | 1089 | colorDepth:24 14:26:19 INFO - PROCESS | 1089 | Browser inner width/height: 1024/697 14:26:19 INFO - PROCESS | 1089 | __metrics 14:26:20 ERROR - PROCESS | 1089 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:26:21 INFO - TEST-INFO | 1089: exit 0 14:26:21 INFO - Browser initialized. 14:26:21 INFO - Running cycle 1/1 for kraken test... 14:26:21 INFO - TEST-INFO | started process 1230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppW3WyU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:33 INFO - PROCESS | 1230 | RSS: Main: 155586560 14:26:33 INFO - PROCESS | 1230 | 14:26:36 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32787/tests/kraken/driver.html?testName=audio-beat-detection) 14:26:37 INFO - PROCESS | 1230 | RSS: Main: 213819392 14:26:37 INFO - PROCESS | 1230 | 14:26:39 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32787/tests/kraken/driver.html?testName=audio-dft) 14:26:40 INFO - PROCESS | 1230 | RSS: Main: 199745536 14:26:40 INFO - PROCESS | 1230 | 14:26:43 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32787/tests/kraken/driver.html?testName=audio-fft) 14:26:44 INFO - PROCESS | 1230 | RSS: Main: 268279808 14:26:44 INFO - PROCESS | 1230 | 14:26:46 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32787/tests/kraken/driver.html?testName=audio-oscillator) 14:26:46 INFO - PROCESS | 1230 | RSS: Main: 178909184 14:26:46 INFO - PROCESS | 1230 | 14:26:48 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32787/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:26:49 INFO - PROCESS | 1230 | RSS: Main: 183898112 14:26:49 INFO - PROCESS | 1230 | 14:26:52 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32787/tests/kraken/driver.html?testName=imaging-darkroom) 14:26:53 INFO - PROCESS | 1230 | RSS: Main: 219213824 14:26:53 INFO - PROCESS | 1230 | 14:26:56 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32787/tests/kraken/driver.html?testName=imaging-desaturate) 14:26:57 INFO - PROCESS | 1230 | RSS: Main: 220078080 14:26:57 INFO - PROCESS | 1230 | 14:26:59 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32787/tests/kraken/driver.html?testName=json-parse-financial) 14:27:00 INFO - PROCESS | 1230 | RSS: Main: 220127232 14:27:00 INFO - PROCESS | 1230 | 14:27:02 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32787/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:27:02 INFO - PROCESS | 1230 | RSS: Main: 208388096 14:27:02 INFO - PROCESS | 1230 | 14:27:04 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:27:05 INFO - PROCESS | 1230 | RSS: Main: 208592896 14:27:05 INFO - PROCESS | 1230 | 14:27:07 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:27:07 INFO - PROCESS | 1230 | RSS: Main: 215580672 14:27:07 INFO - PROCESS | 1230 | 14:27:10 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:27:11 INFO - PROCESS | 1230 | RSS: Main: 221655040 14:27:11 INFO - PROCESS | 1230 | 14:27:13 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:27:14 INFO - PROCESS | 1230 | RSS: Main: 219373568 14:27:14 INFO - PROCESS | 1230 | 14:27:15 INFO - PROCESS | 1230 | Cycle 1(1): loaded http://localhost:32787/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32787/tests/kraken/driver.html?testName=ai-astar) 14:27:16 INFO - PROCESS | 1230 | RSS: Main: 262217728 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | __start_tp_report 14:27:16 INFO - PROCESS | 1230 | _x_x_mozilla_page_load 14:27:16 INFO - PROCESS | 1230 | _x_x_mozilla_page_load_details 14:27:16 INFO - PROCESS | 1230 | |i|pagename|runs| 14:27:16 INFO - PROCESS | 1230 | |0;ai-astar;111;107;111;109;109;110;109;111;109;109 14:27:16 INFO - PROCESS | 1230 | |1;audio-beat-detection;158;134;132;191;131;161;157;124;190;125 14:27:16 INFO - PROCESS | 1230 | |2;audio-dft;161;162;162;162;162;160;162;161;160;162 14:27:16 INFO - PROCESS | 1230 | |3;audio-fft;75;75;76;116;75;77;74;75;102;75 14:27:16 INFO - PROCESS | 1230 | |4;audio-oscillator;83;84;121;83;84;83;111;83;84;121 14:27:16 INFO - PROCESS | 1230 | |5;imaging-gaussian-blur;117;118;116;117;116;116;117;118;117;118 14:27:16 INFO - PROCESS | 1230 | |6;imaging-darkroom;147;147;149;147;145;145;145;146;146;145 14:27:16 INFO - PROCESS | 1230 | |7;imaging-desaturate;96;96;95;96;95;95;95;95;95;95 14:27:16 INFO - PROCESS | 1230 | |8;json-parse-financial;53;53;55;55;54;53;55;54;53;53 14:27:16 INFO - PROCESS | 1230 | |9;json-stringify-tinderbox;61;62;61;61;61;61;61;61;61;62 14:27:16 INFO - PROCESS | 1230 | |10;stanford-crypto-aes;79;84;80;82;80;80;80;82;108;79 14:27:16 INFO - PROCESS | 1230 | |11;stanford-crypto-ccm;121;121;124;118;119;119;129;123;134;125 14:27:16 INFO - PROCESS | 1230 | |12;stanford-crypto-pbkdf2;157;158;158;162;158;158;157;160;158;159 14:27:16 INFO - PROCESS | 1230 | |13;stanford-crypto-sha256-iterative;62;67;63;63;62;66;61;62;63;62 14:27:16 INFO - PROCESS | 1230 | __end_tp_report 14:27:16 INFO - PROCESS | 1230 | __start_cc_report 14:27:16 INFO - PROCESS | 1230 | _x_x_mozilla_cycle_collect,1689 14:27:16 INFO - PROCESS | 1230 | __end_cc_report 14:27:16 INFO - PROCESS | 1230 | __startTimestamp1464384436249__endTimestamp 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | ------- Summary: start ------- 14:27:16 INFO - PROCESS | 1230 | Number of tests: 14 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#0] ai-astar Cycles:10 Average:109.50 Median:109.50 stddev:1.27 (1.2%) stddev-sans-first:1.22 14:27:16 INFO - PROCESS | 1230 | Values: 111.0 107.0 111.0 109.0 109.0 110.0 109.0 111.0 109.0 109.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:157.50 stddev:25.29 (16.1%) stddev-sans-first:26.67 14:27:16 INFO - PROCESS | 1230 | Values: 158.0 134.0 132.0 191.0 131.0 161.0 157.0 124.0 190.0 125.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 14:27:16 INFO - PROCESS | 1230 | Values: 161.0 162.0 162.0 162.0 162.0 160.0 162.0 161.0 160.0 162.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:14.63 (19.5%) stddev-sans-first:15.30 14:27:16 INFO - PROCESS | 1230 | Values: 75.0 75.0 76.0 116.0 75.0 77.0 74.0 75.0 102.0 75.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:83.00 stddev:16.77 (20.2%) stddev-sans-first:17.33 14:27:16 INFO - PROCESS | 1230 | Values: 83.0 84.0 121.0 83.0 84.0 83.0 111.0 83.0 84.0 121.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 14:27:16 INFO - PROCESS | 1230 | Values: 117.0 118.0 116.0 117.0 116.0 116.0 117.0 118.0 117.0 118.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.50 stddev:1.32 (0.9%) stddev-sans-first:1.36 14:27:16 INFO - PROCESS | 1230 | Values: 147.0 147.0 149.0 147.0 145.0 145.0 145.0 146.0 146.0 145.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 14:27:16 INFO - PROCESS | 1230 | Values: 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 14:27:16 INFO - PROCESS | 1230 | Values: 53.0 53.0 55.0 55.0 54.0 53.0 55.0 54.0 53.0 53.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 14:27:16 INFO - PROCESS | 1230 | Values: 61.0 62.0 61.0 61.0 61.0 61.0 61.0 61.0 61.0 62.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:80.00 stddev:8.78 (11.0%) stddev-sans-first:9.17 14:27:16 INFO - PROCESS | 1230 | Values: 79.0 84.0 80.0 82.0 80.0 80.0 80.0 82.0 108.0 79.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#11] stanford-crypto-ccm Cycles:10 Average:123.30 Median:123.50 stddev:5.01 (4.1%) stddev-sans-first:5.25 14:27:16 INFO - PROCESS | 1230 | Values: 121.0 121.0 124.0 118.0 119.0 119.0 129.0 123.0 134.0 125.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.50 Median:158.00 stddev:1.51 (1.0%) stddev-sans-first:1.50 14:27:16 INFO - PROCESS | 1230 | Values: 157.0 158.0 158.0 162.0 158.0 158.0 157.0 160.0 158.0 159.0 14:27:16 INFO - PROCESS | 1230 | 14:27:16 INFO - PROCESS | 1230 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.91 (3.0%) stddev-sans-first:1.99 14:27:16 INFO - PROCESS | 1230 | Values: 62.0 67.0 63.0 63.0 62.0 66.0 61.0 62.0 63.0 62.0 14:27:16 INFO - PROCESS | 1230 | -------- Summary: end -------- 14:27:16 INFO - PROCESS | 1230 | 14:27:17 INFO - TEST-INFO | 1230: exit 0 14:27:17 INFO - TEST-OK | kraken | took 61511ms 14:27:17 INFO - SUITE-END | took 1175s 14:27:17 INFO - Completed test suite (00:19:35) 14:27:17 INFO - javascript benchmark 14:27:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.294333774901, 3141.073383351911, 3168.6514483406827, 3188.8246146461934, 3205.8557364918574, 5318.282031925689, 5401.081816091264, 5415.296011518507, 5438.918416223755, 5472.20653005314, 5404.91892621611, 5414.620976531644, 5425.538829199513, 5544.334679838415, 5554.1391084381985, 4901.705897646144, 4918.877028074291, 4921.975390123044, 5005.6746311489815, 5050.040492306468, 4962.255661650764, 5065.518775716301, 5147.150720131192, 5156.92264616031, 5159.587233021368, 4880.780364883589, 4920.040592084509, 4980.900381992376, 5001.524855138756, 5030.0694371541385, 4946.35697359341, 4953.752312384367, 4975.228839529878, 4977.7511124444, 4982.701037937699, 4938.827141050082, 4940.184869496528, 4943.9258411123865, 4958.925616115761, 5018.698878067291, 5493.478119578634, 5512.779488820443, 5566.053770858964, 5567.192757050205, 5580.525655319303, 5665.12190610456, 5877.85305367365, 5921.61509501881, 5939.970300148515, 5990.341062483167, 5944.583879128462, 5983.58114931955, 5985.610935289235, 5998.040313549855, 5999.370066143036, 5508.173773933942, 5560.582956278296, 5561.499465048165, 5561.916571251434, 5569.637973531748, 5862.0, 5954.612950158313, 5979.850503737354, 6002.879942401128, 6058.379666401924, 5811.15738217956, 5862.794802181903, 5866.6480011199465, 5866.911996320058, 5871.031279838864, 5912.822615321547, 5916.0, 5959.791407300723, 5962.61243019211, 5963.135345374983, 5682.460166284279, 5715.31416230055, 5717.571182161355, 5754.913676294859, 5855.765769369263, 5730.684812335278, 5787.479126878517, 5790.536757059509, 5804.6807425592, 5807.970960145257, 4338.0, 4349.456317960255, 4351.6518678505645, 4351.956480435155, 4361.084172323847, 3846.442265871433, 3849.711271654581, 3861.4787003754695, 3882.0683036071705, 3906.5312162540677, 5476.17857321405, 5507.476789704971, 5512.255845460892, 5514.09017512114, 5559.722013899289, 5730.770769169187, 5810.88378232433, 5812.331581868055, 5815.505682016991, 5873.853153671193, 5544.279243698374, 5576.888462230732, 5584.91622625661, 5587.245721827582, 5611.186377975171, 5035.244713292962, 5036.294918711384, 5039.143345631252, 5045.974770126126, 5088.77100530477, 5907.232059832194, 6010.669413182317, 6126.387361263838, 6142.846428839315, 6148.600340977911, 5861.208736820475, 5938.346781854079, 5941.851453713692, 5946.940530594812, 5960.016597261403, 6040.184575082308, 6056.1824153740445, 6057.515398768, 6058.4244496772735, 6137.048757442603, 5662.178984047372, 5706.857328566819, 5742.856428589319, 5815.534757219497, 5826.970865145647, 5899.321578018411, 5914.201582786441, 5916.881662366814, 5922.0, 5950.285965684146, 5705.087186050212, 5746.942530574641, 5763.510101641407, 5772.5959182856795, 5787.797427090031, 5525.005499010217, 5702.230198923092, 5747.942520574908, 5749.310082790092, 5752.9137062943255, 5683.346415162309, 5712.0, 5713.028785106459, 5718.942810571841, 5719.628224165416, 5842.941570584239, 5855.502282306052, 5893.410658934073, 5899.115132730125, 5907.261592300963, 5515.806946756844, 5516.972415137899, 5614.101743720985, 5628.212050313042, 5641.4358564144895], "value": 5403.03508167877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.623920690758, 631.1794666932988, 634.4290138875012, 648.7632014314771, 650.6258901131849, 34901.0, 45064.324035139456, 46048.0, 46114.999999999956, 49647.751761241234, 47716.0, 48148.0, 48152.51847481524, 48169.7591512042, 48282.517174828245, 8130.0243970723595, 8962.551872406386, 9163.404378715395, 9165.54172291386, 9168.220701240403, 10635.149188064976, 10946.39794811285, 10950.835737463944, 11334.263272887278, 11336.773264534704, 10993.230473866832, 11127.165462590337, 11130.0, 11136.220464567483, 11140.053095486894, 8227.341812654973, 10833.295835770728, 10982.890171098266, 11053.226274160812, 11119.054880335181, 10493.68828896388, 10945.945270273678, 10979.725506862313, 11044.668659940215, 11057.668269951915, 43057.56942430567, 43718.12563748707, 43839.0, 43880.56119438797, 44394.11211775746, 3492.877749278763, 3571.232185080194, 3614.710823134143, 3616.0, 3651.488791569156, 33275.33449331024, 33511.32977340464, 33823.83088084544, 33993.6600633993, 34050.65949340524, 3739.813009349549, 3791.545014598238, 3855.3445914194654, 3906.4140378943102, 3913.706472014582, 386812.0, 388020.0, 389229.0, 389278.05360973295, 391494.0, 67048.6647566759, 67623.0, 67656.6617166911, 67658.0, 67700.66149669318, 70504.0, 71855.64072179605, 71925.0, 72090.27909720941, 72111.27888721046, 17318.0, 17666.0, 17676.381326653507, 17679.87841459033, 17684.911575442296, 11860.347680877461, 13147.802782958262, 13419.463221471227, 13423.597292081253, 13446.0, 20626.58746825055, 20633.277835275992, 20703.171873124906, 20746.48133796637, 20777.0, 6379.330170332141, 7272.182104395235, 7294.8905766413545, 7298.854022919512, 7321.926780732231, 1024.6823484719762, 1043.7860238651058, 1107.8670559532748, 1159.2580748321082, 1217.817327400897, 30985.380292394475, 32977.0, 33551.49672754911, 33593.32813343719, 33619.3276134476, 1113.432149603708, 1126.487448211062, 1148.4487446025787, 1161.3032180691687, 1233.5127624588238, 16816.915915420588, 17237.827621723623, 17310.653786924442, 17411.0, 17418.12909354527, 589428.0, 594649.0, 594841.0, 594846.0, 597220.0, 60789.392106079256, 62816.0, 62944.68527657333, 62968.68515657393, 63050.36949630445, 1289.9097063205486, 1308.2739079810606, 1322.9338533073114, 1393.3869097597026, 1406.1984668738721, 25529.87235063813, 33369.499457508646, 34822.0, 36248.27503449969, 36413.63586364102, 57114.7144264276, 59110.0, 59636.403635963085, 59645.701771490865, 64655.67672161609, 415467.0, 417304.0, 420724.0, 423427.0, 424170.0, 38322.808385959004, 43413.348799767395, 44513.332300016176, 44600.10799784051, 45014.549854502344, 50492.74753626208, 51374.0, 51584.48415515797, 52044.21933671074, 52372.21441678454, 41141.382879257435, 42538.0, 42706.359404609575, 43449.0, 44213.55786442223, 42914.57085429106, 43028.56971430246, 43102.78448607737, 43300.56699433091, 44174.55825441705, 43368.78315608402, 43702.56297436985, 43899.56100438955, 44481.0, 44674.55325446704, 35887.0, 37696.246075078896, 37911.81044094762, 38261.808690956364, 38904.0, 28710.282242944093, 28718.856405718674, 28824.567631485963, 28869.27826804347, 29143.708562914948], "value": 21711.517311781055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.9629037096291, 373.723444650958, 396.84919730502395, 400.19360987609963, 416.0991453503166, 18161.909190454047, 19666.70499942502, 20634.484137896543, 20636.0, 20661.69007464887, 18546.907265463688, 18812.15345309461, 18984.715229271573, 19254.903725481352, 19307.131179096938, 3664.0839790052487, 5500.28496295483, 5620.9437905620825, 5658.971705141468, 5817.331006934193, 3783.1298801275725, 3844.788536630484, 3850.4994350734364, 7062.470314726416, 7359.116905971264, 7362.263773622649, 7392.55644661322, 7483.925160748405, 7486.775396738107, 7567.508111972732, 7273.236310187407, 7385.372243359321, 7504.662290196954, 7538.660760265801, 7551.69793208271, 7038.964805175992, 7151.034610327643, 7227.0, 7249.601271930042, 7252.3835473984245, 25966.0, 26223.606645900327, 26476.073337433096, 27012.189634311002, 27341.863290683617, 2534.873256337176, 2584.715681275058, 2585.5216784894856, 2611.673540807399, 2744.464829358276, 15357.385704571803, 15905.363785448568, 16002.19989000557, 16124.75812862808, 16797.0, 2044.2436298569476, 2064.9984757392654, 2186.464316242531, 2243.876586787726, 2423.3578101803037, 80729.0, 81717.59141204316, 82152.0, 93664.0, 94472.52763736206, 9171.908280917105, 9724.854127188099, 9806.411615303105, 9990.0, 10109.443980581138, 13260.469581216836, 13291.534796282083, 13340.799888001688, 13441.865581344256, 13451.865481345061, 7726.7681969541, 7874.842503149905, 7994.2405471480115, 8074.192580741878, 8134.471259368122, 8001.279884810395, 8047.839043219103, 8051.154628764012, 8081.515109093473, 8109.0, 3826.1582451860545, 3878.84484620612, 3954.1696243789124, 4132.070284186034, 4320.567943205654, 1815.7161525753825, 1903.961920761577, 2010.5576773109892, 2033.2781862438917, 2068.204844111073, 923.2198792020732, 1024.5748014574017, 1037.9792404151874, 1095.7041598768287, 1131.0386171753944, 18605.90697046506, 18866.434006979813, 18929.148188331557, 19473.123709432824, 19839.801601984083, 1015.4871789746131, 1032.8554002439664, 1064.0476773287864, 1073.6456969200144, 1082.1396989393452, 10380.584776609003, 10999.450027498593, 11072.833907491395, 11087.55649774, 11095.778084438267, 474897.0, 483996.0, 487456.0, 491969.0, 495638.0, 84230.5788471066, 86733.5663321692, 91623.54188229016, 95657.521712391, 96928.82174985205, 1037.7301901505664, 1067.2529229539352, 1070.464767616192, 1074.8925107489345, 1079.4386918802336, 17292.827071729123, 17295.30818767235, 17676.558086047437, 17700.91149544244, 17948.910255449162, 41857.0, 41952.0, 43147.0, 44266.11467770691, 44656.10687786161, 59546.70226648839, 60597.0, 60740.0, 60906.69546652238, 62486.68756656188, 7794.181610930771, 7979.202079791974, 8215.0, 8244.752657420288, 8493.0, 9578.521073946415, 12434.067444941486, 12655.493780249055, 12992.935035324763, 13142.868571314164, 11007.449627518432, 11344.319340839576, 11482.138839586898, 11549.769004619693, 11616.0, 11087.22389432732, 11205.159613029218, 11464.140189485988, 11518.136139789716, 11627.418629068681, 11006.559737610405, 11429.371384573928, 11444.82832757526, 11546.538138474367, 11727.120465965258, 7925.643346049326, 7969.840603187788, 8001.519908805491, 8116.83766324682, 8133.511989280662, 8668.436551624125, 10075.899241007495, 10295.691129266133, 10320.896791031993, 10358.896411035794], "value": 9455.542819571856, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.047405867776, 1527.8624923756859, 1543.020182184313, 1683.9915800420997, 1835.8806677565958, 8450.028246751617, 8806.955965220173, 8814.4711317321, 9087.500187489686, 9106.590203440845, 8840.557972101402, 9033.412828166181, 9039.774005649862, 9055.185033346997, 9064.773380665487, 2016.5261163626535, 2260.446190683285, 2339.0526836631193, 2376.595978683624, 2405.518896220754, 2393.0667039854384, 2402.423418379585, 2402.627592723131, 2403.639454081884, 2409.4458274596764, 2370.644403339504, 2373.264288070695, 2403.9158629447975, 2404.7475015123423, 2407.2296865003204, 1998.310582848922, 2189.5730332585154, 2234.2961966980347, 2269.727632684072, 2276.5885151206194, 2248.921287754937, 2266.5353602511445, 2271.7955384015354, 2287.7025986621634, 2290.770922907696, 8919.687810926587, 8928.24109950655, 8978.640854365818, 8988.910110898938, 9001.23489503393, 5165.74171291434, 5298.867528311785, 5299.152135658276, 5304.681719096867, 5305.071612467841, 3070.416620842033, 3266.1181481000235, 3282.78661886979, 3308.123347312964, 3348.6483919188377, 1018.6689325969089, 1045.6862941117633, 1054.2462139570168, 1107.4086637268829, 1108.3959242213014, 12688.111832171751, 12703.745925081446, 12721.04592155592, 12760.872391276062, 12817.42321595521, 4809.134355815917, 4823.831165909176, 4890.413150421972, 4894.657373983858, 4935.333729946483, 10698.304610200312, 10714.946425267824, 10724.195685323475, 10786.73033174177, 10794.568217271379, 5392.218128371364, 5392.40683524812, 5396.19057141424, 5405.0, 5429.56563474921, 4359.956400436018, 4661.184292748755, 4762.166620841339, 4783.760811959388, 4844.32179494871, 4034.8873124120746, 4044.8786536403954, 4084.448599439097, 4085.8569950051606, 4110.979445102816, 1244.4337826289197, 1263.0148484182353, 1267.6260503151593, 1278.7122897348022, 1281.8718128187106, 811.2009670474578, 949.7720547068653, 1027.5786927359748, 1032.7418145463635, 1050.8791488978713, 6292.685365731695, 6366.554341196072, 6393.168888044617, 6470.805875823733, 6546.8363290918105, 457.90612924350427, 473.1648640150138, 1043.9269251152498, 1122.5004872831676, 1154.3131836557236, 4206.915861682749, 4262.190183865056, 4398.252297109435, 4425.557444255549, 4471.284594464935, 10943.83584246237, 11253.943730281297, 11312.264702794291, 11327.0, 11367.886321136682, 11103.333799972028, 11205.159613029055, 11238.550457981753, 11310.773784524428, 11357.886421135683, 8212.876806847782, 8251.174882511701, 8339.666413343399, 8432.662693492315, 8448.831023379498, 5956.970215148896, 5983.850403739942, 6075.605085669417, 6156.107364432043, 6196.597221180609, 9959.502024898871, 10224.59101635956, 10253.74365640865, 10289.073983341535, 10573.735656608646, 10407.791844162923, 10512.684619461428, 10533.683989480327, 10598.947005264925, 10611.734706632396, 6841.589504629738, 7358.96320518394, 7485.737999170003, 7582.962085189539, 7613.01030866006, 8762.824743505222, 8824.64701411962, 8836.425632333878, 8895.822083558163, 8900.688475903313, 7688.961555192188, 8023.318017968538, 8033.236842499953, 8039.196080392148, 8103.108658047492, 7736.961315193388, 7885.2114788520685, 7888.092869319872, 7947.125816160102, 7992.320652744582, 7006.614636195059, 7196.712131514681, 7283.781486555412, 7285.963570182115, 7295.562266264041, 5382.596305277199, 5391.865203369947, 5439.646422982493, 5449.0, 5574.275344205227, 5429.0, 5542.584306177134, 5588.748506317307, 5747.371025609304, 5749.597528172988], "value": 4711.395840149587, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.21695760598504, 317.8347259425099, 323.8429361759547, 324.56832412890856, 325.88756878876796, 5347.278117454142, 5367.731613419323, 5373.731313434322, 5391.73041347933, 5411.512963833259, 5262.105442074847, 5278.9208161877605, 5289.814856480024, 5311.0, 5347.304850369446, 4032.6169013943627, 4143.233501802161, 4160.355144952538, 4162.292410290258, 4176.164767046587, 4107.240160570289, 4118.176364727066, 4132.607402296777, 4154.6676265898805, 4165.58344165584, 4014.4781178446906, 4060.309747342944, 4066.593340665939, 4094.222097801423, 4139.606737359946, 3897.9805100974595, 3947.2894878921784, 4057.9391309130388, 4127.772972486512, 4146.983988922718, 3594.982025089858, 3605.296967091418, 3612.151144481045, 3612.8916132516065, 3615.349237137314, 5108.591312694975, 5129.461406552333, 5138.922916156261, 5163.1480805667015, 5163.173892177234, 4382.824687012516, 4495.707778994355, 4643.651726120521, 4654.697444666086, 4664.696794708333, 5427.48438898304, 5500.147477141009, 5515.448455154493, 5528.75120619573, 5532.28080349556, 3859.26673931952, 3881.786501742403, 3883.805809709503, 3900.8829735107993, 3901.356276214421, 5390.146045577392, 5696.316442026942, 5729.398413166599, 5754.798582049608, 5781.248437703114, 3808.3906574947873, 3845.3270677631863, 3866.9806650966566, 3886.0867696091395, 3906.687465002793, 3971.225611005884, 3972.900677483086, 3973.8807835764974, 3975.0, 3984.6812255019527, 4507.233770259064, 4601.12578610063, 4628.5371462853445, 4637.449402828431, 4643.582077613031, 5594.608377413623, 5605.86976948765, 5611.663300202001, 5612.1581762735095, 5631.605787594829, 1376.0986553807272, 1376.8416632087437, 1388.9930550347385, 1394.9163050217019, 1397.7623803953352, 798.4730078148394, 845.7778510053038, 862.3618522293458, 862.4566523090539, 864.9870251946226, 4747.454042785055, 4753.857384278477, 4774.3077253798, 4802.711837289774, 4819.819144309597, 5022.447530771612, 5042.193249080203, 5054.4692807254705, 5081.898362032739, 5082.0, 4597.540245975442, 4838.475880097758, 4838.95161048385, 4856.465788763233, 4861.9027619447415, 2673.5187666220268, 2692.3134600676703, 2696.382170697605, 2709.444563864403, 2715.823471474348, 5118.38579370478, 5141.5886729061585, 5160.303359046481, 5189.495046436491, 5203.230901493511, 4932.260160975882, 5073.847784566469, 5114.590832733373, 5181.222816577543, 5238.345206849144, 5257.6056795739705, 5269.262303277467, 5294.3117394737665, 5305.522502974673, 5305.575553955674, 4868.732219727807, 5107.978404319077, 5115.0, 5135.486451354835, 5149.193689439189, 4752.596029337545, 5027.622928280321, 5054.620903432184, 5108.540231379194, 5119.206522988943, 5118.314813334393, 5145.681035732006, 5182.948170518246, 5256.579473642176, 5270.341207349082, 3605.1167463971497, 3625.6585063526663, 3632.3643362410944, 3633.8909832705062, 3639.1993761372455, 4736.502667219892, 5121.0, 5131.79472821083, 5136.97431512855, 5146.0, 5001.399832020181, 5018.297438358706, 5022.5434623958645, 5023.463151108666, 5047.899042019212, 4854.878628034327, 4893.951060489349, 4976.402831660224, 5010.949890501194, 5014.52362025607, 4861.416630004422, 4903.656744027884, 5108.9233661495855, 5584.413636568264, 5602.59935016246, 5475.589330800286, 5512.944870551403, 5653.236813030188, 5658.122990936411, 5671.376148623731, 5491.697956612425, 5508.421615730291, 5523.7238138093735, 5526.640768350045, 5538.0], "value": 4011.136885210925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.4061032394734, 783.6316931042408, 784.7763387434583, 797.5493845977022, 838.9286910612595, 14514.63713407164, 15340.616484587863, 15353.15557644329, 15385.692286154284, 15405.0, 13764.518241861537, 14469.638259043502, 14494.927525362385, 14502.92748536256, 14529.128252304843, 680.1905732178692, 798.7603718884341, 823.0617096509983, 833.9249467547919, 836.4186890111375, 807.6002378822491, 814.1370147643516, 814.6008455856647, 814.7067055859885, 816.8121332093622, 863.7926897544606, 866.774638593967, 867.0462491259595, 868.7524055644143, 868.891388576428, 699.3391245273219, 816.603947085663, 852.6291063387446, 853.4793775796759, 865.8008658008662, 624.3662682377391, 624.9125122482856, 624.9968750156265, 625.0124802811547, 625.6308777821062, 14403.927980360031, 14410.783838242434, 14440.783388249185, 14469.20419376934, 14477.203753793538, 13435.529756458574, 13694.178349298974, 13702.588922332347, 13988.0, 14233.572992810232, 11896.345700986441, 11928.46321915516, 11941.462634181482, 12131.818022729665, 12191.939040304742, 4414.735115893057, 4421.226285400042, 4474.865754027384, 4486.932696009562, 4500.09998000398, 55889.0, 56821.0, 57100.42899571034, 57157.714211428676, 57308.0, 11088.22382433238, 11133.888661113448, 11211.831822522669, 11311.0, 11491.195616306946, 20218.0, 20293.492662683257, 20351.084201210982, 20386.28647997313, 20395.082221300076, 7767.650455729506, 7800.804979875548, 7909.762707118795, 7966.681332746741, 8446.366522510836, 4508.774561271924, 4516.932246016312, 4604.263317869125, 4677.251639737626, 4809.374781278481, 3365.0, 3456.42968910127, 3457.8789742358895, 3459.584849818038, 3459.6680278093118, 661.7088481068411, 702.424012309901, 704.7075463682514, 710.4068103133837, 712.4692104382165, 733.6368497593726, 769.9076110866732, 775.2983549887362, 780.157429975625, 789.3763926498002, 9723.708288751348, 9731.336753768433, 9844.950775246078, 9845.310828242098, 9897.35667181631, 646.3278190681639, 726.5096060159372, 751.612919346537, 775.4804281131655, 779.6062988190927, 4704.767604229046, 4882.609391248691, 4921.44142886353, 4921.827736029293, 5000.824971125993, 74840.62579687066, 76334.0, 76340.61829690928, 76500.61749691216, 76842.0, 66305.0, 66392.0, 66432.33567664358, 66543.66728166425, 67470.66264668645, 60561.69719151376, 60892.086618700756, 61078.0838287426, 61745.0, 62230.37769622246, 8847.823043538965, 9368.67209647659, 9427.905720942703, 9546.078685001394, 9709.563069662023, 36226.8188659055, 36621.81689091644, 36777.448338274415, 36821.63178368182, 38499.807500963434, 50493.49506505035, 50638.746806265735, 51216.74391628167, 51226.487735123665, 51814.0, 11293.43532823372, 11299.548018079513, 11478.483468244116, 11525.88474115248, 11841.28952262867, 12174.330411827434, 12455.314957677414, 12504.812427813407, 12627.242365458102, 12858.807117893426, 11788.174827761974, 11986.280823150639, 12308.446119924445, 12620.621381358575, 12710.555130570385, 11496.597619083292, 11738.594819975906, 11886.227395219283, 12196.390180491117, 12350.505979760708, 11874.58438954667, 12232.388380581113, 12552.686182845502, 12557.811632825698, 12602.369881505705, 10240.030202475726, 10244.500354815795, 10296.382217067, 10442.164626829683, 10642.680719578426, 9458.527073646152, 9950.0, 10154.289199755945, 10170.389776613427, 10262.794744104927], "value": 6987.95277584553, "unit": "score"}], "name": "dromaeo_css", "value": 7260.589989680862, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 111.0, 109.0, 109.0, 110.0, 109.0, 111.0, 109.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 134.0, 132.0, 191.0, 131.0, 161.0, 157.0, 124.0, 190.0, 125.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 162.0, 162.0, 160.0, 162.0, 161.0, 160.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 116.0, 75.0, 77.0, 74.0, 75.0, 102.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 121.0, 83.0, 84.0, 83.0, 111.0, 83.0, 84.0, 121.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 117.0, 116.0, 116.0, 117.0, 118.0, 117.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 149.0, 147.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 55.0, 54.0, 53.0, 55.0, 54.0, 53.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 80.0, 82.0, 80.0, 80.0, 80.0, 82.0, 108.0, 79.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 124.0, 118.0, 119.0, 119.0, 129.0, 123.0, 134.0, 125.0], "value": 123.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 158.0, 162.0, 158.0, 158.0, 157.0, 160.0, 158.0, 159.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 63.0, 63.0, 62.0, 66.0, 61.0, 62.0, 63.0, 62.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1498.7, "alertThreshold": 2.0}]} 14:27:17 INFO - Return code: 0 14:27:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:27:17 ERROR - # TBPL SUCCESS # 14:27:17 INFO - Running post-action listener: _resource_record_post_action 14:27:17 INFO - ##### 14:27:17 INFO - ##### Finished run-tests step (success) 14:27:17 INFO - ##### 14:27:17 INFO - Running post-run listener: _resource_record_post_run 14:27:17 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 544100352; Read time: 216; Write time: 485532 14:27:17 INFO - TinderboxPrint: CPU usage
13.3% 14:27:17 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 216 14:27:17 INFO - TinderboxPrint: I/O write bytes / time
544,100,352 / 485,532 14:27:17 INFO - TinderboxPrint: CPU idle
8,257.5 (86.7%) 14:27:17 INFO - TinderboxPrint: CPU user
1,216.1 (12.8%) 14:27:17 INFO - TinderboxPrint: Swap in / out
0 / 0 14:27:17 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 258654208; Read time: 200; Write time: 267004 14:27:17 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285446144; Read time: 16; Write time: 218528 14:27:18 INFO - Running post-run listener: _upload_blobber_files 14:27:18 INFO - Blob upload gear active. 14:27:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:27:18 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 14:27:18 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'] 14:27:18 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 14:27:18 INFO - (blobuploader) - INFO - Open directory for files ... 14:27:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:27:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:27:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:19 INFO - (blobuploader) - INFO - Done attempting. 14:27:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:27:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:27:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:23 INFO - (blobuploader) - INFO - Done attempting. 14:27:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:27:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:27:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:24 INFO - (blobuploader) - INFO - Done attempting. 14:27:24 INFO - (blobuploader) - INFO - Iteration through files over. 14:27:24 INFO - Return code: 0 14:27:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:27:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:27:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72c67fe5d686b09ff570ed3e44d77484a8a540899f0a15d18cdb6917b46a00ad195ce1fe94f286bed8046d75b390276c35949c2c5cb77420e8de7dbe7088fd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea214f20404fc30b07765d0e59a0aad7be5bc11cc997a9a8fb4852e5ff3acb5a07315763461b5a9e682d3994bacdf9f30dc73bea9f5cc3067a21b12e25899f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1b75df1a9154b2372fe1593263da4461fcccd2f64965c26b696689a750b4e38b843c0138b7cd2739d54c8c4e6e435595edb5042999ceea78055677951a7b145"} 14:27:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:27:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:27:24 INFO - Contents: 14:27:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72c67fe5d686b09ff570ed3e44d77484a8a540899f0a15d18cdb6917b46a00ad195ce1fe94f286bed8046d75b390276c35949c2c5cb77420e8de7dbe7088fd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea214f20404fc30b07765d0e59a0aad7be5bc11cc997a9a8fb4852e5ff3acb5a07315763461b5a9e682d3994bacdf9f30dc73bea9f5cc3067a21b12e25899f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1b75df1a9154b2372fe1593263da4461fcccd2f64965c26b696689a750b4e38b843c0138b7cd2739d54c8c4e6e435595edb5042999ceea78055677951a7b145"} 14:27:24 INFO - Running post-run listener: copy_logs_to_upload_dir 14:27:24 INFO - Copying logs to upload dir... 14:27:24 INFO - mkdir: /builds/slave/test/build/upload/logs 14:27:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.159847 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-27 14:27:23.796536) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 14:27:23.799984) ========= 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=574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044 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/72c67fe5d686b09ff570ed3e44d77484a8a540899f0a15d18cdb6917b46a00ad195ce1fe94f286bed8046d75b390276c35949c2c5cb77420e8de7dbe7088fd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea214f20404fc30b07765d0e59a0aad7be5bc11cc997a9a8fb4852e5ff3acb5a07315763461b5a9e682d3994bacdf9f30dc73bea9f5cc3067a21b12e25899f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1b75df1a9154b2372fe1593263da4461fcccd2f64965c26b696689a750b4e38b843c0138b7cd2739d54c8c4e6e435595edb5042999ceea78055677951a7b145"} build_url:https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011626 build_url: 'https://queue.taskcluster.net/v1/task/Pidq7zV2R6yg41_GF640wA/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/72c67fe5d686b09ff570ed3e44d77484a8a540899f0a15d18cdb6917b46a00ad195ce1fe94f286bed8046d75b390276c35949c2c5cb77420e8de7dbe7088fd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea214f20404fc30b07765d0e59a0aad7be5bc11cc997a9a8fb4852e5ff3acb5a07315763461b5a9e682d3994bacdf9f30dc73bea9f5cc3067a21b12e25899f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1b75df1a9154b2372fe1593263da4461fcccd2f64965c26b696689a750b4e38b843c0138b7cd2739d54c8c4e6e435595edb5042999ceea78055677951a7b145"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 14:27:23.857604) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:27:23.857956) ========= 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=574dc5aafe09851ec43d388c000001b6-1464383207.174710-142395044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:27:23.971416) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 14:27:23.971756) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 14:27:23.972059) ========= ========= Total master_lag: 0.23 =========