builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0006 starttime: 1461588434.79 results: success (0) buildid: 20160425033757 builduid: 17e4e8b195af460fb7d14e34ad84a362 revision: 690226a9fb27054d8a20fc7625a2ebd7067573ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:47:14.791672) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:47:14.792077) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 13 secs) (at 2016-04-25 05:47:14.792381) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 13 secs) (at 2016-04-25 05:47:28.432232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2016-04-25 05:47:28.432492) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-25 05:47:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to 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% 8.36M=0.001s 2016-04-25 05:47:29 (8.36 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.867457 ========= master_lag: 7.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2016-04-25 05:47:36.679943) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-04-25 05:47:36.680250) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021228 ========= master_lag: 6.97 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-04-25 05:47:43.672559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 05:47:43.672866) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 690226a9fb27054d8a20fc7625a2ebd7067573ce --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 690226a9fb27054d8a20fc7625a2ebd7067573ce --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-25 05:47:44,831 truncating revision to first 12 chars 2016-04-25 05:47:44,831 Setting DEBUG logging. 2016-04-25 05:47:44,831 attempt 1/10 2016-04-25 05:47:44,831 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/690226a9fb27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 05:47:45,619 unpacking tar archive at: fx-team-690226a9fb27/testing/mozharness/ program finished with exit code 0 elapsedTime=1.037212 ========= master_lag: 1.58 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 05:47:46.289023) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:47:46.289375) ========= script_repo_revision: 690226a9fb27054d8a20fc7625a2ebd7067573ce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:47:46.289781) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-04-25 05:47:46.290043) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-04-25 05:47:48.858243) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-25 05:47:48.861539) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:47:49 INFO - MultiFileLogger online at 20160425 05:47:49 in /builds/slave/test 05:47:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:47:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:47:49 INFO - {'append_to_log': False, 05:47:49 INFO - 'base_work_dir': '/builds/slave/test', 05:47:49 INFO - 'blob_upload_branch': 'Fx-Team', 05:47:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:47:49 INFO - 'branch': 'Fx-Team', 05:47:49 INFO - 'buildbot_json_path': 'buildprops.json', 05:47:49 INFO - 'config_files': ('talos/mac_config.py',), 05:47:49 INFO - 'default_actions': ('clobber', 05:47:49 INFO - 'read-buildbot-config', 05:47:49 INFO - 'download-and-extract', 05:47:49 INFO - 'populate-webroot', 05:47:49 INFO - 'create-virtualenv', 05:47:49 INFO - 'install', 05:47:49 INFO - 'run-tests'), 05:47:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:47:49 INFO - 'download_minidump_stackwalk': True, 05:47:49 INFO - 'download_symbols': 'ondemand', 05:47:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:47:49 INFO - 'tooltool.py': '/tools/tooltool.py', 05:47:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:47:49 INFO - '/tools/misc-python/virtualenv.py')}, 05:47:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:47:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:47:49 INFO - 'installer_path': 'installer.exe', 05:47:49 INFO - 'log_level': 'info', 05:47:49 INFO - 'log_name': 'talos', 05:47:49 INFO - 'log_to_console': True, 05:47:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:47:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:47:49 INFO - 'opt_config_files': (), 05:47:49 INFO - 'pip_index': False, 05:47:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:49 INFO - 'cmd': ('bash', 05:47:49 INFO - '-c', 05:47:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:49 INFO - 'enabled': True, 05:47:49 INFO - 'halt_on_failure': False, 05:47:49 INFO - 'name': 'check_screen_resolution'},), 05:47:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:49 INFO - 'cmd': ('bash', 05:47:49 INFO - '-c', 05:47:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:49 INFO - 'enabled': True, 05:47:49 INFO - 'halt_on_failure': False, 05:47:49 INFO - 'name': 'check_screen_resolution'},), 05:47:49 INFO - 'run_cmd_checks_enabled': True, 05:47:49 INFO - 'sps_profile': False, 05:47:49 INFO - 'sps_profile_interval': 0, 05:47:49 INFO - 'suite': 'dromaeojs-e10s', 05:47:49 INFO - 'system_bits': '32', 05:47:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:47:49 INFO - 'title': 't-yosemite-r7-0006', 05:47:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:47:49 INFO - 'use_talos_json': True, 05:47:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:47:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:47:49 INFO - 'work_dir': 'build'} 05:47:49 INFO - ##### 05:47:49 INFO - ##### Running clobber step. 05:47:49 INFO - ##### 05:47:49 INFO - Running pre-action listener: _resource_record_pre_action 05:47:49 INFO - Running main action method: clobber 05:47:49 INFO - rmtree: /builds/slave/test/build 05:47:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:47:50 INFO - Running post-action listener: _resource_record_post_action 05:47:50 INFO - ##### 05:47:50 INFO - ##### Running read-buildbot-config step. 05:47:50 INFO - ##### 05:47:50 INFO - Running pre-action listener: _resource_record_pre_action 05:47:50 INFO - Running main action method: read_buildbot_config 05:47:50 INFO - Using buildbot properties: 05:47:50 INFO - { 05:47:50 INFO - "project": "", 05:47:50 INFO - "product": "firefox", 05:47:50 INFO - "script_repo_revision": "production", 05:47:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:47:50 INFO - "repository": "", 05:47:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:47:50 INFO - "buildid": "20160425033757", 05:47:50 INFO - "pgo_build": "False", 05:47:50 INFO - "basedir": "/builds/slave/test", 05:47:50 INFO - "buildnumber": 725, 05:47:50 INFO - "slavename": "t-yosemite-r7-0006", 05:47:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:47:50 INFO - "platform": "yosemite_r7", 05:47:50 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:47:50 INFO - "branch": "fx-team", 05:47:50 INFO - "repo_path": "integration/fx-team", 05:47:50 INFO - "revision": "690226a9fb27054d8a20fc7625a2ebd7067573ce", 05:47:50 INFO - "stage_platform": "macosx64", 05:47:50 INFO - "builduid": "17e4e8b195af460fb7d14e34ad84a362", 05:47:50 INFO - "slavebuilddir": "test" 05:47:50 INFO - } 05:47:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:47:50 INFO - Running post-action listener: _resource_record_post_action 05:47:50 INFO - ##### 05:47:50 INFO - ##### Running download-and-extract step. 05:47:50 INFO - ##### 05:47:50 INFO - Running pre-action listener: _resource_record_pre_action 05:47:50 INFO - Running main action method: download_and_extract 05:47:50 INFO - mkdir: /builds/slave/test/build/tests 05:47: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')]} 05:47:50 INFO - https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:47:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:47:52 INFO - Downloaded 1199 bytes. 05:47:52 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:52 INFO - Using the following test package requirements: 05:47:52 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:47:52 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:47:52 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:47:52 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'jsshell-mac.zip'], 05:47:52 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:47:52 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:47:52 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:47:52 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:47:52 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:47:52 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:52 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:47:52 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:52 INFO - https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:47:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:53 INFO - Downloaded 18111271 bytes. 05:47:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:47:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:47:56 INFO - Return code: 0 05:47:56 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:56 INFO - https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:47:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:57 INFO - Downloaded 18111271 bytes. 05:47:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:47:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:48:01 INFO - Return code: 0 05:48: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')]} 05:48:01 INFO - https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:48:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:48:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:48:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:48:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:48:03 INFO - Downloaded 10840145 bytes. 05:48:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:48:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:48:03 INFO - Return code: 0 05:48:03 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')]} 05:48:03 INFO - https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:48:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:48:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:48:07 INFO - Downloaded 110145043 bytes. 05:48:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:07 INFO - mkdir: /builds/slave/test/properties 05:48:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:48:07 INFO - Writing to file /builds/slave/test/properties/build_url 05:48:07 INFO - Contents: 05:48:07 INFO - build_url:https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:48:08 INFO - Running post-action listener: _resource_record_post_action 05:48:08 INFO - Running post-action listener: set_extra_try_arguments 05:48:08 INFO - ##### 05:48:08 INFO - ##### Running populate-webroot step. 05:48:08 INFO - ##### 05:48:08 INFO - Running pre-action listener: _resource_record_pre_action 05:48:08 INFO - Running main action method: populate_webroot 05:48:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:48:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:48:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:48:08 INFO - '--tppagecycles', 05:48:08 INFO - '7'], 05:48:08 INFO - 'tests': ['tsvgm']}}, 05:48:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:48:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:48:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:48:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:48:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['damp', 'tps']}, 05:48:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'tests': ['damp', 'tps']}, 05:48:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['dromaeo_dom']}, 05:48:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:48:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['a11yr', 05:48:08 INFO - 'ts_paint', 05:48:08 INFO - 'tpaint', 05:48:08 INFO - 'sessionrestore', 05:48:08 INFO - 'sessionrestore_no_auto_restore', 05:48:08 INFO - 'tabpaint']}, 05:48:08 INFO - 'other-e10s': {'tests': ['a11yr', 05:48:08 INFO - 'ts_paint', 05:48:08 INFO - 'tpaint', 05:48:08 INFO - 'sessionrestore', 05:48:08 INFO - 'sessionrestore_no_auto_restore', 05:48:08 INFO - 'tabpaint']}, 05:48:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:48:08 INFO - 'ts_paint', 05:48:08 INFO - 'tpaint', 05:48:08 INFO - 'sessionrestore', 05:48:08 INFO - 'sessionrestore_no_auto_restore', 05:48:08 INFO - 'tabpaint']}, 05:48:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:48:08 INFO - 'ts_paint', 05:48:08 INFO - 'tpaint', 05:48:08 INFO - 'sessionrestore', 05:48:08 INFO - 'sessionrestore_no_auto_restore', 05:48:08 INFO - 'tabpaint']}, 05:48:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['a11yr', 05:48:08 INFO - 'ts_paint', 05:48:08 INFO - 'tpaint', 05:48:08 INFO - 'sessionrestore', 05:48:08 INFO - 'sessionrestore_no_auto_restore', 05:48:08 INFO - 'tabpaint']}, 05:48:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['a11yr', 05:48:08 INFO - 'ts_paint', 05:48:08 INFO - 'tpaint', 05:48:08 INFO - 'sessionrestore', 05:48:08 INFO - 'sessionrestore_no_auto_restore', 05:48:08 INFO - 'tabpaint']}, 05:48:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['tsvgx', 05:48:08 INFO - 'tsvgr_opacity', 05:48:08 INFO - 'tart', 05:48:08 INFO - 'tscrollx', 05:48:08 INFO - 'cart']}, 05:48:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:48:08 INFO - 'tsvgr_opacity', 05:48:08 INFO - 'tart', 05:48:08 INFO - 'tscrollx', 05:48:08 INFO - 'cart']}, 05:48:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'talos_options': ['--disable-e10s'], 05:48:08 INFO - 'tests': ['tp5o']}, 05:48:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'tests': ['tp5o']}, 05:48:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'talos_options': ['--disable-e10s', 05:48:08 INFO - '--xperf_path', 05:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:08 INFO - 'tests': ['tp5n']}, 05:48:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:08 INFO - 'talos_options': ['--xperf_path', 05:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:08 INFO - 'tests': ['tp5n']}}, 05:48:08 INFO - 'talos.zip': {'path': '', 05:48:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:48:08 INFO - Running post-action listener: _resource_record_post_action 05:48:08 INFO - ##### 05:48:08 INFO - ##### Running create-virtualenv step. 05:48:08 INFO - ##### 05:48:08 INFO - Running pre-action listener: _resource_record_pre_action 05:48:08 INFO - Running main action method: create_virtualenv 05:48:08 INFO - Creating virtualenv /builds/slave/test/build/venv 05:48:08 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 05:48:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:48:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:48:08 INFO - Using real prefix '/tools/python27' 05:48:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:48:09 INFO - Installing distribute.............................................................................................................................................................................................done. 05:48:12 INFO - Installing pip.................done. 05:48:12 INFO - Return code: 0 05:48:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:12 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 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:12 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 05:48:12 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 05:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:12 INFO - 'HOME': '/Users/cltbld', 05:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:12 INFO - 'LOGNAME': 'cltbld', 05:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:12 INFO - 'MOZ_NO_REMOTE': '1', 05:48:12 INFO - 'NO_EM_RESTART': '1', 05:48:12 INFO - 'PAGER': '/bin/cat', 05:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:12 INFO - 'PWD': '/builds/slave/test', 05:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:12 INFO - 'SHELL': '/bin/bash', 05:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:12 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:12 INFO - 'USER': 'cltbld', 05:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:12 INFO - 'XPC_FLAGS': '0x0', 05:48:12 INFO - 'XPC_SERVICE_NAME': '0', 05:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:12 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 05:48:12 INFO - Cleaning up... 05:48:12 INFO - Return code: 0 05:48:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:12 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:12 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>=0.7.1'] in /builds/slave/test/build 05:48:12 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>=0.7.1 05:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:12 INFO - 'HOME': '/Users/cltbld', 05:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:12 INFO - 'LOGNAME': 'cltbld', 05:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:12 INFO - 'MOZ_NO_REMOTE': '1', 05:48:12 INFO - 'NO_EM_RESTART': '1', 05:48:12 INFO - 'PAGER': '/bin/cat', 05:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:12 INFO - 'PWD': '/builds/slave/test', 05:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:12 INFO - 'SHELL': '/bin/bash', 05:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:12 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:12 INFO - 'USER': 'cltbld', 05:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:12 INFO - 'XPC_FLAGS': '0x0', 05:48:12 INFO - 'XPC_SERVICE_NAME': '0', 05:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:12 INFO - Downloading/unpacking psutil>=0.7.1 05:48:12 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 05:48:12 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 05:48:12 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 05:48:12 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 05:48:12 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 05:48:12 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 05:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:48:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:15 INFO - Installing collected packages: psutil 05:48:15 INFO - Running setup.py install for psutil 05:48:15 INFO - building 'psutil._psutil_osx' extension 05:48:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:48:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:48:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:48:15 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:48:16 INFO - building 'psutil._psutil_posix' extension 05:48:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:48:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:48:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:48:16 INFO - ^ 05:48:16 INFO - 1 warning generated. 05:48:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:16 INFO - Successfully installed psutil 05:48:16 INFO - Cleaning up... 05:48:16 INFO - Return code: 0 05:48:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:16 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:16 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.0.0'] in /builds/slave/test/build 05:48:16 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.0.0 05:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:16 INFO - 'HOME': '/Users/cltbld', 05:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:16 INFO - 'LOGNAME': 'cltbld', 05:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:16 INFO - 'MOZ_NO_REMOTE': '1', 05:48:16 INFO - 'NO_EM_RESTART': '1', 05:48:16 INFO - 'PAGER': '/bin/cat', 05:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:16 INFO - 'PWD': '/builds/slave/test', 05:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:16 INFO - 'SHELL': '/bin/bash', 05:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:16 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:16 INFO - 'USER': 'cltbld', 05:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:16 INFO - 'XPC_FLAGS': '0x0', 05:48:16 INFO - 'XPC_SERVICE_NAME': '0', 05:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:48:16 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 05:48:16 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 05:48:16 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 05:48:16 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 05:48:16 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 05:48:16 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 05:48:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:48:19 INFO - Installing collected packages: mozsystemmonitor 05:48:19 INFO - Running setup.py install for mozsystemmonitor 05:48:19 INFO - Successfully installed mozsystemmonitor 05:48:19 INFO - Cleaning up... 05:48:19 INFO - Return code: 0 05:48:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:19 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 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:19 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 05:48:19 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 05:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:19 INFO - 'HOME': '/Users/cltbld', 05:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:19 INFO - 'LOGNAME': 'cltbld', 05:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:19 INFO - 'MOZ_NO_REMOTE': '1', 05:48:19 INFO - 'NO_EM_RESTART': '1', 05:48:19 INFO - 'PAGER': '/bin/cat', 05:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:19 INFO - 'PWD': '/builds/slave/test', 05:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:19 INFO - 'SHELL': '/bin/bash', 05:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:19 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:19 INFO - 'USER': 'cltbld', 05:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:19 INFO - 'XPC_FLAGS': '0x0', 05:48:19 INFO - 'XPC_SERVICE_NAME': '0', 05:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:19 INFO - Downloading/unpacking blobuploader==1.2.4 05:48:19 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 05:48:19 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 05:48:19 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 05:48:19 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 05:48:19 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 05:48:19 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 05:48:21 INFO - Downloading blobuploader-1.2.4.tar.gz 05:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:48:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:48: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 05:48: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 05:48: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 05:48: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 05:48: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 05:48: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 05:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:48:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:48: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 05:48: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 05:48: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 05:48: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 05:48: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 05:48: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 05:48:23 INFO - Downloading docopt-0.6.1.tar.gz 05:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:48:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:48:23 INFO - Running setup.py install for blobuploader 05:48:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:48:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:48:23 INFO - Running setup.py install for requests 05:48:23 INFO - Running setup.py install for docopt 05:48:24 INFO - Successfully installed blobuploader requests docopt 05:48:24 INFO - Cleaning up... 05:48:24 INFO - Return code: 0 05:48:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:24 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 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:24 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 05:48:24 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 05:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:24 INFO - 'HOME': '/Users/cltbld', 05:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:24 INFO - 'LOGNAME': 'cltbld', 05:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:24 INFO - 'MOZ_NO_REMOTE': '1', 05:48:24 INFO - 'NO_EM_RESTART': '1', 05:48:24 INFO - 'PAGER': '/bin/cat', 05:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:24 INFO - 'PWD': '/builds/slave/test', 05:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:24 INFO - 'SHELL': '/bin/bash', 05:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:24 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:24 INFO - 'USER': 'cltbld', 05:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:24 INFO - 'XPC_FLAGS': '0x0', 05:48:24 INFO - 'XPC_SERVICE_NAME': '0', 05:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-kuhcqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ixyMxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-2Lkhpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-uPj090-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ZAUIF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-sIAC5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-_xnlKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Jd6buv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-k0Kw4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-VgKoSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0kfkYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-h0G_zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-zPBkGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-zSf8rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Rz8d8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-dl3idy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-SILNDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:48:26 INFO - Running setup.py install for manifestparser 05:48:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:48:26 INFO - Running setup.py install for mozcrash 05:48:26 INFO - Running setup.py install for mozdebug 05:48:26 INFO - Running setup.py install for mozdevice 05:48:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:48:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:48:26 INFO - Running setup.py install for mozfile 05:48:26 INFO - Running setup.py install for mozhttpd 05:48:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Running setup.py install for mozinfo 05:48:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Running setup.py install for mozInstall 05:48:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Running setup.py install for mozleak 05:48:27 INFO - Running setup.py install for mozlog 05:48:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Running setup.py install for moznetwork 05:48:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Running setup.py install for mozprocess 05:48:27 INFO - Running setup.py install for mozprofile 05:48:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:48:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:48:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:48:28 INFO - Running setup.py install for mozrunner 05:48:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:48:28 INFO - Running setup.py install for mozscreenshot 05:48:28 INFO - Running setup.py install for moztest 05:48:28 INFO - Running setup.py install for mozversion 05:48:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:48:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:48:28 INFO - Cleaning up... 05:48:28 INFO - Return code: 0 05:48:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:28 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 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:28 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 05:48:28 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 05:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:28 INFO - 'HOME': '/Users/cltbld', 05:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:28 INFO - 'LOGNAME': 'cltbld', 05:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:28 INFO - 'MOZ_NO_REMOTE': '1', 05:48:28 INFO - 'NO_EM_RESTART': '1', 05:48:28 INFO - 'PAGER': '/bin/cat', 05:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:28 INFO - 'PWD': '/builds/slave/test', 05:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:28 INFO - 'SHELL': '/bin/bash', 05:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:28 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:28 INFO - 'USER': 'cltbld', 05:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:28 INFO - 'XPC_FLAGS': '0x0', 05:48:28 INFO - 'XPC_SERVICE_NAME': '0', 05:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6TfPNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-N2Xcbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ggB6up-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-eLWF4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-iSHFOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-pRp301-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-t02kgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-yCPcLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:29 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)) 05:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-MHn5C6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:30 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jjNoUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-JKp3_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:30 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-R3UYSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:30 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-qyyWoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:30 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-I7zpzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:30 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-n0zdEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:30 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-kcWMuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:30 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)) 05:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5USxwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:30 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)) 05:48:30 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)) 05:48:30 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)) 05:48:30 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)) 05:48:30 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)) 05:48:30 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)) 05:48:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:33 INFO - Downloading blessings-1.6.tar.gz 05:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:48:33 INFO - Installing collected packages: blessings 05:48:33 INFO - Running setup.py install for blessings 05:48:33 INFO - Successfully installed blessings 05:48:33 INFO - Cleaning up... 05:48:33 INFO - Return code: 0 05:48:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:48:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:33 INFO - Reading from file tmpfile_stdout 05:48:33 INFO - Current package versions: 05:48:33 INFO - blessings == 1.6 05:48:33 INFO - blobuploader == 1.2.4 05:48:33 INFO - docopt == 0.6.1 05:48:33 INFO - manifestparser == 1.1 05:48:33 INFO - mozInstall == 1.12 05:48:33 INFO - mozcrash == 0.17 05:48:33 INFO - mozdebug == 0.1 05:48:33 INFO - mozdevice == 0.48 05:48:33 INFO - mozfile == 1.2 05:48:33 INFO - mozhttpd == 0.7 05:48:33 INFO - mozinfo == 0.9 05:48:33 INFO - mozleak == 0.1 05:48:33 INFO - mozlog == 3.1 05:48:33 INFO - moznetwork == 0.27 05:48:33 INFO - mozprocess == 0.22 05:48:33 INFO - mozprofile == 0.28 05:48:33 INFO - mozrunner == 6.11 05:48:33 INFO - mozscreenshot == 0.1 05:48:33 INFO - mozsystemmonitor == 0.0 05:48:33 INFO - moztest == 0.7 05:48:33 INFO - mozversion == 1.4 05:48:33 INFO - psutil == 3.1.1 05:48:33 INFO - requests == 1.2.3 05:48:33 INFO - wsgiref == 0.1.2 05:48:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:33 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 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:33 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 05:48:33 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 05:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:34 INFO - 'HOME': '/Users/cltbld', 05:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:34 INFO - 'LOGNAME': 'cltbld', 05:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:34 INFO - 'MOZ_NO_REMOTE': '1', 05:48:34 INFO - 'NO_EM_RESTART': '1', 05:48:34 INFO - 'PAGER': '/bin/cat', 05:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:34 INFO - 'PWD': '/builds/slave/test', 05:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:34 INFO - 'SHELL': '/bin/bash', 05:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:34 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:34 INFO - 'USER': 'cltbld', 05:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:34 INFO - 'XPC_FLAGS': '0x0', 05:48:34 INFO - 'XPC_SERVICE_NAME': '0', 05:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 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)) 05:48:34 INFO - Cleaning up... 05:48:34 INFO - Return code: 0 05:48:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:48:34 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')]} 05:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:34 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')]} 05:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:34 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 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:34 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 05:48:34 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 05:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:34 INFO - 'HOME': '/Users/cltbld', 05:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:34 INFO - 'LOGNAME': 'cltbld', 05:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:34 INFO - 'MOZ_NO_REMOTE': '1', 05:48:34 INFO - 'NO_EM_RESTART': '1', 05:48:34 INFO - 'PAGER': '/bin/cat', 05:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:34 INFO - 'PWD': '/builds/slave/test', 05:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:34 INFO - 'SHELL': '/bin/bash', 05:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:34 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:34 INFO - 'USER': 'cltbld', 05:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:34 INFO - 'XPC_FLAGS': '0x0', 05:48:34 INFO - 'XPC_SERVICE_NAME': '0', 05:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:34 INFO - Downloading/unpacking jsonschema 05:48:34 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 05:48:34 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 05:48:34 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 05:48:34 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 05:48:34 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 05:48:34 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 05:48:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:48:36 INFO - Downloading/unpacking functools32 (from jsonschema) 05:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:37 INFO - Downloading functools32-3.2.3-2.tar.gz 05:48:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:48:37 INFO - warning: no files found matching '*.txt' 05:48:37 INFO - no previously-included directories found matching 'build' 05:48:37 INFO - no previously-included directories found matching 'dist' 05:48:37 INFO - no previously-included directories found matching '.git*' 05:48:37 INFO - Installing collected packages: jsonschema, functools32 05:48:37 INFO - Running setup.py install for functools32 05:48:37 INFO - warning: no files found matching '*.txt' 05:48:37 INFO - no previously-included directories found matching 'build' 05:48:37 INFO - no previously-included directories found matching 'dist' 05:48:37 INFO - no previously-included directories found matching '.git*' 05:48:37 INFO - Successfully installed jsonschema functools32 05:48:37 INFO - Cleaning up... 05:48:37 INFO - Return code: 0 05:48:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100b10a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b20420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd11d82c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:37 INFO - 'HOME': '/Users/cltbld', 05:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:37 INFO - 'LOGNAME': 'cltbld', 05:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:37 INFO - 'MOZ_NO_REMOTE': '1', 05:48:37 INFO - 'NO_EM_RESTART': '1', 05:48:37 INFO - 'PAGER': '/bin/cat', 05:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:37 INFO - 'PWD': '/builds/slave/test', 05:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:37 INFO - 'SHELL': '/bin/bash', 05:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:48:37 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:37 INFO - 'USER': 'cltbld', 05:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:37 INFO - 'XPC_FLAGS': '0x0', 05:48:37 INFO - 'XPC_SERVICE_NAME': '0', 05:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:37 INFO - Downloading/unpacking flake8 05:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:48:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:48:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:48:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:48:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:48:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:48:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:48:41 INFO - no previously-included directories found matching 'docs/_build' 05:48:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:48:41 INFO - Running setup.py install for pep8 05:48:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:48:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:48:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:48:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:48:41 INFO - no previously-included directories found matching 'docs/_build' 05:48:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:48:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:48:41 INFO - Cleaning up... 05:48:42 INFO - Return code: 0 05:48:42 INFO - Running post-action listener: _resource_record_post_action 05:48:42 INFO - Running post-action listener: _start_resource_monitoring 05:48:42 INFO - Starting resource monitoring. 05:48:42 INFO - ##### 05:48:42 INFO - ##### Running install step. 05:48:42 INFO - ##### 05:48:42 INFO - Running pre-action listener: _resource_record_pre_action 05:48:42 INFO - Running main action method: install 05:48:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:42 INFO - Reading from file tmpfile_stdout 05:48:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:48:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:48:42 INFO - Reading from file tmpfile_stdout 05:48:42 INFO - Output received: 05:48:42 INFO - Usage: mozinstall [options] installer 05:48:42 INFO - Options: 05:48:42 INFO - -h, --help show this help message and exit 05:48:42 INFO - -d DEST, --destination=DEST 05:48:42 INFO - Directory to install application into. [default: 05:48:42 INFO - "/builds/slave/test"] 05:48:42 INFO - --app=APP Application being installed. [default: firefox] 05:48:42 INFO - mkdir: /builds/slave/test/build/application 05:48:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:49:15 INFO - Reading from file tmpfile_stdout 05:49:15 INFO - Output received: 05:49:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:49:15 INFO - Running post-action listener: _resource_record_post_action 05:49:15 INFO - ##### 05:49:15 INFO - ##### Running run-tests step. 05:49:15 INFO - ##### 05:49:15 INFO - Running pre-action listener: _resource_record_pre_action 05:49:15 INFO - Running main action method: run_tests 05:49:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:49:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:49:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:49:15 INFO - 2016-04-25 05:49:15.850 screenresolution[3386:50985] starting screenresolution argv=screenresolution get 05:49:15 INFO - 2016-04-25 05:49:15.852 screenresolution[3386:50985] Display 0: 1600x1200x32@60 05:49:15 INFO - 2016-04-25 05:49:15.858 screenresolution[3387:50987] starting screenresolution argv=screenresolution list 05:49:15 INFO - Available Modes on Display 0 05:49:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:49:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:49:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:49:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:49:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:49:16 INFO - Intel Iris: 05:49:16 INFO - Chipset Model: Intel Iris 05:49:16 INFO - Type: GPU 05:49:16 INFO - Bus: Built-In 05:49:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:49:16 INFO - Vendor: Intel (0x8086) 05:49:16 INFO - Device ID: 0x0a2e 05:49:16 INFO - Revision ID: 0x0009 05:49:16 INFO - Displays: 05:49:16 INFO - Display: 05:49:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:49:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:49:16 INFO - Display Serial Number: 1600x1200 60 05:49:16 INFO - Main Display: Yes 05:49:16 INFO - Mirror: Off 05:49:16 INFO - Online: Yes 05:49:16 INFO - Rotation: Supported 05:49:16 INFO - Return code: 0 05:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:49:16 INFO - Python 2.7.3 05:49:16 INFO - Return code: 0 05:49:16 INFO - grabbing minidump binary from tooltool 05:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009afe00>, '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 0x100818db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:49:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:49:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:49:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:49:16 INFO - Return code: 0 05:49:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:49:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:49:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:49:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:49:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:49:17 INFO - Return code: 0 05:49:17 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:49:17 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render', 05:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:17 INFO - 'HOME': '/Users/cltbld', 05:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:17 INFO - 'LOGNAME': 'cltbld', 05:49:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:49:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:17 INFO - 'MOZ_NO_REMOTE': '1', 05:49:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:49:17 INFO - 'NO_EM_RESTART': '1', 05:49:17 INFO - 'PAGER': '/bin/cat', 05:49:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:17 INFO - 'PWD': '/builds/slave/test', 05:49:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:17 INFO - 'SHELL': '/bin/bash', 05:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners', 05:49:17 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 05:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:17 INFO - 'USER': 'cltbld', 05:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:17 INFO - 'XPC_FLAGS': '0x0', 05:49:17 INFO - 'XPC_SERVICE_NAME': '0', 05:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:49:17 INFO - mozversion application_buildid: 20160425033757 05:49:17 INFO - mozversion application_changeset: 690226a9fb27054d8a20fc7625a2ebd7067573ce 05:49:17 INFO - mozversion application_display_name: Nightly 05:49:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:49:17 INFO - mozversion application_name: Firefox 05:49:17 INFO - mozversion application_remotingname: firefox 05:49:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:49:17 INFO - mozversion application_vendor: Mozilla 05:49:17 INFO - mozversion application_version: 48.0a1 05:49:17 INFO - mozversion platform_buildid: 20160425033757 05:49:17 INFO - mozversion platform_changeset: 690226a9fb27054d8a20fc7625a2ebd7067573ce 05:49:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:49:17 INFO - mozversion platform_version: 48.0a1 05:49:17 INFO - using testdate: 1461588557 05:49:17 INFO - actual date: 1461588557 05:49:17 INFO - starting webserver on 'localhost:64768' 05:49:17 INFO - SUITE-START | Running 2 tests 05:49:17 INFO - TEST-START | dromaeo_css 05:49:17 INFO - operating with platform_type : mac_ 05:49:17 INFO - Initialising browser for dromaeo_css test... 05:49:17 INFO - TEST-INFO | started process 3405 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpjPd7rQ/profile http://localhost:64768/getInfo.html) 05:49:20 INFO - PROCESS | 3405 | __metrics Screen width/height:1600/1200 05:49:20 INFO - PROCESS | 3405 | colorDepth:24 05:49:20 INFO - PROCESS | 3405 | Browser inner width/height: 1024/689 05:49:20 INFO - PROCESS | 3405 | __metrics 05:49:20 ERROR - PROCESS | 3405 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:49:21 INFO - TEST-INFO | 3405: exit 0 05:49:21 INFO - Browser initialized. 05:49:21 INFO - Running cycle 1/1 for dromaeo_css test... 05:49:21 INFO - TEST-INFO | started process 3408 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpjPd7rQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:33 INFO - PROCESS | 3408 | RSS: Main: 249970688 05:49:33 INFO - PROCESS | 3408 | 05:50:22 INFO - PROCESS | 3408 | 1461588622849 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] 05:52:33 INFO - PROCESS | 3408 | Cycle 1(1): loaded http://localhost:64768/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64768/tests/dromaeo/cssquery-ext.html) 05:52:34 INFO - PROCESS | 3408 | RSS: Main: 261844992 05:52:34 INFO - PROCESS | 3408 | 05:55:37 INFO - PROCESS | 3408 | Cycle 1(1): loaded http://localhost:64768/tests/dromaeo/cssquery-ext.html (next: http://localhost:64768/tests/dromaeo/cssquery-jquery.html) 05:55:37 INFO - PROCESS | 3408 | RSS: Main: 273625088 05:55:37 INFO - PROCESS | 3408 | 05:58:39 INFO - PROCESS | 3408 | Cycle 1(1): loaded http://localhost:64768/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64768/tests/dromaeo/cssquery-mootools.html) 05:58:40 INFO - PROCESS | 3408 | RSS: Main: 275820544 05:58:40 INFO - PROCESS | 3408 | 06:01:41 INFO - PROCESS | 3408 | Cycle 1(1): loaded http://localhost:64768/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64768/tests/dromaeo/cssquery-prototype.html) 06:01:42 INFO - PROCESS | 3408 | RSS: Main: 277364736 06:01:42 INFO - PROCESS | 3408 | 06:04:45 INFO - PROCESS | 3408 | Cycle 1(1): loaded http://localhost:64768/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64768/tests/dromaeo/cssquery-yui.html) 06:04:45 INFO - PROCESS | 3408 | RSS: Main: 280281088 06:04:45 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | Cycle 1(1): loaded http://localhost:64768/tests/dromaeo/cssquery-yui.html (next: http://localhost:64768/tests/dromaeo/cssquery-dojo.html) 06:07:48 INFO - PROCESS | 3408 | RSS: Main: 281493504 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | __start_tp_report 06:07:48 INFO - PROCESS | 3408 | _x_x_mozilla_page_load 06:07:48 INFO - PROCESS | 3408 | _x_x_mozilla_page_load_details 06:07:48 INFO - PROCESS | 3408 | |i|pagename|runs| 06:07:48 INFO - PROCESS | 3408 | |0;dojo.html;3458.4812278158274;3465.151037995691;3487.4420092785144;3507.0706262840354;3512.4731290306436;5782.306123265209;5782.797602083928;5828.300603927524;5829.650220986744;5885.791658963851;5199.584033277338;5833.649981001143;5882.794102206423;5929.970350148254;6019.849503762408;5210.817621383262;5239.711815850127;5290.761915713783;5291.947080529203;5350;203.65789215250732;4219.662427005847;5182.533571978521;5277.1556550951855;5295.735213239323;5258.237555554423;5292.973535132319;5293.073712100367;5299.125644268691;5348.224507446418;5313.893722125575;5330.946690533084;5361.303030605996;5361.973190134025;5364.839054828361;5186.559142472895;5262.23697563855;5269.710165940872;5361.758720857571;5371.865703357408;4266.338717498793;4630.976845115752;5715.457031582035;5736.684482353469;5851.297844258674;6190.597611155261;6254.8741226579195;6263.686815659199;6304.653244071575;6314.652694101823;5322;6287.811365659038;6344.6510441925675;6385.074164246204;6393;6029.758809647609;6041.395860413967;6056.152138700585;6057.091436284549;6063.8484037898525;6307.842303942438;6396.904046439307;6490.896547586922;6494.090827284184;6509.511786615928;6148.262208535004;6160.568760186834;6251.031090181029;6256.937430625636;6286.937130628727;6325.114483972247;6338.112664227012;6358.554901156967;6387.552871299057;6392.680365981775;6171.56799024073;6206.968965155145;6284.622922624657;6287.591306565058;6292.2134733158355;6290.779822706275;6295.307516173218;6319.652419116901;6327.873442531123;6337;4247.787610619456;5007.424146223231;5010.123228435009;5017.473165317662;5028.698278103326;4470.329450582439;4504.076664283814;4541.545845415432;4554.772261386917;4610;5839.824805255849;5944.167816505693;5962.672053037038;5989.431004054608;6063.969125248718;5315.867103322448;6298.401651843067;6309.08518264849;6312.747490100344;6322.0200868865395;5821.854453638693;5995.820125396245;6001.579889407787;6090.116933044772;6104.023356263066;5473.343198816167;5474.233607294982;5489.176623506506;5503.697296648723;5509.696966666792;6505.381988711065;6570.967145164243;6595.373439523238;6597.670116494156;6609.768658096944;6169.259688837368;6270.216222972129;6286.65423401699;6340.587861788969;6370.331115232834;6500.089987401673;6508.967455162694;6535.44448721864;6560.967195163994;6577.210734711865;6240.063990401476;6249;6252.437280644582;6267.4986001120715;6273.654948977667;6209.565330426827;6265.780697675559;6278;6318.526110541636;6334.651594162366;5593.188987596857;6215.171893158975;6257.530685198537;6264.342244064308;6266.342034086538;6142.539309551712;6226.377362263918;6260.812175634738;6267.435930766253;6269.341719119427;5832.766689332379;6018.157457955802;6118.388161184024;6125.418593525838;6186.876262474635;6201.81394558164;6241.96879015602;6249.468795152281;6256.155419018556;6289.371062893674;6065.787697430569;6081.27024757032;6100.944524685319;6188.1027251049245;6199.504039676905 06:07:48 INFO - PROCESS | 3408 | |1;ext.html;482.18992093283293;499.13400250565275;529.1137344947213;539.8299535646271;544.1755740053817;37791.622083779155;38106.80946595267;38993.41509877354;39006;39014.219715605745;40024;40758.184836303255;40772.388414173736;40778.79610601943;40972.79513602435;8139.593020348973;9382.483963382012;9444.952775236114;9572.56923438447;9586.281028922824;9444.669436569722;9610.663626773065;9678.564464599074;9684.61261549537;9709.95145024274;9663.903360966371;9694.515274236295;9744.853827192563;9759.121679048883;9808.950955245215;9674.419534827897;9723.367981081206;9726.902730972706;9733.02669733021;9766.218702503782;9496.192823610001;9501.097395747393;9590.808183836354;9609.087136722;9734.02659734028;37910.810445947594;38040.42939355912;38194.85415437541;38420.42369364462;40090.398644020366;2885.581590669341;3113.486274764661;3182.3158021025592;3307.338532293551;3457.844397002141;21592.89203553988;22400.887995560082;26958.326041848915;27672.446551068864;28155.01457449;2327.511222643247;2623.331050582108;2654.2568080937367;2711.227300219428;2766.9070717066847;350556;352201;353240;353327;354414;34513.65486345105;47635.57093287207;60234.39765602288;60995.780084398066;61010.694946524985;28903;29172.562411563842;35702.64297357045;35781.1054723634;36015.4597681035;14728.263586820616;14981.101133932;15115.244237788067;15302.846971530365;15334.23328833554;10704.30422022566;12746.872531274568;12817.295048772407;12839.935800320938;12877.227366358049;8146.389020823462;8168.42821002536;8227.382946279053;8274.048484424367;8310.293625041939;3474.7915125092577;3505.614382417932;3507.2108775525303;3510.7542472027226;3517.874280230302;847.2925107535277;899.7750562359411;955.1165172215673;1022.1465076660922;1157.484919210943;29221.707782922324;29387.706122938926;29551.40897182044;29857.40285194284;29949.401011979637;1019.938803671782;1107.5126944144552;1136.7612801311818;1188.6849984754047;1204.3978010994504;12887.742245155232;14646;14813.851861481462;15138.697226055638;15224.771628425582;484861;497825;500287;501199;613892;59191.70404148038;59881.70059149677;60028;60056;60292.397076029556;1415.7027024325007;1435.5621535431585;1467.9412823487155;1522.9923850380678;1523.3373482535133;28747.28131796722;29075.7092429073;29079.56380654334;29508.852455737582;29509;49224.76938076577;50263;53718.73140634272;54675.17987230115;54798;529789;553272.2336388292;553837;556619;559120;26530.867345663148;27144.864275678494;27204.455910881275;27239.182824515297;27560.862195689697;38973.805130974164;45465.54534454613;45496.09007819759;45744.77127614341;46118.76940615275;37714;37881.431778523896;37949.62050379571;38567;38667.2266554673;32805.03169809819;33847.32305353929;36871.63128368682;37739.81130094332;38260.234795304495;30588;35363.11592210215;36565.45151822778;37280;37422.438663419525;30454.54318185185;31244.687553125088;31257.687423126386;31307.686923130477;31318.686813132488;19115.426537203904;22059.77940220577;22085.785281809654;23558.764412355657;24777.62833557462 06:07:48 INFO - PROCESS | 3408 | |2;jquery.html;390.8534299637636;397.65205445235426;400.47537725579485;401.06751802060217;407.82667366369304;20124;20379.694304585424;20999.790002099973;21211.72729636221;21237.469063273427;19414.126364313604;19764.40706778799;19787.802121978777;20016.199352025902;20094.09576569054;5083.771230294637;5975.43233392827;6020.217371741689;6022.036474164134;6048.243969503812;5677.744501497443;5844.795432159875;5871.55963302754;5960.314563825151;5967.761289548413;5852.561057920651;5870.412958704138;5901.439363260483;5910.0248459004215;5915.319738230116;5642.520385767215;5844.824655260349;5893.174955506232;5902.291724992985;5943.286805583358;5079.517445842639;5151.948480515184;5153.510416510463;5187.662801917901;5190.4031036430915;22787.430314242276;25472.49055018908;25782.871085644638;25885;25964.052696047682;2024.7671517775498;2145.302776597612;2317.281642690771;2497.6378425128437;2713.3487962888767;12208.694782630506;14522.273886305642;14918;15027.474038408713;15328.463503777315;1681.2350380576809;1685.8482736553647;1745.5636090977255;1771.070188151218;1771.636814453034;85723.57138214332;122072;124144.37927810304;125540;127353.36323318323;10659.893401066045;10714.892851071545;11394.544218231344;11502.94248528752;11640.825387619023;13206.405711742802;14067.648308792363;14085.71828563423;14089.084209526349;14105.224212668401;6776.593404395752;8690.304775617935;8766.736997890073;8812.942446906412;8840.425372350777;8864;8864.468131912106;8924.553772311489;8929;8942.46345219289;6632.403083722488;6695.631740254235;6757.662116894234;6787.1176747023555;6787.830304242433;1664.8085470171086;1802.873798834069;1894.507428068712;1895.4882181810833;1920.7310976463307;963.6579014449909;978.4227305889559;1017.8549132226245;1085.5766251162054;1085.9846043948776;19881.701774473393;20857.791422085887;21067.103960643617;21114.472138196666;21203.36389908305;871.9171678690515;989.6684610655367;999.8400255959011;1009.4843887259284;1019.6635110413546;11034.613788517363;11139.777204455866;11272.943635281936;11287.121783945042;11359.943200284111;564770;566142;568663;572364.2907127845;575184.6203844937;123652.76347236412;126696;126921;128439;129943;932.9720108396759;940.9931373430501;1044.0916402729674;1069.3690722473623;1071.0414179309475;16626.50120496387;18719.532011699815;18850.24599016024;18857.811421885606;19100.522486937938;49295.50704493053;51702;54103.72948135234;54340.72829635826;54415.455845441036;71797.6410117946;72324.27675723386;73098;73654;74254.2574574269;6097.817065488042;7858.29275365197;7936.920630793849;8053.234942680436;8136.22705842944;11114.944425277821;12399.380030998234;12415.193012454523;12548.43532041042;12752.74494510123;10520.421376824348;10546.683599492027;10953.726156846142;10987;11003.17476189273;8948.865767013369;10106.494675266355;10712.250142489951;11128.1653875958;11320.547178113113;10807.405592692478;11344.319340839576;11406.714832129263;11448.141389395661;11468.967792898678;7933.563653999086;8040.678372865254;8197.71308004217;8235.13531079228;8277.255047045795;10106.444145572064;11053.226274160732;11238.7752244953;11339.149563782585;11341.149413794163 06:07:48 INFO - PROCESS | 3408 | |3;mootools.html;1437.4034775568136;1596.3056070609287;1833.257530700067;1944.3389247655793;1948.6979518174678;6762.695678694458;8196.91803081969;8407.453515521494;8450.619722112504;8660.177283158106;8643.308535317175;8691.348148888826;8742.125787421255;8779.648814047447;8819.118088191177;2480.9951969452354;2496.6005439129744;2514.497100579882;2517.2070797698666;2531.0635065025967;2468.419921318489;2500.1874390823054;2532.050481069599;2532.3795670060767;2539.0605475973825;2474.5793215153467;2486.3411196033067;2505.812064095191;2530.1523989463553;2539.733328000561;2061.8556701030934;2474.5669507836055;2475.6039033754514;2494.3390001649577;2501.7373175816642;2451.9141830035865;2479.6776419065404;2499.7250302466714;2500.237427584586;2514.8491090534444;8929.821403571957;8966.417182883091;8976.955115224382;9009.729708108702;9027.277817774628;5366.70483123428;5507.614466987313;5573.331200255955;5585.273914391103;5615.298087739033;3591.3535563598543;3592.0301518590095;3611.2235869287965;3654.7807131571926;3678.1172518595613;998.4408731110601;1042.7080417483116;1046.0062940206833;1048.486241741541;1066.7386490309846;10785.514651840685;11227.719307017309;11785.351805650685;11925.463354149084;11925.761484770255;4202.705810593229;4922.138625740481;5021.246812978083;5038.47096054909;5204.4014938281825;8942.7033080203;9823.803523929482;10174.084332410119;10185.134263587677;10228.53971571281;5301.072312345401;5449.01917654818;5451.236826844166;5514.448555144453;5532.640378375393;4442.511323754385;4487.6185524230805;4890.437599676012;4895.0209958008545;5074.873128171826;4076.449679293317;4099.525830790178;4111.5477297497255;4179.226843034048;4180.623743863067;1347.7075514486392;1374.29223949666;1381.2196109198471;1383.9100458470368;1389.979150312746;842.6587232170981;914.7895983923735;949.8622699708503;955.8661787349777;962.119660510625;6027.246594175728;6112.786052488142;6141.57009009374;6155.10750941111;6177.0508178299415;456.9520200378979;467.2080823005042;473.1506945033673;476.9618430525619;481.508860961817;4031.07285324377;4061.4517040199194;4085.264652362544;4121.651841176727;4138.089620283533;10512.42181680015;10569.365838049742;10905.90940905919;10920;10930.508127134299;10517;10801.621943231949;10843.132549396028;10858.131349492021;10877.83683244752;6960.512764106464;7771.611419429006;8236.794080148044;8322.16778322175;8424.747257582283;6024.126501657148;6060.151578779062;6069.119977603134;6158.291796443466;6160.938390616216;8347.624356903832;9905.950470247602;10134.087932086148;10228.948855255676;10234.130098941376;8192.385571082074;10111.797764044532;10318.845217321896;10526.31578947366;10529.57881684724;6465.838354041187;6988.021676965331;7406.222346653739;7611.505252158592;7674.654640541077;8433.030201526657;8462.449940753831;8463.830723385621;8473.618687159216;8517.872231916403;7377.631118444164;7536.472446928662;7600.2779735925;7620.504667196615;7633.160352361125;7368.336849683555;7379.298966598165;7402.444816638881;7469.215732348242;7470.92529074724;7028.753993610199;7088.468364872552;7116.786496405116;7118.644067796486;7125.572465652078;4818.494058123987;4868.2940973557925;4938.9259161111895;4939.580135688507;5228.895422091461;4761.357216775829;4868.148074087091;4882.316475693335;4944.975275123746;5020.698758074527 06:07:48 INFO - PROCESS | 3408 | |4;prototype.html;320.89410494536804;322.86439695327965;327.69360647794326;330.6082292483406;332.84190009745373;4912.950870491294;4968.453470118284;5324.440933701963;5766.077427611588;5796.362400135992;4812.181929072058;5320.122179840322;5326.174442961337;5654.9151762723595;5689.715514224293;3559.341521818472;3678.8227767114536;3701.8334174962124;3912.667423269011;4078.653314468259;4244.278472659656;4260.467441569804;4263.914721705564;4312.698111132222;4323.762193079379;4246.575342465744;4255.191513612419;4271.103068355634;4271.786410679453;4276.935845962313;3900.239453306592;4280.315149576053;4290.356446533029;4297.570242975708;4303.440552728157;3104.441200583894;3235.0133209371133;3714.832832522543;3738.280391020134;3741.7567858089137;4283.571642835723;4609.746463944482;5271.841844744664;5441.782328706847;5549.973254947848;4624.606908412789;4887.608991280689;5002.274670172805;5003.199488081926;5029.949700502984;5186.34362774247;5502.028904391624;5959.910601340984;5999.700014999276;6026.8493287667725;3555.5022296878456;3709.6290370962956;3914.5889681583308;4044.51465824103;4064.248114098901;5198.116320225571;5419.620626556142;6024.246969128859;6143.846403839895;6332.525060620425;3798.6771124454176;4061.593840615915;4088.468499095158;4093.815778289984;4107.54816970133;3266.738660907122;3737.588865224823;4149.792510374469;4167.395727619478;4171.957010747313;4580.198465268565;4864.78108485119;4880.999395123991;4886.29148773433;4894.388201474816;5284.471552844761;5463.69949652765;5705.74324155414;5983.820485385445;5988.640681559121;1280.0911352939331;1311.3377744239099;1438.9208593527455;1511.4507629679667;1516.7042426726682;856.9185927336893;879.4942907828024;924.3621900888365;962.8507581324906;988.2983082011853;5124.205748109049;5305.071612467803;5365.597580181502;5699.943000570024;6076.453119219292;6071.844495204227;6144.539159563051;6674.365935236145;6700.296468870796;6723.890558057963;5427.891442171134;6071.726772295257;6104.664243466563;6109.5481053205685;6153.815385538441;3354.6142193647556;3437.5359326491102;3542.2030043240065;3576.2668652926086;3579.2304654499408;6123.142760013601;6299.5275354348605;6336.778212762531;6961.547499412522;7024.789256322319;6036.909446358308;6907.412869906014;6929.272426395257;6961.990511375822;6999.965000174967;5827.679477628772;6428.678566071678;6641.900371494431;7007.878739401774;7028.0863487746265;5381.354237491526;6667;6680.4989625779235;6686.425726095391;6690.899636505359;5804.883902322014;6046.455818976313;6530.934690653033;6649.202095748541;6676.666166691549;5493.9286839066;5671.546276297969;5903.553629360834;6039.728212230548;6482.54622176462;4048.716589838683;4114.732542384735;4119.794010299533;4136.896577585584;4139.716687826663;5683.573731970201;5929.555283353679;6022.844237476127;6175.320714721467;6253.3741227280325;5664.178694089252;5979;6044.9395506044375;6057.939420605914;6059.606125601822;4832.1543729846435;5937.643741375531;6046.334903160561;6053.182820319201;6076.939230607814;4271.8718438446895;5632.211490391266;5790.639199788131;5858.970705146447;5896.727316339473;5287.391949925808;5340.279062326536;5977.581569290108;6064.635457022135;6090.878182436415;5690.089585666274;5977.103434484862;5980.970095149496;5981.790637327673;6016.315431679137 06:07:48 INFO - PROCESS | 3408 | |5;yui.html;682.3756263019336;721.4553012475584;745.5191401546;764.2968469008512;778.8091917480216;14600.70798584029;14968.700625987474;15048.924755376207;15072.246387680614;15122.697546049072;12154.209976351553;13629.25039122848;13742.450301987908;13754.312284385791;13776.517821876236;576.4494907363479;798.7284323330067;893.714011516315;896.1531352871507;897.824924139793;830.4270053662954;895.4850960697593;906.1210625693067;935.7567032571512;938.4650749073031;841.1336323586715;972.4165500699559;975.4732444479972;979.1970584121024;981.1071924548662;946.5740416812462;962.1581116523042;965.9420434773936;966.6809952715587;968.0706521739139;640.3084668557947;686.1834417043702;686.2794066230438;720.1430297945428;720.2761224968873;13221.942244620408;15169.862260330408;15233;15332.693346133126;15463.381464741396;10584.153267738562;11957.521699132025;12200.572979945659;12323.5070597176;12432.875671243262;9933.552990115462;9936.304458687895;9947.20422366209;10021.59913603455;10460.633877814316;2865.7564107050925;3397.575303087114;3420.435628121357;3434.4161492546327;3444.932071057955;79961.60019199867;82183;82990.58504707499;83126.9218269542;83753.04346087173;9037.09629037091;9080.455172689659;9169.724908252763;9270.768230794283;9412.717618471457;20326.186952522028;20987.895060524905;21156.365309040753;21171.576568468543;21280.042398091813;7489.887651685229;7610.124835643972;7658.770236892902;7727.1886451922865;7785.922140778633;4068.2677118119022;4069.409935559327;4090.959090409058;4111.218868414991;4132.8140233689555;3569.5538057742783;3633.436817293271;3636.6908812751212;3641.6540428659237;3847.115163512408;591.9260092488439;688.7968049425432;707.4340527577917;709.716113554572;709.9680514376865;531.1952392125933;585.1486087742325;608.3976862905691;612.9295131059985;621.8227805075531;8033.758987230392;8123.6750529978135;8151.5924203790755;8239.752807415787;8340.62467188978;523.4059342646124;539.6006954853381;586.9354371019184;594.872102497965;608.1182285685774;3448.2930999145615;3815.3323168445413;3953.367461206193;3998.4402183694306;4213.325867861189;97010;97313;99546.50226748965;103394.48302758439;107041;58662.70668646629;77845;80491.59754201192;81943;84154;53882.46117538775;71730.64134679294;75087;76937.84593231106;77578.61210693911;7306.7077316906725;7661.463697541119;7723.918651388923;7790.376769858284;7858.803529911798;40732.79633601932;41697;46453.3032004527;46980.06039879115;48735.61379475864;65067.67466162828;70152;70254;70456;70932.29067709463;9142.63429462814;9254.4910029949;9347.252219822261;9446.669366572138;9880.35777674449;11704.590339338083;11840.28958262507;12118.333491658044;12316.81524777147;12431.37843107859;11155.888441115485;11191.216614837209;11311.886881131084;11561.653150405502;11707.239029463057;11357.37534435614;11414.372209528556;11625.24435911663;11642.88357116418;11727.648170554898;11107.38909359993;11122.277051991594;11192.328460292409;11282.086873754488;11583;9155.725328240163;9182.219511341611;9337.486438245962;9339.439633622005;9470.289728270269;8812.51531165792;8854.156316448545;9074.31942604294;9320.254379649747;9822.803543929223 06:07:48 INFO - PROCESS | 3408 | __end_tp_report 06:07:48 INFO - PROCESS | 3408 | __start_cc_report 06:07:48 INFO - PROCESS | 3408 | _x_x_mozilla_cycle_collect,409 06:07:48 INFO - PROCESS | 3408 | __end_cc_report 06:07:48 INFO - PROCESS | 3408 | __startTimestamp1461589668577__endTimestamp 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | ------- Summary: start ------- 06:07:48 INFO - PROCESS | 3408 | Number of tests: 6 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | [#0] dojo.html Cycles:165 Average:5796.93 Median:6104.02 stddev:812.95 (13.3%) stddev-sans-first:794.48 06:07:48 INFO - PROCESS | 3408 | Values: 3458.5 3465.2 3487.4 3507.1 3512.5 5782.3 5782.8 5828.3 5829.7 5885.8 5199.6 5833.6 5882.8 5930.0 6019.8 5210.8 5239.7 5290.8 5291.9 5350.0 203.7 4219.7 5182.5 5277.2 5295.7 5258.2 5293.0 5293.1 5299.1 5348.2 5313.9 5330.9 5361.3 5362.0 5364.8 5186.6 5262.2 5269.7 5361.8 5371.9 4266.3 4631.0 5715.5 5736.7 5851.3 6190.6 6254.9 6263.7 6304.7 6314.7 5322.0 6287.8 6344.7 6385.1 6393.0 6029.8 6041.4 6056.2 6057.1 6063.8 6307.8 6396.9 6490.9 6494.1 6509.5 6148.3 6160.6 6251.0 6256.9 6286.9 6325.1 6338.1 6358.6 6387.6 6392.7 6171.6 6207.0 6284.6 6287.6 6292.2 6290.8 6295.3 6319.7 6327.9 6337.0 4247.8 5007.4 5010.1 5017.5 5028.7 4470.3 4504.1 4541.5 4554.8 4610.0 5839.8 5944.2 5962.7 5989.4 6064.0 5315.9 6298.4 6309.1 6312.7 6322.0 5821.9 5995.8 6001.6 6090.1 6104.0 5473.3 5474.2 5489.2 5503.7 5509.7 6505.4 6571.0 6595.4 6597.7 6609.8 6169.3 6270.2 6286.7 6340.6 6370.3 6500.1 6509.0 6535.4 6561.0 6577.2 6240.1 6249.0 6252.4 6267.5 6273.7 6209.6 6265.8 6278.0 6318.5 6334.7 5593.2 6215.2 6257.5 6264.3 6266.3 6142.5 6226.4 6260.8 6267.4 6269.3 5832.8 6018.2 6118.4 6125.4 6186.9 6201.8 6242.0 6249.5 6256.2 6289.4 6065.8 6081.3 6100.9 6188.1 6199.5 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | [#1] ext.html Cycles:180 Average:60255.94 Median:37568.22 stddev:129015.73 (343.4%) stddev-sans-first:129299.60 06:07:48 INFO - PROCESS | 3408 | Values: 482.2 499.1 529.1 539.8 544.2 37791.6 38106.8 38993.4 39006.0 39014.2 40024.0 40758.2 40772.4 40778.8 40972.8 8139.6 9382.5 9445.0 9572.6 9586.3 9444.7 9610.7 9678.6 9684.6 9710.0 9663.9 9694.5 9744.9 9759.1 9809.0 9674.4 9723.4 9726.9 9733.0 9766.2 9496.2 9501.1 9590.8 9609.1 9734.0 37910.8 38040.4 38194.9 38420.4 40090.4 2885.6 3113.5 3182.3 3307.3 3457.8 21592.9 22400.9 26958.3 27672.4 28155.0 2327.5 2623.3 2654.3 2711.2 2766.9 350556.0 352201.0 353240.0 353327.0 354414.0 34513.7 47635.6 60234.4 60995.8 61010.7 28903.0 29172.6 35702.6 35781.1 36015.5 14728.3 14981.1 15115.2 15302.8 15334.2 10704.3 12746.9 12817.3 12839.9 12877.2 8146.4 8168.4 8227.4 8274.0 8310.3 3474.8 3505.6 3507.2 3510.8 3517.9 847.3 899.8 955.1 1022.1 1157.5 29221.7 29387.7 29551.4 29857.4 29949.4 1019.9 1107.5 1136.8 1188.7 1204.4 12887.7 14646.0 14813.9 15138.7 15224.8 484861.0 497825.0 500287.0 501199.0 613892.0 59191.7 59881.7 60028.0 60056.0 60292.4 1415.7 1435.6 1467.9 1523.0 1523.3 28747.3 29075.7 29079.6 29508.9 29509.0 49224.8 50263.0 53718.7 54675.2 54798.0 529789.0 553272.2 553837.0 556619.0 559120.0 26530.9 27144.9 27204.5 27239.2 27560.9 38973.8 45465.5 45496.1 45744.8 46118.8 37714.0 37881.4 37949.6 38567.0 38667.2 32805.0 33847.3 36871.6 37739.8 38260.2 30588.0 35363.1 36565.5 37280.0 37422.4 30454.5 31244.7 31257.7 31307.7 31318.7 19115.4 22059.8 22085.8 23558.8 24777.6 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | [#2] jquery.html Cycles:180 Average:34126.30 Median:21224.60 stddev:95215.82 (448.6%) stddev-sans-first:95449.23 06:07:48 INFO - PROCESS | 3408 | Values: 390.9 397.7 400.5 401.1 407.8 20124.0 20379.7 20999.8 21211.7 21237.5 19414.1 19764.4 19787.8 20016.2 20094.1 5083.8 5975.4 6020.2 6022.0 6048.2 5677.7 5844.8 5871.6 5960.3 5967.8 5852.6 5870.4 5901.4 5910.0 5915.3 5642.5 5844.8 5893.2 5902.3 5943.3 5079.5 5151.9 5153.5 5187.7 5190.4 22787.4 25472.5 25782.9 25885.0 25964.1 2024.8 2145.3 2317.3 2497.6 2713.3 12208.7 14522.3 14918.0 15027.5 15328.5 1681.2 1685.8 1745.6 1771.1 1771.6 85723.6 122072.0 124144.4 125540.0 127353.4 10659.9 10714.9 11394.5 11502.9 11640.8 13206.4 14067.6 14085.7 14089.1 14105.2 6776.6 8690.3 8766.7 8812.9 8840.4 8864.0 8864.5 8924.6 8929.0 8942.5 6632.4 6695.6 6757.7 6787.1 6787.8 1664.8 1802.9 1894.5 1895.5 1920.7 963.7 978.4 1017.9 1085.6 1086.0 19881.7 20857.8 21067.1 21114.5 21203.4 871.9 989.7 999.8 1009.5 1019.7 11034.6 11139.8 11272.9 11287.1 11359.9 564770.0 566142.0 568663.0 572364.3 575184.6 123652.8 126696.0 126921.0 128439.0 129943.0 933.0 941.0 1044.1 1069.4 1071.0 16626.5 18719.5 18850.2 18857.8 19100.5 49295.5 51702.0 54103.7 54340.7 54415.5 71797.6 72324.3 73098.0 73654.0 74254.3 6097.8 7858.3 7936.9 8053.2 8136.2 11114.9 12399.4 12415.2 12548.4 12752.7 10520.4 10546.7 10953.7 10987.0 11003.2 8948.9 10106.5 10712.3 11128.2 11320.5 10807.4 11344.3 11406.7 11448.1 11469.0 7933.6 8040.7 8197.7 8235.1 8277.3 10106.4 11053.2 11238.8 11339.1 11341.1 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | [#3] mootools.html Cycles:180 Average:5741.41 Median:4875.31 stddev:3170.73 (65.0%) stddev-sans-first:3163.13 06:07:48 INFO - PROCESS | 3408 | Values: 1437.4 1596.3 1833.3 1944.3 1948.7 6762.7 8196.9 8407.5 8450.6 8660.2 8643.3 8691.3 8742.1 8779.6 8819.1 2481.0 2496.6 2514.5 2517.2 2531.1 2468.4 2500.2 2532.1 2532.4 2539.1 2474.6 2486.3 2505.8 2530.2 2539.7 2061.9 2474.6 2475.6 2494.3 2501.7 2451.9 2479.7 2499.7 2500.2 2514.8 8929.8 8966.4 8977.0 9009.7 9027.3 5366.7 5507.6 5573.3 5585.3 5615.3 3591.4 3592.0 3611.2 3654.8 3678.1 998.4 1042.7 1046.0 1048.5 1066.7 10785.5 11227.7 11785.4 11925.5 11925.8 4202.7 4922.1 5021.2 5038.5 5204.4 8942.7 9823.8 10174.1 10185.1 10228.5 5301.1 5449.0 5451.2 5514.4 5532.6 4442.5 4487.6 4890.4 4895.0 5074.9 4076.4 4099.5 4111.5 4179.2 4180.6 1347.7 1374.3 1381.2 1383.9 1390.0 842.7 914.8 949.9 955.9 962.1 6027.2 6112.8 6141.6 6155.1 6177.1 457.0 467.2 473.2 477.0 481.5 4031.1 4061.5 4085.3 4121.7 4138.1 10512.4 10569.4 10905.9 10920.0 10930.5 10517.0 10801.6 10843.1 10858.1 10877.8 6960.5 7771.6 8236.8 8322.2 8424.7 6024.1 6060.2 6069.1 6158.3 6160.9 8347.6 9906.0 10134.1 10228.9 10234.1 8192.4 10111.8 10318.8 10526.3 10529.6 6465.8 6988.0 7406.2 7611.5 7674.7 8433.0 8462.4 8463.8 8473.6 8517.9 7377.6 7536.5 7600.3 7620.5 7633.2 7368.3 7379.3 7402.4 7469.2 7470.9 7028.8 7088.5 7116.8 7118.6 7125.6 4818.5 4868.3 4938.9 4939.6 5228.9 4761.4 4868.1 4882.3 4945.0 5020.7 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | [#4] prototype.html Cycles:180 Average:4897.24 Median:5423.76 stddev:1571.71 (29.0%) stddev-sans-first:1538.13 06:07:48 INFO - PROCESS | 3408 | Values: 320.9 322.9 327.7 330.6 332.8 4913.0 4968.5 5324.4 5766.1 5796.4 4812.2 5320.1 5326.2 5654.9 5689.7 3559.3 3678.8 3701.8 3912.7 4078.7 4244.3 4260.5 4263.9 4312.7 4323.8 4246.6 4255.2 4271.1 4271.8 4276.9 3900.2 4280.3 4290.4 4297.6 4303.4 3104.4 3235.0 3714.8 3738.3 3741.8 4283.6 4609.7 5271.8 5441.8 5550.0 4624.6 4887.6 5002.3 5003.2 5029.9 5186.3 5502.0 5959.9 5999.7 6026.8 3555.5 3709.6 3914.6 4044.5 4064.2 5198.1 5419.6 6024.2 6143.8 6332.5 3798.7 4061.6 4088.5 4093.8 4107.5 3266.7 3737.6 4149.8 4167.4 4172.0 4580.2 4864.8 4881.0 4886.3 4894.4 5284.5 5463.7 5705.7 5983.8 5988.6 1280.1 1311.3 1438.9 1511.5 1516.7 856.9 879.5 924.4 962.9 988.3 5124.2 5305.1 5365.6 5699.9 6076.5 6071.8 6144.5 6674.4 6700.3 6723.9 5427.9 6071.7 6104.7 6109.5 6153.8 3354.6 3437.5 3542.2 3576.3 3579.2 6123.1 6299.5 6336.8 6961.5 7024.8 6036.9 6907.4 6929.3 6962.0 7000.0 5827.7 6428.7 6641.9 7007.9 7028.1 5381.4 6667.0 6680.5 6686.4 6690.9 5804.9 6046.5 6530.9 6649.2 6676.7 5493.9 5671.5 5903.6 6039.7 6482.5 4048.7 4114.7 4119.8 4136.9 4139.7 5683.6 5929.6 6022.8 6175.3 6253.4 5664.2 5979.0 6044.9 6057.9 6059.6 4832.2 5937.6 6046.3 6053.2 6076.9 4271.9 5632.2 5790.6 5859.0 5896.7 5287.4 5340.3 5977.6 6064.6 6090.9 5690.1 5977.1 5981.0 5981.8 6016.3 06:07:48 INFO - PROCESS | 3408 | 06:07:48 INFO - PROCESS | 3408 | [#5] yui.html Cycles:180 Average:18337.22 Median:684.28 stddev:26550.29 (3880.0%) stddev-sans-first:26591.68 06:07:48 INFO - PROCESS | 3408 | Values: 682.4 721.5 745.5 764.3 778.8 14600.7 14968.7 15048.9 15072.2 15122.7 12154.2 13629.3 13742.5 13754.3 13776.5 576.4 798.7 893.7 896.2 897.8 830.4 895.5 906.1 935.8 938.5 841.1 972.4 975.5 979.2 981.1 946.6 962.2 965.9 966.7 968.1 640.3 686.2 686.3 720.1 720.3 13221.9 15169.9 15233.0 15332.7 15463.4 10584.2 11957.5 12200.6 12323.5 12432.9 9933.6 9936.3 9947.2 10021.6 10460.6 2865.8 3397.6 3420.4 3434.4 3444.9 79961.6 82183.0 82990.6 83126.9 83753.0 9037.1 9080.5 9169.7 9270.8 9412.7 20326.2 20987.9 21156.4 21171.6 21280.0 7489.9 7610.1 7658.8 7727.2 7785.9 4068.3 4069.4 4091.0 4111.2 4132.8 3569.6 3633.4 3636.7 3641.7 3847.1 591.9 688.8 707.4 709.7 710.0 531.2 585.1 608.4 612.9 621.8 8033.8 8123.7 8151.6 8239.8 8340.6 523.4 539.6 586.9 594.9 608.1 3448.3 3815.3 3953.4 3998.4 4213.3 97010.0 97313.0 99546.5 103394.5 107041.0 58662.7 77845.0 80491.6 81943.0 84154.0 53882.5 71730.6 75087.0 76937.8 77578.6 7306.7 7661.5 7723.9 7790.4 7858.8 40732.8 41697.0 46453.3 46980.1 48735.6 65067.7 70152.0 70254.0 70456.0 70932.3 9142.6 9254.5 9347.3 9446.7 9880.4 11704.6 11840.3 12118.3 12316.8 12431.4 11155.9 11191.2 11311.9 11561.7 11707.2 11357.4 11414.4 11625.2 11642.9 11727.6 11107.4 11122.3 11192.3 11282.1 11583.0 9155.7 9182.2 9337.5 9339.4 9470.3 8812.5 8854.2 9074.3 9320.3 9822.8 06:07:48 INFO - PROCESS | 3408 | -------- Summary: end -------- 06:07:48 INFO - PROCESS | 3408 | 06:07:49 INFO - TEST-INFO | 3408: exit 0 06:07:49 INFO - TEST-OK | dromaeo_css | took 1111615ms 06:07:49 INFO - TEST-START | kraken 06:07:49 INFO - operating with platform_type : mac_ 06:07:49 INFO - Initialising browser for kraken test... 06:07:49 INFO - TEST-INFO | started process 3412 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpAbzb8m/profile http://localhost:64768/getInfo.html) 06:07:52 INFO - PROCESS | 3412 | __metrics Screen width/height:1600/1200 06:07:52 INFO - PROCESS | 3412 | colorDepth:24 06:07:52 INFO - PROCESS | 3412 | Browser inner width/height: 1024/689 06:07:52 INFO - PROCESS | 3412 | __metrics 06:07:52 ERROR - PROCESS | 3412 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 06:07:52 INFO - TEST-INFO | 3412: exit 0 06:07:52 INFO - Browser initialized. 06:07:52 INFO - Running cycle 1/1 for kraken test... 06:07:52 INFO - TEST-INFO | started process 3415 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpAbzb8m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:04 INFO - PROCESS | 3415 | RSS: Main: 250081280 06:08:04 INFO - PROCESS | 3415 | 06:08:07 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64768/tests/kraken/driver.html?testName=audio-beat-detection) 06:08:08 INFO - PROCESS | 3415 | RSS: Main: 262160384 06:08:08 INFO - PROCESS | 3415 | 06:08:10 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64768/tests/kraken/driver.html?testName=audio-dft) 06:08:11 INFO - PROCESS | 3415 | RSS: Main: 271429632 06:08:11 INFO - PROCESS | 3415 | 06:08:15 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64768/tests/kraken/driver.html?testName=audio-fft) 06:08:15 INFO - PROCESS | 3415 | RSS: Main: 274747392 06:08:15 INFO - PROCESS | 3415 | 06:08:17 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64768/tests/kraken/driver.html?testName=audio-oscillator) 06:08:18 INFO - PROCESS | 3415 | RSS: Main: 275623936 06:08:18 INFO - PROCESS | 3415 | 06:08:20 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64768/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:08:21 INFO - PROCESS | 3415 | RSS: Main: 276852736 06:08:21 INFO - PROCESS | 3415 | 06:08:24 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64768/tests/kraken/driver.html?testName=imaging-darkroom) 06:08:24 INFO - PROCESS | 3415 | RSS: Main: 277508096 06:08:24 INFO - PROCESS | 3415 | 06:08:27 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64768/tests/kraken/driver.html?testName=imaging-desaturate) 06:08:28 INFO - PROCESS | 3415 | RSS: Main: 278192128 06:08:28 INFO - PROCESS | 3415 | 06:08:31 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64768/tests/kraken/driver.html?testName=json-parse-financial) 06:08:32 INFO - PROCESS | 3415 | RSS: Main: 277831680 06:08:32 INFO - PROCESS | 3415 | 06:08:34 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64768/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:34 INFO - PROCESS | 3415 | RSS: Main: 276942848 06:08:34 INFO - PROCESS | 3415 | 06:08:36 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:37 INFO - PROCESS | 3415 | RSS: Main: 277524480 06:08:37 INFO - PROCESS | 3415 | 06:08:39 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:40 INFO - PROCESS | 3415 | RSS: Main: 277512192 06:08:40 INFO - PROCESS | 3415 | 06:08:43 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:43 INFO - PROCESS | 3415 | RSS: Main: 277315584 06:08:43 INFO - PROCESS | 3415 | 06:08:46 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:47 INFO - PROCESS | 3415 | RSS: Main: 277336064 06:08:47 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | Cycle 1(1): loaded http://localhost:64768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64768/tests/kraken/driver.html?testName=ai-astar) 06:08:49 INFO - PROCESS | 3415 | RSS: Main: 278913024 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | __start_tp_report 06:08:49 INFO - PROCESS | 3415 | _x_x_mozilla_page_load 06:08:49 INFO - PROCESS | 3415 | _x_x_mozilla_page_load_details 06:08:49 INFO - PROCESS | 3415 | |i|pagename|runs| 06:08:49 INFO - PROCESS | 3415 | |0;ai-astar;116;109;116;117;115;129;116;116;159;172 06:08:49 INFO - PROCESS | 3415 | |1;audio-beat-detection;156;124;147;164;121;167;144;127;171;133 06:08:49 INFO - PROCESS | 3415 | |2;audio-dft;250;195;209;232;281;200;275;244;203;198 06:08:49 INFO - PROCESS | 3415 | |3;audio-fft;66;86;78;143;46;54;73;89;92;48 06:08:49 INFO - PROCESS | 3415 | |4;audio-oscillator;78;80;166;115;116;127;126;79;82;125 06:08:49 INFO - PROCESS | 3415 | |5;imaging-gaussian-blur;106;116;90;99;137;86;89;86;90;86 06:08:49 INFO - PROCESS | 3415 | |6;imaging-darkroom;94;95;95;91;151;137;95;147;104;95 06:08:49 INFO - PROCESS | 3415 | |7;imaging-desaturate;152;114;97;98;94;135;143;133;133;128 06:08:49 INFO - PROCESS | 3415 | |8;json-parse-financial;59;61;61;65;52;61;92;102;87;51 06:08:49 INFO - PROCESS | 3415 | |9;json-stringify-tinderbox;73;87;67;72;72;73;75;64;64;69 06:08:49 INFO - PROCESS | 3415 | |10;stanford-crypto-aes;91;107;104;85;84;83;85;83;85;85 06:08:49 INFO - PROCESS | 3415 | |11;stanford-crypto-ccm;144;159;131;139;136;134;144;136;236;163 06:08:49 INFO - PROCESS | 3415 | |12;stanford-crypto-pbkdf2;172;176;171;236;231;175;187;172;244;232 06:08:49 INFO - PROCESS | 3415 | |13;stanford-crypto-sha256-iterative;68;68;67;70;67;71;69;68;70;69 06:08:49 INFO - PROCESS | 3415 | __end_tp_report 06:08:49 INFO - PROCESS | 3415 | __start_cc_report 06:08:49 INFO - PROCESS | 3415 | _x_x_mozilla_cycle_collect,1106 06:08:49 INFO - PROCESS | 3415 | __end_cc_report 06:08:49 INFO - PROCESS | 3415 | __startTimestamp1461589729495__endTimestamp 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | ------- Summary: start ------- 06:08:49 INFO - PROCESS | 3415 | Number of tests: 14 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#0] ai-astar Cycles:10 Average:126.50 Median:116.50 stddev:21.35 (18.3%) stddev-sans-first:22.30 06:08:49 INFO - PROCESS | 3415 | Values: 116.0 109.0 116.0 117.0 115.0 129.0 116.0 116.0 159.0 172.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:151.50 stddev:18.65 (12.3%) stddev-sans-first:19.38 06:08:49 INFO - PROCESS | 3415 | Values: 156.0 124.0 147.0 164.0 121.0 167.0 144.0 127.0 171.0 133.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#2] audio-dft Cycles:10 Average:228.70 Median:238.00 stddev:32.54 (13.7%) stddev-sans-first:33.59 06:08:49 INFO - PROCESS | 3415 | Values: 250.0 195.0 209.0 232.0 281.0 200.0 275.0 244.0 203.0 198.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#3] audio-fft Cycles:10 Average:77.50 Median:75.50 stddev:28.43 (37.7%) stddev-sans-first:29.84 06:08:49 INFO - PROCESS | 3415 | Values: 66.0 86.0 78.0 143.0 46.0 54.0 73.0 89.0 92.0 48.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:122.00 stddev:29.12 (23.9%) stddev-sans-first:28.59 06:08:49 INFO - PROCESS | 3415 | Values: 78.0 80.0 166.0 115.0 116.0 127.0 126.0 79.0 82.0 125.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:87.50 stddev:16.83 (19.2%) stddev-sans-first:17.63 06:08:49 INFO - PROCESS | 3415 | Values: 106.0 116.0 90.0 99.0 137.0 86.0 89.0 86.0 90.0 86.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:94.50 stddev:24.34 (25.8%) stddev-sans-first:25.08 06:08:49 INFO - PROCESS | 3415 | Values: 94.0 95.0 95.0 91.0 151.0 137.0 95.0 147.0 104.0 95.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#7] imaging-desaturate Cycles:10 Average:122.70 Median:147.50 stddev:20.63 (14.0%) stddev-sans-first:18.97 06:08:49 INFO - PROCESS | 3415 | Values: 152.0 114.0 97.0 98.0 94.0 135.0 143.0 133.0 133.0 128.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#8] json-parse-financial Cycles:10 Average:69.10 Median:61.00 stddev:17.84 (29.2%) stddev-sans-first:18.54 06:08:49 INFO - PROCESS | 3415 | Values: 59.0 61.0 61.0 65.0 52.0 61.0 92.0 102.0 87.0 51.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.50 stddev:6.64 (9.2%) stddev-sans-first:7.02 06:08:49 INFO - PROCESS | 3415 | Values: 73.0 87.0 67.0 72.0 72.0 73.0 75.0 64.0 64.0 69.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:85.00 stddev:8.90 (10.5%) stddev-sans-first:9.42 06:08:49 INFO - PROCESS | 3415 | Values: 91.0 107.0 104.0 85.0 84.0 83.0 85.0 83.0 85.0 85.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:144.00 stddev:31.27 (21.7%) stddev-sans-first:33.02 06:08:49 INFO - PROCESS | 3415 | Values: 144.0 159.0 131.0 139.0 136.0 134.0 144.0 136.0 236.0 163.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.60 Median:209.00 stddev:31.61 (15.1%) stddev-sans-first:31.91 06:08:49 INFO - PROCESS | 3415 | Values: 172.0 176.0 171.0 236.0 231.0 175.0 187.0 172.0 244.0 232.0 06:08:49 INFO - PROCESS | 3415 | 06:08:49 INFO - PROCESS | 3415 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.34 (1.9%) stddev-sans-first:1.39 06:08:49 INFO - PROCESS | 3415 | Values: 68.0 68.0 67.0 70.0 67.0 71.0 69.0 68.0 70.0 69.0 06:08:49 INFO - PROCESS | 3415 | -------- Summary: end -------- 06:08:49 INFO - PROCESS | 3415 | 06:08:50 INFO - TEST-INFO | 3415: exit 0 06:08:50 INFO - TEST-OK | kraken | took 61131ms 06:08:50 INFO - SUITE-END | took 1172s 06:08:50 INFO - Completed test suite (00:19:33) 06:08:50 INFO - javascript benchmark 06:08:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3458.4812278158274, 3465.151037995691, 3487.4420092785144, 3507.0706262840354, 3512.4731290306436, 5782.306123265209, 5782.797602083928, 5828.300603927524, 5829.650220986744, 5885.791658963851, 5199.584033277338, 5833.649981001143, 5882.794102206423, 5929.970350148254, 6019.849503762408, 5210.817621383262, 5239.711815850127, 5290.761915713783, 5291.947080529203, 5350.0, 203.65789215250732, 4219.662427005847, 5182.533571978521, 5277.1556550951855, 5295.735213239323, 5258.237555554423, 5292.973535132319, 5293.073712100367, 5299.125644268691, 5348.224507446418, 5313.893722125575, 5330.946690533084, 5361.303030605996, 5361.973190134025, 5364.839054828361, 5186.559142472895, 5262.23697563855, 5269.710165940872, 5361.758720857571, 5371.865703357408, 4266.338717498793, 4630.976845115752, 5715.457031582035, 5736.684482353469, 5851.297844258674, 6190.597611155261, 6254.8741226579195, 6263.686815659199, 6304.653244071575, 6314.652694101823, 5322.0, 6287.811365659038, 6344.6510441925675, 6385.074164246204, 6393.0, 6029.758809647609, 6041.395860413967, 6056.152138700585, 6057.091436284549, 6063.8484037898525, 6307.842303942438, 6396.904046439307, 6490.896547586922, 6494.090827284184, 6509.511786615928, 6148.262208535004, 6160.568760186834, 6251.031090181029, 6256.937430625636, 6286.937130628727, 6325.114483972247, 6338.112664227012, 6358.554901156967, 6387.552871299057, 6392.680365981775, 6171.56799024073, 6206.968965155145, 6284.622922624657, 6287.591306565058, 6292.2134733158355, 6290.779822706275, 6295.307516173218, 6319.652419116901, 6327.873442531123, 6337.0, 4247.787610619456, 5007.424146223231, 5010.123228435009, 5017.473165317662, 5028.698278103326, 4470.329450582439, 4504.076664283814, 4541.545845415432, 4554.772261386917, 4610.0, 5839.824805255849, 5944.167816505693, 5962.672053037038, 5989.431004054608, 6063.969125248718, 5315.867103322448, 6298.401651843067, 6309.08518264849, 6312.747490100344, 6322.0200868865395, 5821.854453638693, 5995.820125396245, 6001.579889407787, 6090.116933044772, 6104.023356263066, 5473.343198816167, 5474.233607294982, 5489.176623506506, 5503.697296648723, 5509.696966666792, 6505.381988711065, 6570.967145164243, 6595.373439523238, 6597.670116494156, 6609.768658096944, 6169.259688837368, 6270.216222972129, 6286.65423401699, 6340.587861788969, 6370.331115232834, 6500.089987401673, 6508.967455162694, 6535.44448721864, 6560.967195163994, 6577.210734711865, 6240.063990401476, 6249.0, 6252.437280644582, 6267.4986001120715, 6273.654948977667, 6209.565330426827, 6265.780697675559, 6278.0, 6318.526110541636, 6334.651594162366, 5593.188987596857, 6215.171893158975, 6257.530685198537, 6264.342244064308, 6266.342034086538, 6142.539309551712, 6226.377362263918, 6260.812175634738, 6267.435930766253, 6269.341719119427, 5832.766689332379, 6018.157457955802, 6118.388161184024, 6125.418593525838, 6186.876262474635, 6201.81394558164, 6241.96879015602, 6249.468795152281, 6256.155419018556, 6289.371062893674, 6065.787697430569, 6081.27024757032, 6100.944524685319, 6188.1027251049245, 6199.504039676905], "value": 5745.337766504192, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.18992093283293, 499.13400250565275, 529.1137344947213, 539.8299535646271, 544.1755740053817, 37791.622083779155, 38106.80946595267, 38993.41509877354, 39006.0, 39014.219715605745, 40024.0, 40758.184836303255, 40772.388414173736, 40778.79610601943, 40972.79513602435, 8139.593020348973, 9382.483963382012, 9444.952775236114, 9572.56923438447, 9586.281028922824, 9444.669436569722, 9610.663626773065, 9678.564464599074, 9684.61261549537, 9709.95145024274, 9663.903360966371, 9694.515274236295, 9744.853827192563, 9759.121679048883, 9808.950955245215, 9674.419534827897, 9723.367981081206, 9726.902730972706, 9733.02669733021, 9766.218702503782, 9496.192823610001, 9501.097395747393, 9590.808183836354, 9609.087136722, 9734.02659734028, 37910.810445947594, 38040.42939355912, 38194.85415437541, 38420.42369364462, 40090.398644020366, 2885.581590669341, 3113.486274764661, 3182.3158021025592, 3307.338532293551, 3457.844397002141, 21592.89203553988, 22400.887995560082, 26958.326041848915, 27672.446551068864, 28155.01457449, 2327.511222643247, 2623.331050582108, 2654.2568080937367, 2711.227300219428, 2766.9070717066847, 350556.0, 352201.0, 353240.0, 353327.0, 354414.0, 34513.65486345105, 47635.57093287207, 60234.39765602288, 60995.780084398066, 61010.694946524985, 28903.0, 29172.562411563842, 35702.64297357045, 35781.1054723634, 36015.4597681035, 14728.263586820616, 14981.101133932, 15115.244237788067, 15302.846971530365, 15334.23328833554, 10704.30422022566, 12746.872531274568, 12817.295048772407, 12839.935800320938, 12877.227366358049, 8146.389020823462, 8168.42821002536, 8227.382946279053, 8274.048484424367, 8310.293625041939, 3474.7915125092577, 3505.614382417932, 3507.2108775525303, 3510.7542472027226, 3517.874280230302, 847.2925107535277, 899.7750562359411, 955.1165172215673, 1022.1465076660922, 1157.484919210943, 29221.707782922324, 29387.706122938926, 29551.40897182044, 29857.40285194284, 29949.401011979637, 1019.938803671782, 1107.5126944144552, 1136.7612801311818, 1188.6849984754047, 1204.3978010994504, 12887.742245155232, 14646.0, 14813.851861481462, 15138.697226055638, 15224.771628425582, 484861.0, 497825.0, 500287.0, 501199.0, 613892.0, 59191.70404148038, 59881.70059149677, 60028.0, 60056.0, 60292.397076029556, 1415.7027024325007, 1435.5621535431585, 1467.9412823487155, 1522.9923850380678, 1523.3373482535133, 28747.28131796722, 29075.7092429073, 29079.56380654334, 29508.852455737582, 29509.0, 49224.76938076577, 50263.0, 53718.73140634272, 54675.17987230115, 54798.0, 529789.0, 553272.2336388292, 553837.0, 556619.0, 559120.0, 26530.867345663148, 27144.864275678494, 27204.455910881275, 27239.182824515297, 27560.862195689697, 38973.805130974164, 45465.54534454613, 45496.09007819759, 45744.77127614341, 46118.76940615275, 37714.0, 37881.431778523896, 37949.62050379571, 38567.0, 38667.2266554673, 32805.03169809819, 33847.32305353929, 36871.63128368682, 37739.81130094332, 38260.234795304495, 30588.0, 35363.11592210215, 36565.45151822778, 37280.0, 37422.438663419525, 30454.54318185185, 31244.687553125088, 31257.687423126386, 31307.686923130477, 31318.686813132488, 19115.426537203904, 22059.77940220577, 22085.785281809654, 23558.764412355657, 24777.62833557462], "value": 17926.660274607744, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.8534299637636, 397.65205445235426, 400.47537725579485, 401.06751802060217, 407.82667366369304, 20124.0, 20379.694304585424, 20999.790002099973, 21211.72729636221, 21237.469063273427, 19414.126364313604, 19764.40706778799, 19787.802121978777, 20016.199352025902, 20094.09576569054, 5083.771230294637, 5975.43233392827, 6020.217371741689, 6022.036474164134, 6048.243969503812, 5677.744501497443, 5844.795432159875, 5871.55963302754, 5960.314563825151, 5967.761289548413, 5852.561057920651, 5870.412958704138, 5901.439363260483, 5910.0248459004215, 5915.319738230116, 5642.520385767215, 5844.824655260349, 5893.174955506232, 5902.291724992985, 5943.286805583358, 5079.517445842639, 5151.948480515184, 5153.510416510463, 5187.662801917901, 5190.4031036430915, 22787.430314242276, 25472.49055018908, 25782.871085644638, 25885.0, 25964.052696047682, 2024.7671517775498, 2145.302776597612, 2317.281642690771, 2497.6378425128437, 2713.3487962888767, 12208.694782630506, 14522.273886305642, 14918.0, 15027.474038408713, 15328.463503777315, 1681.2350380576809, 1685.8482736553647, 1745.5636090977255, 1771.070188151218, 1771.636814453034, 85723.57138214332, 122072.0, 124144.37927810304, 125540.0, 127353.36323318323, 10659.893401066045, 10714.892851071545, 11394.544218231344, 11502.94248528752, 11640.825387619023, 13206.405711742802, 14067.648308792363, 14085.71828563423, 14089.084209526349, 14105.224212668401, 6776.593404395752, 8690.304775617935, 8766.736997890073, 8812.942446906412, 8840.425372350777, 8864.0, 8864.468131912106, 8924.553772311489, 8929.0, 8942.46345219289, 6632.403083722488, 6695.631740254235, 6757.662116894234, 6787.1176747023555, 6787.830304242433, 1664.8085470171086, 1802.873798834069, 1894.507428068712, 1895.4882181810833, 1920.7310976463307, 963.6579014449909, 978.4227305889559, 1017.8549132226245, 1085.5766251162054, 1085.9846043948776, 19881.701774473393, 20857.791422085887, 21067.103960643617, 21114.472138196666, 21203.36389908305, 871.9171678690515, 989.6684610655367, 999.8400255959011, 1009.4843887259284, 1019.6635110413546, 11034.613788517363, 11139.777204455866, 11272.943635281936, 11287.121783945042, 11359.943200284111, 564770.0, 566142.0, 568663.0, 572364.2907127845, 575184.6203844937, 123652.76347236412, 126696.0, 126921.0, 128439.0, 129943.0, 932.9720108396759, 940.9931373430501, 1044.0916402729674, 1069.3690722473623, 1071.0414179309475, 16626.50120496387, 18719.532011699815, 18850.24599016024, 18857.811421885606, 19100.522486937938, 49295.50704493053, 51702.0, 54103.72948135234, 54340.72829635826, 54415.455845441036, 71797.6410117946, 72324.27675723386, 73098.0, 73654.0, 74254.2574574269, 6097.817065488042, 7858.29275365197, 7936.920630793849, 8053.234942680436, 8136.22705842944, 11114.944425277821, 12399.380030998234, 12415.193012454523, 12548.43532041042, 12752.74494510123, 10520.421376824348, 10546.683599492027, 10953.726156846142, 10987.0, 11003.17476189273, 8948.865767013369, 10106.494675266355, 10712.250142489951, 11128.1653875958, 11320.547178113113, 10807.405592692478, 11344.319340839576, 11406.714832129263, 11448.141389395661, 11468.967792898678, 7933.563653999086, 8040.678372865254, 8197.71308004217, 8235.13531079228, 8277.255047045795, 10106.444145572064, 11053.226274160732, 11238.7752244953, 11339.149563782585, 11341.149413794163], "value": 9689.720568944185, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.4034775568136, 1596.3056070609287, 1833.257530700067, 1944.3389247655793, 1948.6979518174678, 6762.695678694458, 8196.91803081969, 8407.453515521494, 8450.619722112504, 8660.177283158106, 8643.308535317175, 8691.348148888826, 8742.125787421255, 8779.648814047447, 8819.118088191177, 2480.9951969452354, 2496.6005439129744, 2514.497100579882, 2517.2070797698666, 2531.0635065025967, 2468.419921318489, 2500.1874390823054, 2532.050481069599, 2532.3795670060767, 2539.0605475973825, 2474.5793215153467, 2486.3411196033067, 2505.812064095191, 2530.1523989463553, 2539.733328000561, 2061.8556701030934, 2474.5669507836055, 2475.6039033754514, 2494.3390001649577, 2501.7373175816642, 2451.9141830035865, 2479.6776419065404, 2499.7250302466714, 2500.237427584586, 2514.8491090534444, 8929.821403571957, 8966.417182883091, 8976.955115224382, 9009.729708108702, 9027.277817774628, 5366.70483123428, 5507.614466987313, 5573.331200255955, 5585.273914391103, 5615.298087739033, 3591.3535563598543, 3592.0301518590095, 3611.2235869287965, 3654.7807131571926, 3678.1172518595613, 998.4408731110601, 1042.7080417483116, 1046.0062940206833, 1048.486241741541, 1066.7386490309846, 10785.514651840685, 11227.719307017309, 11785.351805650685, 11925.463354149084, 11925.761484770255, 4202.705810593229, 4922.138625740481, 5021.246812978083, 5038.47096054909, 5204.4014938281825, 8942.7033080203, 9823.803523929482, 10174.084332410119, 10185.134263587677, 10228.53971571281, 5301.072312345401, 5449.01917654818, 5451.236826844166, 5514.448555144453, 5532.640378375393, 4442.511323754385, 4487.6185524230805, 4890.437599676012, 4895.0209958008545, 5074.873128171826, 4076.449679293317, 4099.525830790178, 4111.5477297497255, 4179.226843034048, 4180.623743863067, 1347.7075514486392, 1374.29223949666, 1381.2196109198471, 1383.9100458470368, 1389.979150312746, 842.6587232170981, 914.7895983923735, 949.8622699708503, 955.8661787349777, 962.119660510625, 6027.246594175728, 6112.786052488142, 6141.57009009374, 6155.10750941111, 6177.0508178299415, 456.9520200378979, 467.2080823005042, 473.1506945033673, 476.9618430525619, 481.508860961817, 4031.07285324377, 4061.4517040199194, 4085.264652362544, 4121.651841176727, 4138.089620283533, 10512.42181680015, 10569.365838049742, 10905.90940905919, 10920.0, 10930.508127134299, 10517.0, 10801.621943231949, 10843.132549396028, 10858.131349492021, 10877.83683244752, 6960.512764106464, 7771.611419429006, 8236.794080148044, 8322.16778322175, 8424.747257582283, 6024.126501657148, 6060.151578779062, 6069.119977603134, 6158.291796443466, 6160.938390616216, 8347.624356903832, 9905.950470247602, 10134.087932086148, 10228.948855255676, 10234.130098941376, 8192.385571082074, 10111.797764044532, 10318.845217321896, 10526.31578947366, 10529.57881684724, 6465.838354041187, 6988.021676965331, 7406.222346653739, 7611.505252158592, 7674.654640541077, 8433.030201526657, 8462.449940753831, 8463.830723385621, 8473.618687159216, 8517.872231916403, 7377.631118444164, 7536.472446928662, 7600.2779735925, 7620.504667196615, 7633.160352361125, 7368.336849683555, 7379.298966598165, 7402.444816638881, 7469.215732348242, 7470.92529074724, 7028.753993610199, 7088.468364872552, 7116.786496405116, 7118.644067796486, 7125.572465652078, 4818.494058123987, 4868.2940973557925, 4938.9259161111895, 4939.580135688507, 5228.895422091461, 4761.357216775829, 4868.148074087091, 4882.316475693335, 4944.975275123746, 5020.698758074527], "value": 4583.028546628588, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.89410494536804, 322.86439695327965, 327.69360647794326, 330.6082292483406, 332.84190009745373, 4912.950870491294, 4968.453470118284, 5324.440933701963, 5766.077427611588, 5796.362400135992, 4812.181929072058, 5320.122179840322, 5326.174442961337, 5654.9151762723595, 5689.715514224293, 3559.341521818472, 3678.8227767114536, 3701.8334174962124, 3912.667423269011, 4078.653314468259, 4244.278472659656, 4260.467441569804, 4263.914721705564, 4312.698111132222, 4323.762193079379, 4246.575342465744, 4255.191513612419, 4271.103068355634, 4271.786410679453, 4276.935845962313, 3900.239453306592, 4280.315149576053, 4290.356446533029, 4297.570242975708, 4303.440552728157, 3104.441200583894, 3235.0133209371133, 3714.832832522543, 3738.280391020134, 3741.7567858089137, 4283.571642835723, 4609.746463944482, 5271.841844744664, 5441.782328706847, 5549.973254947848, 4624.606908412789, 4887.608991280689, 5002.274670172805, 5003.199488081926, 5029.949700502984, 5186.34362774247, 5502.028904391624, 5959.910601340984, 5999.700014999276, 6026.8493287667725, 3555.5022296878456, 3709.6290370962956, 3914.5889681583308, 4044.51465824103, 4064.248114098901, 5198.116320225571, 5419.620626556142, 6024.246969128859, 6143.846403839895, 6332.525060620425, 3798.6771124454176, 4061.593840615915, 4088.468499095158, 4093.815778289984, 4107.54816970133, 3266.738660907122, 3737.588865224823, 4149.792510374469, 4167.395727619478, 4171.957010747313, 4580.198465268565, 4864.78108485119, 4880.999395123991, 4886.29148773433, 4894.388201474816, 5284.471552844761, 5463.69949652765, 5705.74324155414, 5983.820485385445, 5988.640681559121, 1280.0911352939331, 1311.3377744239099, 1438.9208593527455, 1511.4507629679667, 1516.7042426726682, 856.9185927336893, 879.4942907828024, 924.3621900888365, 962.8507581324906, 988.2983082011853, 5124.205748109049, 5305.071612467803, 5365.597580181502, 5699.943000570024, 6076.453119219292, 6071.844495204227, 6144.539159563051, 6674.365935236145, 6700.296468870796, 6723.890558057963, 5427.891442171134, 6071.726772295257, 6104.664243466563, 6109.5481053205685, 6153.815385538441, 3354.6142193647556, 3437.5359326491102, 3542.2030043240065, 3576.2668652926086, 3579.2304654499408, 6123.142760013601, 6299.5275354348605, 6336.778212762531, 6961.547499412522, 7024.789256322319, 6036.909446358308, 6907.412869906014, 6929.272426395257, 6961.990511375822, 6999.965000174967, 5827.679477628772, 6428.678566071678, 6641.900371494431, 7007.878739401774, 7028.0863487746265, 5381.354237491526, 6667.0, 6680.4989625779235, 6686.425726095391, 6690.899636505359, 5804.883902322014, 6046.455818976313, 6530.934690653033, 6649.202095748541, 6676.666166691549, 5493.9286839066, 5671.546276297969, 5903.553629360834, 6039.728212230548, 6482.54622176462, 4048.716589838683, 4114.732542384735, 4119.794010299533, 4136.896577585584, 4139.716687826663, 5683.573731970201, 5929.555283353679, 6022.844237476127, 6175.320714721467, 6253.3741227280325, 5664.178694089252, 5979.0, 6044.9395506044375, 6057.939420605914, 6059.606125601822, 4832.1543729846435, 5937.643741375531, 6046.334903160561, 6053.182820319201, 6076.939230607814, 4271.8718438446895, 5632.211490391266, 5790.639199788131, 5858.970705146447, 5896.727316339473, 5287.391949925808, 5340.279062326536, 5977.581569290108, 6064.635457022135, 6090.878182436415, 5690.089585666274, 5977.103434484862, 5980.970095149496, 5981.790637327673, 6016.315431679137], "value": 4398.047679384765, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.3756263019336, 721.4553012475584, 745.5191401546, 764.2968469008512, 778.8091917480216, 14600.70798584029, 14968.700625987474, 15048.924755376207, 15072.246387680614, 15122.697546049072, 12154.209976351553, 13629.25039122848, 13742.450301987908, 13754.312284385791, 13776.517821876236, 576.4494907363479, 798.7284323330067, 893.714011516315, 896.1531352871507, 897.824924139793, 830.4270053662954, 895.4850960697593, 906.1210625693067, 935.7567032571512, 938.4650749073031, 841.1336323586715, 972.4165500699559, 975.4732444479972, 979.1970584121024, 981.1071924548662, 946.5740416812462, 962.1581116523042, 965.9420434773936, 966.6809952715587, 968.0706521739139, 640.3084668557947, 686.1834417043702, 686.2794066230438, 720.1430297945428, 720.2761224968873, 13221.942244620408, 15169.862260330408, 15233.0, 15332.693346133126, 15463.381464741396, 10584.153267738562, 11957.521699132025, 12200.572979945659, 12323.5070597176, 12432.875671243262, 9933.552990115462, 9936.304458687895, 9947.20422366209, 10021.59913603455, 10460.633877814316, 2865.7564107050925, 3397.575303087114, 3420.435628121357, 3434.4161492546327, 3444.932071057955, 79961.60019199867, 82183.0, 82990.58504707499, 83126.9218269542, 83753.04346087173, 9037.09629037091, 9080.455172689659, 9169.724908252763, 9270.768230794283, 9412.717618471457, 20326.186952522028, 20987.895060524905, 21156.365309040753, 21171.576568468543, 21280.042398091813, 7489.887651685229, 7610.124835643972, 7658.770236892902, 7727.1886451922865, 7785.922140778633, 4068.2677118119022, 4069.409935559327, 4090.959090409058, 4111.218868414991, 4132.8140233689555, 3569.5538057742783, 3633.436817293271, 3636.6908812751212, 3641.6540428659237, 3847.115163512408, 591.9260092488439, 688.7968049425432, 707.4340527577917, 709.716113554572, 709.9680514376865, 531.1952392125933, 585.1486087742325, 608.3976862905691, 612.9295131059985, 621.8227805075531, 8033.758987230392, 8123.6750529978135, 8151.5924203790755, 8239.752807415787, 8340.62467188978, 523.4059342646124, 539.6006954853381, 586.9354371019184, 594.872102497965, 608.1182285685774, 3448.2930999145615, 3815.3323168445413, 3953.367461206193, 3998.4402183694306, 4213.325867861189, 97010.0, 97313.0, 99546.50226748965, 103394.48302758439, 107041.0, 58662.70668646629, 77845.0, 80491.59754201192, 81943.0, 84154.0, 53882.46117538775, 71730.64134679294, 75087.0, 76937.84593231106, 77578.61210693911, 7306.7077316906725, 7661.463697541119, 7723.918651388923, 7790.376769858284, 7858.803529911798, 40732.79633601932, 41697.0, 46453.3032004527, 46980.06039879115, 48735.61379475864, 65067.67466162828, 70152.0, 70254.0, 70456.0, 70932.29067709463, 9142.63429462814, 9254.4910029949, 9347.252219822261, 9446.669366572138, 9880.35777674449, 11704.590339338083, 11840.28958262507, 12118.333491658044, 12316.81524777147, 12431.37843107859, 11155.888441115485, 11191.216614837209, 11311.886881131084, 11561.653150405502, 11707.239029463057, 11357.37534435614, 11414.372209528556, 11625.24435911663, 11642.88357116418, 11727.648170554898, 11107.38909359993, 11122.277051991594, 11192.328460292409, 11282.086873754488, 11583.0, 9155.725328240163, 9182.219511341611, 9337.486438245962, 9339.439633622005, 9470.289728270269, 8812.51531165792, 8854.156316448545, 9074.31942604294, 9320.254379649747, 9822.803543929223], "value": 6818.14065216335, "unit": "score"}], "name": "dromaeo_css", "value": 7181.369507506486, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 116.0, 117.0, 115.0, 129.0, 116.0, 116.0, 159.0, 172.0], "value": 126.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 147.0, 164.0, 121.0, 167.0, 144.0, 127.0, 171.0, 133.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [250.0, 195.0, 209.0, 232.0, 281.0, 200.0, 275.0, 244.0, 203.0, 198.0], "value": 228.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 86.0, 78.0, 143.0, 46.0, 54.0, 73.0, 89.0, 92.0, 48.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 166.0, 115.0, 116.0, 127.0, 126.0, 79.0, 82.0, 125.0], "value": 109.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 90.0, 99.0, 137.0, 86.0, 89.0, 86.0, 90.0, 86.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 91.0, 151.0, 137.0, 95.0, 147.0, 104.0, 95.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 97.0, 98.0, 94.0, 135.0, 143.0, 133.0, 133.0, 128.0], "value": 122.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 65.0, 52.0, 61.0, 92.0, 102.0, 87.0, 51.0], "value": 69.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 87.0, 67.0, 72.0, 72.0, 73.0, 75.0, 64.0, 64.0, 69.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 107.0, 104.0, 85.0, 84.0, 83.0, 85.0, 83.0, 85.0, 85.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 159.0, 131.0, 139.0, 136.0, 134.0, 144.0, 136.0, 236.0, 163.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 176.0, 171.0, 236.0, 231.0, 175.0, 187.0, 172.0, 244.0, 232.0], "value": 199.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 70.0, 67.0, 71.0, 69.0, 68.0, 70.0, 69.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1669.4999999999998, "alertThreshold": 2.0}]} 06:08:50 INFO - Return code: 0 06:08:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:08:50 ERROR - # TBPL SUCCESS # 06:08:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:50 INFO - 2016-04-25 06:08:50.818 screenresolution[3420:53555] starting screenresolution argv=screenresolution get 06:08:50 INFO - 2016-04-25 06:08:50.821 screenresolution[3420:53555] Display 0: 1600x1200x32@60 06:08:50 INFO - 2016-04-25 06:08:50.826 screenresolution[3421:53558] starting screenresolution argv=screenresolution list 06:08:50 INFO - Available Modes on Display 0 06:08:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:51 INFO - Intel Iris: 06:08:51 INFO - Chipset Model: Intel Iris 06:08:51 INFO - Type: GPU 06:08:51 INFO - Bus: Built-In 06:08:51 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:51 INFO - Vendor: Intel (0x8086) 06:08:51 INFO - Device ID: 0x0a2e 06:08:51 INFO - Revision ID: 0x0009 06:08:51 INFO - Displays: 06:08:51 INFO - Display: 06:08:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:51 INFO - Display Serial Number: 1600x1200 60 06:08:51 INFO - Main Display: Yes 06:08:51 INFO - Mirror: Off 06:08:51 INFO - Online: Yes 06:08:51 INFO - Rotation: Supported 06:08:51 INFO - Return code: 0 06:08:51 INFO - Running post-action listener: _resource_record_post_action 06:08:51 INFO - Running post-run listener: _resource_record_post_run 06:08:52 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 12288; Write bytes: 696028160; Read time: 1; Write time: 2040 06:08:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378149376; Write bytes: 373024768; Read time: 28201; Write time: 722 06:08:52 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 12288; Write bytes: 297706496; Read time: 1; Write time: 1268 06:08:52 INFO - Running post-run listener: _upload_blobber_files 06:08:52 INFO - Blob upload gear active. 06:08:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:52 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 06:08:52 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'] 06:08:52 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 06:08:52 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:08:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:08:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:53 INFO - (blobuploader) - INFO - Done attempting. 06:08:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:08:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:08:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:54 INFO - (blobuploader) - INFO - Done attempting. 06:08:54 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:54 INFO - Return code: 0 06:08:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08003fe073fa9b223f21d7789980be82459826947cb958c33152966d83e784612797e23eaf1bee98a24c7572a1a9a0336660d6b81af777935c92078244afd73a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ad6a93494f3e4d093b77c176584f32be10e00bf004484fed36a9c68bef1272e23d7f4c90157bca27441a9ec12f29c93bd9652ce40bc8e404d074873dfaa324"} 06:08:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:54 INFO - Contents: 06:08:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08003fe073fa9b223f21d7789980be82459826947cb958c33152966d83e784612797e23eaf1bee98a24c7572a1a9a0336660d6b81af777935c92078244afd73a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ad6a93494f3e4d093b77c176584f32be10e00bf004484fed36a9c68bef1272e23d7f4c90157bca27441a9ec12f29c93bd9652ce40bc8e404d074873dfaa324"} 06:08:54 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:54 INFO - Copying logs to upload dir... 06:08:54 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.966540 ========= master_lag: 1.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-25 06:08:56.018201) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-25 06:08:56.022655) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08003fe073fa9b223f21d7789980be82459826947cb958c33152966d83e784612797e23eaf1bee98a24c7572a1a9a0336660d6b81af777935c92078244afd73a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ad6a93494f3e4d093b77c176584f32be10e00bf004484fed36a9c68bef1272e23d7f4c90157bca27441a9ec12f29c93bd9652ce40bc8e404d074873dfaa324"} build_url:https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011526 build_url: 'https://queue.taskcluster.net/v1/task/MX1Ysy-xRlaPqYmQPRnRug/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08003fe073fa9b223f21d7789980be82459826947cb958c33152966d83e784612797e23eaf1bee98a24c7572a1a9a0336660d6b81af777935c92078244afd73a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ad6a93494f3e4d093b77c176584f32be10e00bf004484fed36a9c68bef1272e23d7f4c90157bca27441a9ec12f29c93bd9652ce40bc8e404d074873dfaa324"}' ========= master_lag: 3.16 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-25 06:08:59.192082) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 06:08:59.192389) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNVVCrgZZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zVrHQXmqoL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005345 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 06:08:59.299662) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-25 06:08:59.299981) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-25 06:09:01.331106) ========= ========= Total master_lag: 20.38 =========