builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0109 starttime: 1461711053.21 results: success (0) buildid: 20160426124801 builduid: 7b48acd4a17645fcb627f31c8c143f00 revision: 597390d44c49cb5c89260feb4f5e1e6ef2eef15c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.210648) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.211116) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.211389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.229533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.229855) ========= 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.KzeznnkS07/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.8sebif21SY/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-26 15:50:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 193M=0s 2016-04-26 15:50:53 (193 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124959 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.468727) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.469127) ========= 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.KzeznnkS07/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.8sebif21SY/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.020401 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:53.506648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-26 15:50:53.506970) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 597390d44c49cb5c89260feb4f5e1e6ef2eef15c --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 597390d44c49cb5c89260feb4f5e1e6ef2eef15c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KzeznnkS07/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.8sebif21SY/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-26 15:50:53,582 truncating revision to first 12 chars 2016-04-26 15:50:53,582 Setting DEBUG logging. 2016-04-26 15:50:53,583 attempt 1/10 2016-04-26 15:50:53,583 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/597390d44c49?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 15:50:55,067 unpacking tar archive at: fx-team-597390d44c49/testing/mozharness/ program finished with exit code 0 elapsedTime=1.733989 ========= master_lag: 1.95 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-26 15:50:57.194929) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:57.195212) ========= script_repo_revision: 597390d44c49cb5c89260feb4f5e1e6ef2eef15c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 15:50:57.195560) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-26 15:50:57.195815) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-26 15:51:01.483915) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-26 15:51:01.484177) ========= /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.KzeznnkS07/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.8sebif21SY/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:01 INFO - MultiFileLogger online at 20160426 15:51:01 in /builds/slave/test 15:51:01 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 15:51:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:51:01 INFO - {'append_to_log': False, 15:51:01 INFO - 'base_work_dir': '/builds/slave/test', 15:51:01 INFO - 'blob_upload_branch': 'Fx-Team', 15:51:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:51:01 INFO - 'branch': 'Fx-Team', 15:51:01 INFO - 'buildbot_json_path': 'buildprops.json', 15:51:01 INFO - 'config_files': ('talos/mac_config.py',), 15:51:01 INFO - 'default_actions': ('clobber', 15:51:01 INFO - 'read-buildbot-config', 15:51:01 INFO - 'download-and-extract', 15:51:01 INFO - 'populate-webroot', 15:51:01 INFO - 'create-virtualenv', 15:51:01 INFO - 'install', 15:51:01 INFO - 'run-tests'), 15:51:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:51:01 INFO - 'download_minidump_stackwalk': True, 15:51:01 INFO - 'download_symbols': 'ondemand', 15:51:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:51:01 INFO - 'tooltool.py': '/tools/tooltool.py', 15:51:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:51:01 INFO - '/tools/misc-python/virtualenv.py')}, 15:51:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:51:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:51:01 INFO - 'installer_path': 'installer.exe', 15:51:01 INFO - 'log_level': 'info', 15:51:01 INFO - 'log_name': 'talos', 15:51:01 INFO - 'log_to_console': True, 15:51:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:51:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:51:01 INFO - 'opt_config_files': (), 15:51:01 INFO - 'pip_index': False, 15:51:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:51:01 INFO - 'cmd': ('bash', 15:51:01 INFO - '-c', 15:51:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:51:01 INFO - 'enabled': True, 15:51:01 INFO - 'halt_on_failure': False, 15:51:01 INFO - 'name': 'check_screen_resolution'},), 15:51:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:51:01 INFO - 'cmd': ('bash', 15:51:01 INFO - '-c', 15:51:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:51:01 INFO - 'enabled': True, 15:51:01 INFO - 'halt_on_failure': False, 15:51:01 INFO - 'name': 'check_screen_resolution'},), 15:51:01 INFO - 'run_cmd_checks_enabled': True, 15:51:01 INFO - 'sps_profile': False, 15:51:01 INFO - 'sps_profile_interval': 0, 15:51:01 INFO - 'suite': 'dromaeojs-e10s', 15:51:01 INFO - 'system_bits': '32', 15:51:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:51:01 INFO - 'title': 't-yosemite-r7-0109', 15:51:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:51:01 INFO - 'use_talos_json': True, 15:51:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:51:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:51:01 INFO - 'work_dir': 'build'} 15:51:01 INFO - ##### 15:51:01 INFO - ##### Running clobber step. 15:51:01 INFO - ##### 15:51:01 INFO - Running pre-action listener: _resource_record_pre_action 15:51:01 INFO - Running main action method: clobber 15:51:01 INFO - rmtree: /builds/slave/test/build 15:51:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:51:03 INFO - Running post-action listener: _resource_record_post_action 15:51:03 INFO - ##### 15:51:03 INFO - ##### Running read-buildbot-config step. 15:51:03 INFO - ##### 15:51:03 INFO - Running pre-action listener: _resource_record_pre_action 15:51:03 INFO - Running main action method: read_buildbot_config 15:51:03 INFO - Using buildbot properties: 15:51:03 INFO - { 15:51:03 INFO - "project": "", 15:51:03 INFO - "product": "firefox", 15:51:03 INFO - "script_repo_revision": "production", 15:51:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 15:51:03 INFO - "repository": "", 15:51:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 15:51:03 INFO - "buildid": "20160426124801", 15:51:03 INFO - "pgo_build": "False", 15:51:03 INFO - "basedir": "/builds/slave/test", 15:51:03 INFO - "buildnumber": 645, 15:51:03 INFO - "slavename": "t-yosemite-r7-0109", 15:51:03 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:51:03 INFO - "platform": "yosemite_r7", 15:51:03 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 15:51:03 INFO - "branch": "fx-team", 15:51:03 INFO - "repo_path": "integration/fx-team", 15:51:03 INFO - "revision": "597390d44c49cb5c89260feb4f5e1e6ef2eef15c", 15:51:03 INFO - "stage_platform": "macosx64", 15:51:03 INFO - "builduid": "7b48acd4a17645fcb627f31c8c143f00", 15:51:03 INFO - "slavebuilddir": "test" 15:51:03 INFO - } 15:51:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:51:03 INFO - Running post-action listener: _resource_record_post_action 15:51:03 INFO - ##### 15:51:03 INFO - ##### Running download-and-extract step. 15:51:03 INFO - ##### 15:51:03 INFO - Running pre-action listener: _resource_record_pre_action 15:51:03 INFO - Running main action method: download_and_extract 15:51:03 INFO - mkdir: /builds/slave/test/build/tests 15:51: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')]} 15:51:03 INFO - https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:51:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:51:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:51:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:51:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 15:51:06 INFO - Downloaded 1199 bytes. 15:51:06 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:51:06 INFO - Using the following test package requirements: 15:51:06 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:51:06 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:51:06 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:51:06 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'jsshell-mac.zip'], 15:51:06 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:51:06 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:51:06 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:51:06 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:51:06 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:51:06 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:51:06 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:51:06 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:06 INFO - https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:51:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:51:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:51:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:51:07 INFO - Downloaded 18114112 bytes. 15:51:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:51:11 INFO - Return code: 0 15:51:11 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:51:11 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')]} 15:51:11 INFO - https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:51:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:51:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:51:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:51:12 INFO - Downloaded 18114112 bytes. 15:51:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:51:16 INFO - Return code: 0 15:51: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')]} 15:51:16 INFO - https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:51:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:51:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:51:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:51:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:51:17 INFO - Downloaded 10840145 bytes. 15:51:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:51:18 INFO - Return code: 0 15:51:18 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')]} 15:51:18 INFO - https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:51:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:51:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:51:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:51:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 15:51:20 INFO - Downloaded 110127154 bytes. 15:51:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:51:20 INFO - mkdir: /builds/slave/test/properties 15:51:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:20 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:20 INFO - Contents: 15:51:20 INFO - build_url:https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:51:22 INFO - Running post-action listener: _resource_record_post_action 15:51:22 INFO - Running post-action listener: set_extra_try_arguments 15:51:22 INFO - ##### 15:51:22 INFO - ##### Running populate-webroot step. 15:51:22 INFO - ##### 15:51:22 INFO - Running pre-action listener: _resource_record_pre_action 15:51:22 INFO - Running main action method: populate_webroot 15:51:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:22 INFO - '--tppagecycles', 15:51:22 INFO - '7'], 15:51:22 INFO - 'tests': ['tsvgm']}}, 15:51:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['damp', 'tps']}, 15:51:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'tests': ['damp', 'tps']}, 15:51:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['dromaeo_dom']}, 15:51:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tsvgx', 15:51:22 INFO - 'tsvgr_opacity', 15:51:22 INFO - 'tart', 15:51:22 INFO - 'tscrollx', 15:51:22 INFO - 'cart']}, 15:51:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:22 INFO - 'tsvgr_opacity', 15:51:22 INFO - 'tart', 15:51:22 INFO - 'tscrollx', 15:51:22 INFO - 'cart']}, 15:51:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tp5o']}, 15:51:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'tests': ['tp5o']}, 15:51:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s', 15:51:22 INFO - '--xperf_path', 15:51:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:22 INFO - 'tests': ['tp5n']}, 15:51:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--xperf_path', 15:51:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:22 INFO - 'tests': ['tp5n']}}, 15:51:22 INFO - 'talos.zip': {'path': '', 15:51:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:22 INFO - Running post-action listener: _resource_record_post_action 15:51:22 INFO - ##### 15:51:22 INFO - ##### Running create-virtualenv step. 15:51:22 INFO - ##### 15:51:22 INFO - Running pre-action listener: _resource_record_pre_action 15:51:22 INFO - Running main action method: create_virtualenv 15:51:22 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:22 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 15:51:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:22 INFO - Using real prefix '/tools/python27' 15:51:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:23 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:25 INFO - Installing pip.................done. 15:51:25 INFO - Return code: 0 15:51:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:25 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:25 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 15:51:25 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 15:51:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:25 INFO - 'HOME': '/Users/cltbld', 15:51:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:25 INFO - 'LOGNAME': 'cltbld', 15:51:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:25 INFO - 'MOZ_NO_REMOTE': '1', 15:51:25 INFO - 'NO_EM_RESTART': '1', 15:51:25 INFO - 'PAGER': '/bin/cat', 15:51:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:25 INFO - 'PWD': '/builds/slave/test', 15:51:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:25 INFO - 'SHELL': '/bin/bash', 15:51:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:25 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:25 INFO - 'USER': 'cltbld', 15:51:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:25 INFO - 'XPC_FLAGS': '0x0', 15:51:25 INFO - 'XPC_SERVICE_NAME': '0', 15:51:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:26 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 15:51:26 INFO - Cleaning up... 15:51:26 INFO - Return code: 0 15:51:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:51:26 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')]} 15:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 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')]} 15:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:26 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 15:51:26 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 15:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:26 INFO - 'HOME': '/Users/cltbld', 15:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:26 INFO - 'LOGNAME': 'cltbld', 15:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:26 INFO - 'MOZ_NO_REMOTE': '1', 15:51:26 INFO - 'NO_EM_RESTART': '1', 15:51:26 INFO - 'PAGER': '/bin/cat', 15:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:26 INFO - 'PWD': '/builds/slave/test', 15:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:26 INFO - 'SHELL': '/bin/bash', 15:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:26 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:26 INFO - 'USER': 'cltbld', 15:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:26 INFO - 'XPC_FLAGS': '0x0', 15:51:26 INFO - 'XPC_SERVICE_NAME': '0', 15:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:26 INFO - Downloading/unpacking psutil>=0.7.1 15:51:26 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 15:51:26 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 15:51:26 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 15:51:26 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 15:51:26 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 15:51:26 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 15:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:28 INFO - Installing collected packages: psutil 15:51:28 INFO - Running setup.py install for psutil 15:51:29 INFO - building 'psutil._psutil_osx' extension 15:51:29 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 15:51:29 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 15:51:29 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 15:51:29 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 15:51:29 INFO - building 'psutil._psutil_posix' extension 15:51:29 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 15:51:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:51:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:51:29 INFO - ^ 15:51:29 INFO - 1 warning generated. 15:51:29 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 15:51:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:29 INFO - Successfully installed psutil 15:51:29 INFO - Cleaning up... 15:51:29 INFO - Return code: 0 15:51:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:51:29 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')]} 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:29 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')]} 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:29 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:29 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 15:51:29 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 15:51:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:29 INFO - 'HOME': '/Users/cltbld', 15:51:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:29 INFO - 'LOGNAME': 'cltbld', 15:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:29 INFO - 'MOZ_NO_REMOTE': '1', 15:51:29 INFO - 'NO_EM_RESTART': '1', 15:51:29 INFO - 'PAGER': '/bin/cat', 15:51:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:29 INFO - 'PWD': '/builds/slave/test', 15:51:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:29 INFO - 'SHELL': '/bin/bash', 15:51:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:29 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:29 INFO - 'USER': 'cltbld', 15:51:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:29 INFO - 'XPC_FLAGS': '0x0', 15:51:29 INFO - 'XPC_SERVICE_NAME': '0', 15:51:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:51:32 INFO - Installing collected packages: mozsystemmonitor 15:51:32 INFO - Running setup.py install for mozsystemmonitor 15:51:32 INFO - Successfully installed mozsystemmonitor 15:51:32 INFO - Cleaning up... 15:51:32 INFO - Return code: 0 15:51:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51:32 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')]} 15:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:32 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')]} 15:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:32 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:32 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 15:51:32 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 15:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:32 INFO - 'HOME': '/Users/cltbld', 15:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:32 INFO - 'LOGNAME': 'cltbld', 15:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:32 INFO - 'MOZ_NO_REMOTE': '1', 15:51:32 INFO - 'NO_EM_RESTART': '1', 15:51:32 INFO - 'PAGER': '/bin/cat', 15:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:32 INFO - 'PWD': '/builds/slave/test', 15:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:32 INFO - 'SHELL': '/bin/bash', 15:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:32 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:32 INFO - 'USER': 'cltbld', 15:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:32 INFO - 'XPC_FLAGS': '0x0', 15:51:32 INFO - 'XPC_SERVICE_NAME': '0', 15:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:33 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:35 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:36 INFO - Downloading docopt-0.6.1.tar.gz 15:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:36 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:36 INFO - Running setup.py install for blobuploader 15:51:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:36 INFO - Running setup.py install for requests 15:51:37 INFO - Running setup.py install for docopt 15:51:37 INFO - Successfully installed blobuploader requests docopt 15:51:37 INFO - Cleaning up... 15:51:37 INFO - Return code: 0 15:51:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:37 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:37 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 15:51:37 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 15:51:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:37 INFO - 'HOME': '/Users/cltbld', 15:51:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:37 INFO - 'LOGNAME': 'cltbld', 15:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:37 INFO - 'MOZ_NO_REMOTE': '1', 15:51:37 INFO - 'NO_EM_RESTART': '1', 15:51:37 INFO - 'PAGER': '/bin/cat', 15:51:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:37 INFO - 'PWD': '/builds/slave/test', 15:51:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:37 INFO - 'SHELL': '/bin/bash', 15:51:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:37 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:37 INFO - 'USER': 'cltbld', 15:51:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:37 INFO - 'XPC_FLAGS': '0x0', 15:51:37 INFO - 'XPC_SERVICE_NAME': '0', 15:51:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:37 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-M0Ybw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-bs3AFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-2OA5m7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-mobZng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-cqFwcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-irvvTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-W25aYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-T8uCZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-WYOZ5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ssEAay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:38 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-_8SJMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-GQh7En-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-eM3bVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ZfDEtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-l5hONH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oxqLtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-CtiJqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:39 INFO - Running setup.py install for manifestparser 15:51:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Running setup.py install for mozcrash 15:51:39 INFO - Running setup.py install for mozdebug 15:51:40 INFO - Running setup.py install for mozdevice 15:51:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Running setup.py install for mozfile 15:51:40 INFO - Running setup.py install for mozhttpd 15:51:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Running setup.py install for mozinfo 15:51:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Running setup.py install for mozInstall 15:51:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Running setup.py install for mozleak 15:51:40 INFO - Running setup.py install for mozlog 15:51:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:41 INFO - Running setup.py install for moznetwork 15:51:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:41 INFO - Running setup.py install for mozprocess 15:51:41 INFO - Running setup.py install for mozprofile 15:51:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:41 INFO - Running setup.py install for mozrunner 15:51:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:41 INFO - Running setup.py install for mozscreenshot 15:51:41 INFO - Running setup.py install for moztest 15:51:42 INFO - Running setup.py install for mozversion 15:51:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:42 INFO - Cleaning up... 15:51:42 INFO - Return code: 0 15:51:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:42 INFO - 'HOME': '/Users/cltbld', 15:51:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:42 INFO - 'LOGNAME': 'cltbld', 15:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:42 INFO - 'MOZ_NO_REMOTE': '1', 15:51:42 INFO - 'NO_EM_RESTART': '1', 15:51:42 INFO - 'PAGER': '/bin/cat', 15:51:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:42 INFO - 'PWD': '/builds/slave/test', 15:51:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:42 INFO - 'SHELL': '/bin/bash', 15:51:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:42 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:42 INFO - 'USER': 'cltbld', 15:51:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:42 INFO - 'XPC_FLAGS': '0x0', 15:51:42 INFO - 'XPC_SERVICE_NAME': '0', 15:51:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-9z2Pxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UIXCc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-CCujJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-70GbOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-2KFT58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:43 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)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-XuJO9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:43 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)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-hbdcXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:43 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)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-I4Yg2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:43 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)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-F7cTWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-VnHqQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:43 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)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-O9Funl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-_79_rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-2R6d0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:43 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-4lFODq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:44 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)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:44 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-A0T4XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:44 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)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:44 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-hwkdnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:44 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)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:44 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-l6LMp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:44 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)) 15:51:44 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)) 15:51:44 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)) 15:51:44 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)) 15:51:44 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)) 15:51:44 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)) 15:51:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:46 INFO - Downloading blessings-1.6.tar.gz 15:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:46 INFO - Installing collected packages: blessings 15:51:46 INFO - Running setup.py install for blessings 15:51:46 INFO - Successfully installed blessings 15:51:46 INFO - Cleaning up... 15:51:47 INFO - Return code: 0 15:51:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:47 INFO - Reading from file tmpfile_stdout 15:51:47 INFO - Current package versions: 15:51:47 INFO - blessings == 1.6 15:51:47 INFO - blobuploader == 1.2.4 15:51:47 INFO - docopt == 0.6.1 15:51:47 INFO - manifestparser == 1.1 15:51:47 INFO - mozInstall == 1.12 15:51:47 INFO - mozcrash == 0.17 15:51:47 INFO - mozdebug == 0.1 15:51:47 INFO - mozdevice == 0.48 15:51:47 INFO - mozfile == 1.2 15:51:47 INFO - mozhttpd == 0.7 15:51:47 INFO - mozinfo == 0.9 15:51:47 INFO - mozleak == 0.1 15:51:47 INFO - mozlog == 3.1 15:51:47 INFO - moznetwork == 0.27 15:51:47 INFO - mozprocess == 0.22 15:51:47 INFO - mozprofile == 0.28 15:51:47 INFO - mozrunner == 6.11 15:51:47 INFO - mozscreenshot == 0.1 15:51:47 INFO - mozsystemmonitor == 0.0 15:51:47 INFO - moztest == 0.7 15:51:47 INFO - mozversion == 1.4 15:51:47 INFO - psutil == 3.1.1 15:51:47 INFO - requests == 1.2.3 15:51:47 INFO - wsgiref == 0.1.2 15:51:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:47 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')]} 15:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:47 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')]} 15:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:47 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:47 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 15:51:47 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 15:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:47 INFO - 'HOME': '/Users/cltbld', 15:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:47 INFO - 'LOGNAME': 'cltbld', 15:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:47 INFO - 'MOZ_NO_REMOTE': '1', 15:51:47 INFO - 'NO_EM_RESTART': '1', 15:51:47 INFO - 'PAGER': '/bin/cat', 15:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:47 INFO - 'PWD': '/builds/slave/test', 15:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:47 INFO - 'SHELL': '/bin/bash', 15:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:47 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:47 INFO - 'USER': 'cltbld', 15:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:47 INFO - 'XPC_FLAGS': '0x0', 15:51:47 INFO - 'XPC_SERVICE_NAME': '0', 15:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 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)) 15:51:47 INFO - Cleaning up... 15:51:47 INFO - Return code: 0 15:51:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:47 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')]} 15:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:47 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')]} 15:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:47 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:47 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 15:51:47 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 15:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:47 INFO - 'HOME': '/Users/cltbld', 15:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:47 INFO - 'LOGNAME': 'cltbld', 15:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:47 INFO - 'MOZ_NO_REMOTE': '1', 15:51:47 INFO - 'NO_EM_RESTART': '1', 15:51:47 INFO - 'PAGER': '/bin/cat', 15:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:47 INFO - 'PWD': '/builds/slave/test', 15:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:47 INFO - 'SHELL': '/bin/bash', 15:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:47 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:47 INFO - 'USER': 'cltbld', 15:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:47 INFO - 'XPC_FLAGS': '0x0', 15:51:47 INFO - 'XPC_SERVICE_NAME': '0', 15:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:47 INFO - Downloading/unpacking jsonschema 15:51:47 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 15:51:47 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 15:51:47 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 15:51:47 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 15:51:47 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 15:51:47 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 15:51:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:50 INFO - Downloading/unpacking functools32 (from jsonschema) 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:50 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:50 INFO - warning: no files found matching '*.txt' 15:51:50 INFO - no previously-included directories found matching 'build' 15:51:50 INFO - no previously-included directories found matching 'dist' 15:51:50 INFO - no previously-included directories found matching '.git*' 15:51:50 INFO - Installing collected packages: jsonschema, functools32 15:51:50 INFO - Running setup.py install for functools32 15:51:51 INFO - warning: no files found matching '*.txt' 15:51:51 INFO - no previously-included directories found matching 'build' 15:51:51 INFO - no previously-included directories found matching 'dist' 15:51:51 INFO - no previously-included directories found matching '.git*' 15:51:51 INFO - Successfully installed jsonschema functools32 15:51:51 INFO - Cleaning up... 15:51:51 INFO - Return code: 0 15:51:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:51:51 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')]} 15:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 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')]} 15:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 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 0x10b75da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b76d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0994432e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, '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.KzeznnkS07/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.8sebif21SY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 15:51:51 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 15:51:51 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 15:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:51 INFO - 'HOME': '/Users/cltbld', 15:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:51 INFO - 'LOGNAME': 'cltbld', 15:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:51 INFO - 'MOZ_NO_REMOTE': '1', 15:51:51 INFO - 'NO_EM_RESTART': '1', 15:51:51 INFO - 'PAGER': '/bin/cat', 15:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:51 INFO - 'PWD': '/builds/slave/test', 15:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:51 INFO - 'SHELL': '/bin/bash', 15:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:51:51 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:51 INFO - 'USER': 'cltbld', 15:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:51 INFO - 'XPC_FLAGS': '0x0', 15:51:51 INFO - 'XPC_SERVICE_NAME': '0', 15:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:52 INFO - Downloading/unpacking flake8 15:51:52 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 15:51:52 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 15:51:52 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 15:51:52 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 15:51:52 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 15:51:52 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 15:51:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:51:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:51:54 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 15:51:54 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 15:51:54 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 15:51:54 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 15:51:54 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 15:51:54 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 15:51:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:51:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:51:54 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 15:51:54 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 15:51:54 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 15:51:54 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 15:51:54 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 15:51:54 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 15:51:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:51:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:55 INFO - no previously-included directories found matching 'docs/_build' 15:51:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:51:55 INFO - Running setup.py install for pep8 15:51:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:56 INFO - no previously-included directories found matching 'docs/_build' 15:51:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:51:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:51:56 INFO - Cleaning up... 15:51:56 INFO - Return code: 0 15:51:56 INFO - Running post-action listener: _resource_record_post_action 15:51:56 INFO - Running post-action listener: _start_resource_monitoring 15:51:56 INFO - Starting resource monitoring. 15:51:56 INFO - ##### 15:51:56 INFO - ##### Running install step. 15:51:56 INFO - ##### 15:51:56 INFO - Running pre-action listener: _resource_record_pre_action 15:51:56 INFO - Running main action method: install 15:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:56 INFO - Reading from file tmpfile_stdout 15:51:56 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:56 INFO - Reading from file tmpfile_stdout 15:51:56 INFO - Output received: 15:51:56 INFO - Usage: mozinstall [options] installer 15:51:56 INFO - Options: 15:51:56 INFO - -h, --help show this help message and exit 15:51:56 INFO - -d DEST, --destination=DEST 15:51:56 INFO - Directory to install application into. [default: 15:51:56 INFO - "/builds/slave/test"] 15:51:56 INFO - --app=APP Application being installed. [default: firefox] 15:51:56 INFO - mkdir: /builds/slave/test/build/application 15:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:52:29 INFO - Reading from file tmpfile_stdout 15:52:29 INFO - Output received: 15:52:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:52:29 INFO - Running post-action listener: _resource_record_post_action 15:52:29 INFO - ##### 15:52:29 INFO - ##### Running run-tests step. 15:52:29 INFO - ##### 15:52:29 INFO - Running pre-action listener: _resource_record_pre_action 15:52:29 INFO - Running main action method: run_tests 15:52:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:52:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:52:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:52:29 INFO - 2016-04-26 15:52:29.932 screenresolution[2198:20303] starting screenresolution argv=screenresolution get 15:52:29 INFO - 2016-04-26 15:52:29.934 screenresolution[2198:20303] Display 0: 1600x1200x32@60 15:52:29 INFO - 2016-04-26 15:52:29.940 screenresolution[2199:20306] starting screenresolution argv=screenresolution list 15:52:29 INFO - Available Modes on Display 0 15:52:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:52:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:52:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:52:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:52:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:52:30 INFO - Intel Iris: 15:52:30 INFO - Chipset Model: Intel Iris 15:52:30 INFO - Type: GPU 15:52:30 INFO - Bus: Built-In 15:52:30 INFO - VRAM (Dynamic, Max): 1536 MB 15:52:30 INFO - Vendor: Intel (0x8086) 15:52:30 INFO - Device ID: 0x0a2e 15:52:30 INFO - Revision ID: 0x0009 15:52:30 INFO - Displays: 15:52:30 INFO - Display: 15:52:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:52:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:52:30 INFO - Display Serial Number: 1600x1200 60 15:52:30 INFO - Main Display: Yes 15:52:30 INFO - Mirror: Off 15:52:30 INFO - Online: Yes 15:52:30 INFO - Rotation: Supported 15:52:30 INFO - Return code: 0 15:52:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:30 INFO - Python 2.7.3 15:52:30 INFO - Return code: 0 15:52:30 INFO - grabbing minidump binary from tooltool 15:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:30 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 0x10b5fce00>, '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 0x10b465db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:30 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 15:52:30 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 15:52:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:30 INFO - Return code: 0 15:52:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:52:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:52:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:52:31 INFO - Return code: 0 15:52:31 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/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 15:52:31 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/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0109 --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 15:52:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzeznnkS07/Render', 15:52:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:31 INFO - 'HOME': '/Users/cltbld', 15:52:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:31 INFO - 'LOGNAME': 'cltbld', 15:52:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:31 INFO - 'MOZ_NO_REMOTE': '1', 15:52:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:31 INFO - 'NO_EM_RESTART': '1', 15:52:31 INFO - 'PAGER': '/bin/cat', 15:52:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:31 INFO - 'PWD': '/builds/slave/test', 15:52:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:31 INFO - 'SHELL': '/bin/bash', 15:52:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8sebif21SY/Listeners', 15:52:31 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 15:52:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:31 INFO - 'USER': 'cltbld', 15:52:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:31 INFO - 'XPC_FLAGS': '0x0', 15:52:31 INFO - 'XPC_SERVICE_NAME': '0', 15:52:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:31 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/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 15:52:31 INFO - mozversion application_buildid: 20160426124801 15:52:31 INFO - mozversion application_changeset: 597390d44c49cb5c89260feb4f5e1e6ef2eef15c 15:52:31 INFO - mozversion application_display_name: Nightly 15:52:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:31 INFO - mozversion application_name: Firefox 15:52:31 INFO - mozversion application_remotingname: firefox 15:52:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:52:31 INFO - mozversion application_vendor: Mozilla 15:52:31 INFO - mozversion application_version: 49.0a1 15:52:31 INFO - mozversion platform_buildid: 20160426124801 15:52:31 INFO - mozversion platform_changeset: 597390d44c49cb5c89260feb4f5e1e6ef2eef15c 15:52:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:52:31 INFO - mozversion platform_version: 49.0a1 15:52:31 INFO - using testdate: 1461711151 15:52:31 INFO - actual date: 1461711151 15:52:31 INFO - starting webserver on 'localhost:57533' 15:52:31 INFO - SUITE-START | Running 2 tests 15:52:31 INFO - TEST-START | dromaeo_css 15:52:31 INFO - operating with platform_type : mac_ 15:52:31 INFO - Initialising browser for dromaeo_css test... 15:52:31 INFO - TEST-INFO | started process 2217 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpTrcDw1/profile http://localhost:57533/getInfo.html) 15:52:34 INFO - PROCESS | 2217 | __metrics Screen width/height:1600/1200 15:52:34 INFO - PROCESS | 2217 | colorDepth:24 15:52:34 INFO - PROCESS | 2217 | Browser inner width/height: 1024/689 15:52:34 INFO - PROCESS | 2217 | __metrics 15:52:34 ERROR - PROCESS | 2217 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:52:35 INFO - TEST-INFO | 2217: exit 0 15:52:35 INFO - Browser initialized. 15:52:35 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:35 INFO - TEST-INFO | started process 2220 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpTrcDw1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:47 INFO - PROCESS | 2220 | RSS: Main: 249532416 15:52:47 INFO - PROCESS | 2220 | 15:53:36 INFO - PROCESS | 2220 | 1461711216763 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] 15:55:47 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:57533/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57533/tests/dromaeo/cssquery-ext.html) 15:55:48 INFO - PROCESS | 2220 | RSS: Main: 263421952 15:55:48 INFO - PROCESS | 2220 | 15:58:50 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:57533/tests/dromaeo/cssquery-ext.html (next: http://localhost:57533/tests/dromaeo/cssquery-jquery.html) 15:58:51 INFO - PROCESS | 2220 | RSS: Main: 275185664 15:58:51 INFO - PROCESS | 2220 | 16:01:52 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:57533/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57533/tests/dromaeo/cssquery-mootools.html) 16:01:53 INFO - PROCESS | 2220 | RSS: Main: 277295104 16:01:53 INFO - PROCESS | 2220 | 16:04:55 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:57533/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57533/tests/dromaeo/cssquery-prototype.html) 16:04:55 INFO - PROCESS | 2220 | RSS: Main: 279527424 16:04:55 INFO - PROCESS | 2220 | 16:07:58 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:57533/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57533/tests/dromaeo/cssquery-yui.html) 16:07:59 INFO - PROCESS | 2220 | RSS: Main: 281755648 16:07:59 INFO - PROCESS | 2220 | 16:11:01 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:57533/tests/dromaeo/cssquery-yui.html (next: http://localhost:57533/tests/dromaeo/cssquery-dojo.html) 16:11:02 INFO - PROCESS | 2220 | RSS: Main: 282923008 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | __start_tp_report 16:11:02 INFO - PROCESS | 2220 | _x_x_mozilla_page_load 16:11:02 INFO - PROCESS | 2220 | _x_x_mozilla_page_load_details 16:11:02 INFO - PROCESS | 2220 | |i|pagename|runs| 16:11:02 INFO - PROCESS | 2220 | |0;dojo.html;3419.914502137446;3424.726021918246;3465;3468.583769947605;3504.9299014019703;5838.299404071512;5844.824655260344;5876.324222714389;5890.087036509337;5905.527557795377;5694.2312787773635;5818.5926985110955;5820.15607736879;5919.408059194078;5952.672603006833;5192.273081768546;5253.789848406059;5269.1832765921345;5302.867428314304;5339.279197308372;311.8994397189886;5129.307543481638;5222.608304377168;5259.447757985414;5262.842114736545;3910.9244957636733;4232.899446143994;5283.154695248781;5298.549623282006;5313.149896016619;5289.735513224324;5315.282436871012;5316.989771943318;5317.9468205317835;5352.839414817562;5160.277561141444;5174.43081261061;5224.451432599561;5262.158054711266;5306.336707911511;5578.553715702734;5628.380878103405;5651.208830763697;5696.1740547620775;5719.256496655408;6096.889554637394;6208.441240288395;6209.5032397408095;6210.96894515529;6222.43998040174;6165.229346331708;6207.068939659042;6217.942949698562;6238.064290356438;6257.906131408033;5989.760409583611;6017.0974353846395;6021.939780602138;6038.33578306386;6091.933911565458;6163.1679723237685;6253.374662533801;6328.208973878265;6368.490520758328;6374.139491168726;5290;6083.300420451616;6121.051237058174;6130.153553065376;6166.105914642351;6250.9062364064575;6270.7805226816845;6288.308286088527;6312.684365781693;6321.431071203614;5543.334799824047;5968.403159684084;6208.844778880474;6213.565050446426;6225.844353891189;6206.441420272197;6215.533834962396;6219.813405597839;6244.7814326499265;6259.6870156492;4981.551660350513;5001.224810154432;5007.574356179693;5010.89978200434;5017.523335283142;4342.544032876565;4439.311906654474;4488.910221795546;4512.052468981485;4552.089582083597;4453;5494.972525137348;5546.722663866871;5934.554908381889;6021.126936594257;6224.253089629274;6253.624782513064;6264.060390941304;6270.968645156745;6293.653849038255;5836.124581312837;5917.881642367214;6013;6029.698515074228;6032.1856549365275;5444.673319600836;5465.9180112298345;5471.945280547144;5492.725363731797;5496.203050557647;6517.9674101630135;6549.2468366137555;6552.082708420825;6556.147700798866;6557;6123.343628574205;6231.40801623845;6285.748570057147;6309.558330916975;6314.715837787402;5547.2511210986;6460.773872914426;6507.414332710079;6516.674166291761;6542.084108224947;6089.360617135314;6169.444749972524;6179.721912513858;6228.813135605939;6229.5327850410495;4846.297286893451;5716.456936591017;6181.134641150153;6233.812985610439;6302.779402721067;4801.375821143229;6147.262328520606;6219.0982307566055;6219.253689557282;6228.5951413158;4926.531979461945;6112.72492737819;6168.784092556919;6179.783707570394;6211.440970312694;4708.340832283415;5012.724300163381;5988.101784732325;6121.602095863933;6139.508839292756;6221.720022598904;6223.408776166257;6231.750729970931;6249.25008998923;6268.404501572343;5951.7619295227705;6027.668478233739;6065;6107.633541987495;6160.322364539987 16:11:02 INFO - PROCESS | 2220 | |1;ext.html;480.93507376504164;501.4433978284105;524.4729047307455;604.274870155813;625.146674788913;37353.87938361847;38185.42721859174;38190.809045954804;38243.80878095609;38302.04244893872;38645.42031869524;39327.60672393275;39333.60666393335;39341.81974540769;39452.40821387681;9397.107274808883;9613;9625.51872406377;9637.662681806138;9683.080107389822;9480.715578532656;9632.711018669415;9694.369865958726;9698.321117521777;9753.268504862128;9656.75858103546;9664.613415463373;9689.467079310634;9709.029097090304;9720.513974301291;8918.152775486318;9082.000979892273;9145.72562823113;9681.322307438482;9715.319927605071;8915.484367657515;9732.854007189897;9737.56180971858;9748.31761776676;9752.90247097527;32293.192670183198;38583.22833543314;38823.61176388228;38962.80518597389;38966;2811.9718802812117;3039.7720170987263;3081.1218802641138;3200.647928727838;3410.198603328216;26474.6028809568;26488.337791555172;26505.808491283642;27035.324116897038;27371.86314068417;2592.494463579603;2644.7884169266413;2678.9866050669816;2680.142354446578;2718.415540658748;349748;352056;354173;355004;355224;29242.707572924424;35269.64730352664;35342.46986295208;35394.64605353965;35425;36114;36252.27495450129;51950.70123246949;61515.38484615186;62319.37680623226;13084.28036458004;13585.932070339584;14269.78706809912;15796.842031579767;15912.363505459653;12044.87955120455;12418.937905310597;12488.438020289108;12498.875011249953;12529.93735031319;15542.067475951479;18450.907745461187;18703.438896833115;18972.620547589246;18997.955112468946;7997.560134192675;8048.476849004796;8124.796880077927;8134.518971713685;8143.51138931666;843.3548335523329;1051.7370657335514;1069.5989004123453;1137.5279259107378;1177.4877928101303;23344.7665523346;28761.856190719333;29044.709552904624;29535.113946581638;29600;1058.5554067291755;1077.789830982951;1096.9012788849043;1101.168617693652;1105.160078340467;13211.075224734177;14850.480233191787;15071.849281507044;15106.091888261573;15163.545093647208;491549;495701;496342;502722;502825;58527;58772.11841822376;58886;59267;59319.40680593139;1435.3135066297268;1454.5999850041323;1473.4774066797754;1478.1869971515623;1483.6958423523151;28566.143015709564;28763.568546472237;29141;29290;29291.85354073216;52322.21516677329;54893.17660235179;55924.720376397854;56756.43243567512;56832;470961.29038709175;471071;472994;477328;479608;41179.58820411757;41315.96710082272;41929;42498.36252456154;42519.5748042528;45434.77282613566;45822.77088614536;45884;46042.76978615086;46594.534054659016;30648;36871.44692829663;37447.25105497929;37740.62259377371;38461.615383846925;36718.632813671524;37106.628933710315;37237.44143837791;37812.43281350837;38091.61908380881;36302.46219222597;36885.44671829978;36900.261994759414;37204.813975929945;37266.69566565157;32050.35899282048;33293.50059749057;33318.83340583282;33468.99593012214;33479.49780753242;24237.75762242355;24544.631830522914;24660.50678986446;25120.246392608253;25259.368515787253 16:11:02 INFO - PROCESS | 2220 | |2;jquery.html;395.86144849302747;400.9979950100249;401.6525705264945;408.8677546594509;412.29291764623673;19624.509387265327;19942.302019429324;20398.69401958972;20423.387298381036;20951.161953521878;19727.5068123297;19907.402777916686;21014.264500742476;21266.46833829155;21295.68056479154;5284.39229488608;5618.887622247553;6157.969210153943;6174.135621013062;6274.121622972787;5920.171176035358;5959.791407300745;5972.522198224132;6007.759689612432;6062.514998800086;6014.458698717114;6101.969490152543;6110.08348747687;6126.7549298028025;6129.509639228851;5178.223266510035;5557.972210138943;6057.061155520902;6071.787487437919;6111.816645500619;5259.13300778222;5275.340582427197;5283.973580132075;5286.788528458857;5294.602904782157;17868.28526858937;21508.924553772405;24454.14410495624;25384.87307563469;25549.105781297752;2181.974471998158;2188.753817593863;2203.623159490837;2268.6029944759603;2389.97610023901;14289.785653215211;14317.42730290787;14701.705965880623;15076.396944122222;15269;1770.6281680847037;1813.9002354870477;1814.5010122216497;1839.0253165822135;1849.6393203325356;96751.51624241903;98523;98587;99278.0072199276;99865.5020174698;10958.780824383468;11033.558657653604;11144.888551114385;11160.330380177214;11351.205415620827;11567.363794991188;13625.727485450236;13772.380242889094;13792.448302068005;13806.585802425943;8490.193431623986;8664.090270521505;8710.869336959951;8736;8748.825023499621;6828.453723702191;7010.719571217196;7464.029676142067;8666.956665216634;8674.826503469896;6669.466442684574;6800.829979250559;6805.557638753564;6875.621840798804;6879.140107486564;1910.4937191644226;1922.5385907382129;1924.0668275886185;1924.0956750327323;1931.9903400483188;986.4574484033752;996.292632231124;1056.1392465140887;1092.5684354680016;1112.2547892911768;16826.411075612294;18318.358857439925;20660.79339206619;21095.839728814757;21099.578008439745;919.1865199298669;971.3346357744967;977.9853302200472;1069.4973362519604;1115.5259014918627;11138.66584002465;11472.885271147348;11526.88473115275;11545.191836571528;11570.363630000262;561620;574781;576020;583343;592366;86541;89266.66100008505;90798.09201908029;95873;96526;1007.1942446043137;1013.1135256650431;1017.6438246613626;1024.3085917005992;1053.8840727519814;17165.399211027554;18730.34443794461;18850.245990160787;19077.809221907602;19136.521586960436;47342;48086.759566201945;48590;48933.51066489289;49719.50280497294;66567.66716166388;67022;67516.6624166876;67847.18822741932;68637.65681171563;6230.320961986389;6969.721211151497;7494.7633640450895;7752.844943100994;7978.441509094308;8773;12605.74788504243;12629;12829.55096571653;12957.157784743962;10873.510692018719;10955.945220274167;11189.552417903193;11396.829047564459;11432.885671143182;9455.362716943438;10812.891871081189;11041.44792760375;11076.446177691245;11244.212905096565;11114.610988615359;11322.60370887048;11363.602273920704;11385.829212561652;11635.88364116348;8068.273855353046;8159.388045896463;8280.792980175303;8337.374696897636;8397.70608028716;10397.688069357931;10591.5233814477;10945.726356841144;10977.615783447542;11327.830082548933 16:11:02 INFO - PROCESS | 2220 | |3;mootools.html;1593.1158207195006;1631.657351956089;1905.5331443796272;1955.3840540229817;1991.3372334101034;7772.572508512029;8147.837043259131;8603.65585376585;8955.820883582324;9011.909880901198;7454.1055073391135;8542.957285213566;8648.091950345217;8665.220130188281;8691.130886911305;2275.977240227593;2297.781710737486;2302.3668491164894;2315.69895913531;2317.7450480447137;2382;2421.4914867877765;2433.488967316857;2438.2928950604305;2468.9135880244194;2451.9264422067367;2457.041753716052;2464.112919349033;2473.2332976777166;2500.0499810072147;2406.2901444073864;2457.4839283750343;2457.5453541094953;2468.642046903207;2469.8147638927153;2255.9735320429168;2273.306641474366;2460.2865169100937;2460.2988148377804;2460.803135749136;8803.251723603502;8837.293016558724;8866.822663546758;8885.822283554293;8902.33232507558;5150.3047088642925;5340.492653197979;5358.624896257264;5374.16700411433;5374.731263436852;2822.5342818434933;3441.4665726812386;3443.8278086095597;3587.712982961357;3606.5672119345845;995.1690338567305;1020.6070662794878;1024.2676486312323;1025.3642741500319;1029.9330543514648;9960.053794889474;10703.518341674644;11265.26775759573;11284;11284.943575282154;4694.718316900997;4737.786799594027;4819.927701084486;4827.010462855106;4832.63755218353;9681.854772178423;9687.467189304656;9692.030796920393;9695.369800962915;9699.224062075016;5219.269302297681;5267.236250743621;5305.230741542455;5372.758225879845;5376.247325374451;4349.565043495689;4799.688020278671;4845.927311090337;5006.674566153189;5015.22264049073;3967.980160099181;4007.519097708294;4043.3732771420478;4120.011197312623;4179.26862799009;1182.2433642468827;1348.7032852772375;1349.6086135020735;1351.8850897673808;1358.2936872826076;848.4781859156574;864.6454953469048;865.4158443050916;898.4788822483075;914.9222316103072;4794.256890182028;5660.122680984454;5738.254026976467;5872.530197584183;6146.361929116543;911.6262332443799;920.5857364186143;943.3019565521465;944.2804722399445;958.6644674363916;3697.2975134724315;3711.721620878445;3830.751001184914;3885.805709714503;3895.8831235062994;9586.185174260267;10871.184661150446;10959.232853700323;11229.213955023233;11247.87521247885;10461.895381046092;10698.839517407245;10904.61833835812;11021.944890275498;11239.606613768643;8231.753047408587;8235.629396677044;8369.288610468057;8423.410361274651;8434.409591328671;4464.888377790581;5548.223248745098;5638.21065050885;5673.120666296731;5737.282839645044;8173.918260817316;9616.326857119935;9654.613815447303;9974.351667141613;10010.899891000996;8433.241008309282;9781.85327220078;9840.704778856645;10139.594416223268;10206.846897296398;6712;7367.115946086504;7481.850362992818;7646.8088297793;7711.1132219795445;7068.151821781419;7629.2752188542;8323.500589964378;8413.45312554682;8414.4109912308;6401.519886008661;6903.792886213421;7404.888926666212;7485.288897554724;7644.541327520366;7383.409327253914;7450.96274518624;7519.774406767806;7522.247775222434;7529.13414957265;6736.02327662496;6763.917773156271;6817.488688348294;6866.896996544956;6897.103376561015;4368.301071828492;4692.343071969859;4777.570018698334;4780.211265141352;4954.603631709527;4675.158471475167;4852.878678033077;4877.097736918681;4878.804847806048;4905.705657660552 16:11:02 INFO - PROCESS | 2220 | |4;prototype.html;269.34414700205;297.9215240828202;313.1841552755072;329.59459864366823;331.3092202757251;5383.031054410205;5502.972485137579;5546.667199968005;5612;5668.6395265136325;5014.3982722073315;5511.071125106221;5610.887782244353;5639.900219457208;5795.449432303935;3699.278640665065;4031.9596804032026;4248.511421186557;4293.892652683677;4305.461817272841;3568.946465803015;4277.97328641127;4305.375720520523;4320.611144996949;4338.696291259613;3365.444701624229;3438.965610343902;4219.2405367033925;4236.639885609727;4279.186954478655;4044.3124668806227;4261.6803739719335;4272.764997925113;4276.144771045803;4310.181065597527;3422.5528746945806;3705.7776533407814;3709.350863598859;3711.0907827582146;3724.4599533067553;4430.756308403036;5155.639105262633;5336.866578335534;5410.783568657249;5440.347158340985;4869.269609558594;4871.171900776841;4875.8781030474165;4894.632902532288;4920.606351491872;4894.853154405374;5752.648127689989;5853.122031695238;5922.09817189574;6012.6392416455155;4071.6742660587083;4074.6740260779065;4079.755214687128;4084.550699423061;4089.5297040840387;5007.899842003176;5878.764849406061;6231.626102433823;6267.811965640993;6271.968640156816;3452.9813704956596;3646.835892384849;3826.559945606291;4130.834766609302;4132.318167502389;3278.344331133783;3490.8254587270535;3840;3848.826802793881;4216.373918430148;4794.9211427428545;4808;4869.780859861315;4875.634327425386;4912.484189160158;5428.430014848463;5477.972610137003;5672.205891175238;5976.324560395413;6007.729652165575;1426.8216472940883;1481.8518148185094;1544.4246868194602;1553.3553575266365;1558.0885182168558;983.9901600984042;1006.5420233793607;1007.4912169354576;1035.7669524357111;1036.4817591204398;5279.894402111936;5533.612647114744;6013.819585412444;6027.397260273938;6083.665398403042;6252.749450109905;6576.638284894281;6616.610511792577;6642.4021838034805;6642.601443913284;5356.491133342326;5398.811041613525;5696.6582005079;5716.685582293014;5727.742251598688;3528.8764893228613;3539.203679172217;3560.1099725068734;3569.5002699622073;3577.678008979204;4964.67729597575;6446.323136070645;6523.934760652428;7016.158061032709;7094.822629434306;5147.948520514822;5589.916151257735;6108.419700128481;6403.391677790602;6404.3595640436515;5844.8538786529825;5984.730687119177;6242.344553821783;6484.870302593922;6493.31820158886;5660.971695141498;5827.863566604471;6194;6225.688715564113;6234.75060997555;6148.846278843064;6150.10823430609;6166.229221347332;6175.289841668086;6188.381161883775;5507.173923911445;6090.634561926298;6115.388461153849;6236.282827474717;6437.485001199986;3742.831572579295;4040.1717647881524;4061.6953728469894;4089.693273004596;4102.687140115135;5608.186812912187;5822.417758224144;6237.594556353823;6275.592086514362;6296.512877413487;5650.84157747669;5752.367239603724;5784.033428634117;6040.637561746309;6043.728032238472;5173.663711858717;5294.49702278283;6022.307434644897;6036.366181550874;6042.939570604238;4666.976665116788;4753.191957367187;5011.774470148779;5094.490550944876;5352.919706204332;5281.498257665516;5436.782528698808;5786.884262314814;5903.760210355702;5988.443004818866;4710.340552322609;4799.232122860465;5551.140367976612;5808.157817116579;5909.497692695997 16:11:02 INFO - PROCESS | 2220 | |5;yui.html;679.9830004249897;705.6894966214865;712.7362875735978;729.7154109897141;806.4354951533926;11937;15116.244187790622;15263.618409539768;15268.84731152688;15314;13872.583822485316;13946.860531394683;14287.571372858805;14302.213378264218;14332.713345733078;726.7529040126356;910.8841668955542;921.787988762585;929.251952178496;930.241852889896;903.1600611431367;918.4718786697642;939.506758951549;939.9906000940006;942.7124726958275;950.8906475755273;972.9173020293283;977.218225419665;978.5400861595039;979.1236843025488;860.3117505995241;945.5886689290181;951.1487218939045;953.4184147669915;971.0532231074716;658.4436151452063;708.0688894104288;712.479889680531;717.160921721587;719.2950908110081;15044.398224071025;15214.239288035555;15354.692906141927;15358.769618455732;15455.459058932996;11738.65005524367;12179.147459677826;12637.557685480964;12706.872931270662;12783.105182637126;9311.627534898664;10036;10081.546330415149;10319.84520232197;10407.427591482465;3460.1349662584353;3479;3523.4714792781033;3555.231454658045;3621.6378362163837;54987;75069.62465187693;78227;78289.21710782876;78537.60731196307;9548.331616786758;9569.760755981017;9571.18644915176;9837.114659680663;9847.261455390875;21782.237621683165;22286.665700014513;22309.553808924054;22370.77629223687;22552.436189095402;7383.778486645409;7449.478536502393;7469.663865126083;7521.360684341783;7760.068791745027;3963.1677347757295;4022.3966405039478;4025.5571887092397;4025.6376926076796;4039.899002524902;3471.8611255549495;3520.982395088008;3556.3776339140545;3561.8245978826926;3572.4105522589;615.1326629452451;625.0904933322006;626.9090981807612;634.2040738872778;634.9238091429058;499.16140883316376;515.1911498946693;566.5948846574719;600.474584738354;602.6745557399043;7749.651265693057;8133.75598732039;8145.79635509117;8254.958725206336;8260.091389947102;493.2305603258928;575.9115272135667;581.7236812514022;585.6954383720441;604.3865476541314;3763.190913953458;3841.0973421245985;3843.3466310726685;3924.9215015699524;3965.7422267552515;85879;87025;90366;90391;97205;62528.374716253165;75072.7604023654;79784.6010769954;81668;82406.93982650482;73456.69444874993;73561.6321918387;74630;74680;75004.62497687367;7637.618119094134;8166.877496837671;8287.378446616407;8346.081930987708;8376.743488476777;45158.54841451543;45536.77231613821;45777;46050.53949460597;46946.29580556226;60816.69591652013;61197.69401152966;61285;62009.68995154995;63169;10774.622888198875;10892.40091794959;10929.56281748753;11009.229353945148;11159.776804463703;12089.516419343128;12123.393830308272;12171.39143042862;12251.387430628254;12332.50669973191;11057.778844422906;11224.382658953837;11265.493052812806;11387.886121138683;11627.360495172847;11466.885331146581;11543.88456115428;11619.767604648028;11750.177487575787;11760.000399966098;10502.792178899525;10601.522931468257;11259.831102533632;11472.827907581226;11498.597549085742;9550.886853813927;9572.222332862197;9599.952000240233;9783.85324220123;9900.50497475109;9431.622735090794;9984.608510004584;10057.295989280972;10072.345297555636;10081.14310283634 16:11:02 INFO - PROCESS | 2220 | __end_tp_report 16:11:02 INFO - PROCESS | 2220 | __start_cc_report 16:11:02 INFO - PROCESS | 2220 | _x_x_mozilla_cycle_collect,413 16:11:02 INFO - PROCESS | 2220 | __end_cc_report 16:11:02 INFO - PROCESS | 2220 | __startTimestamp1461712262178__endTimestamp 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | ------- Summary: start ------- 16:11:02 INFO - PROCESS | 2220 | Number of tests: 6 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | [#0] dojo.html Cycles:165 Average:5711.47 Median:6021.94 stddev:803.43 (13.3%) stddev-sans-first:785.52 16:11:02 INFO - PROCESS | 2220 | Values: 3419.9 3424.7 3465.0 3468.6 3504.9 5838.3 5844.8 5876.3 5890.1 5905.5 5694.2 5818.6 5820.2 5919.4 5952.7 5192.3 5253.8 5269.2 5302.9 5339.3 311.9 5129.3 5222.6 5259.4 5262.8 3910.9 4232.9 5283.2 5298.5 5313.1 5289.7 5315.3 5317.0 5317.9 5352.8 5160.3 5174.4 5224.5 5262.2 5306.3 5578.6 5628.4 5651.2 5696.2 5719.3 6096.9 6208.4 6209.5 6211.0 6222.4 6165.2 6207.1 6217.9 6238.1 6257.9 5989.8 6017.1 6021.9 6038.3 6091.9 6163.2 6253.4 6328.2 6368.5 6374.1 5290.0 6083.3 6121.1 6130.2 6166.1 6250.9 6270.8 6288.3 6312.7 6321.4 5543.3 5968.4 6208.8 6213.6 6225.8 6206.4 6215.5 6219.8 6244.8 6259.7 4981.6 5001.2 5007.6 5010.9 5017.5 4342.5 4439.3 4488.9 4512.1 4552.1 4453.0 5495.0 5546.7 5934.6 6021.1 6224.3 6253.6 6264.1 6271.0 6293.7 5836.1 5917.9 6013.0 6029.7 6032.2 5444.7 5465.9 5471.9 5492.7 5496.2 6518.0 6549.2 6552.1 6556.1 6557.0 6123.3 6231.4 6285.7 6309.6 6314.7 5547.3 6460.8 6507.4 6516.7 6542.1 6089.4 6169.4 6179.7 6228.8 6229.5 4846.3 5716.5 6181.1 6233.8 6302.8 4801.4 6147.3 6219.1 6219.3 6228.6 4926.5 6112.7 6168.8 6179.8 6211.4 4708.3 5012.7 5988.1 6121.6 6139.5 6221.7 6223.4 6231.8 6249.3 6268.4 5951.8 6027.7 6065.0 6107.6 6160.3 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | [#1] ext.html Cycles:180 Average:58444.88 Median:37252.07 stddev:118522.89 (318.2%) stddev-sans-first:118775.48 16:11:02 INFO - PROCESS | 2220 | Values: 480.9 501.4 524.5 604.3 625.1 37353.9 38185.4 38190.8 38243.8 38302.0 38645.4 39327.6 39333.6 39341.8 39452.4 9397.1 9613.0 9625.5 9637.7 9683.1 9480.7 9632.7 9694.4 9698.3 9753.3 9656.8 9664.6 9689.5 9709.0 9720.5 8918.2 9082.0 9145.7 9681.3 9715.3 8915.5 9732.9 9737.6 9748.3 9752.9 32293.2 38583.2 38823.6 38962.8 38966.0 2812.0 3039.8 3081.1 3200.6 3410.2 26474.6 26488.3 26505.8 27035.3 27371.9 2592.5 2644.8 2679.0 2680.1 2718.4 349748.0 352056.0 354173.0 355004.0 355224.0 29242.7 35269.6 35342.5 35394.6 35425.0 36114.0 36252.3 51950.7 61515.4 62319.4 13084.3 13585.9 14269.8 15796.8 15912.4 12044.9 12418.9 12488.4 12498.9 12529.9 15542.1 18450.9 18703.4 18972.6 18998.0 7997.6 8048.5 8124.8 8134.5 8143.5 843.4 1051.7 1069.6 1137.5 1177.5 23344.8 28761.9 29044.7 29535.1 29600.0 1058.6 1077.8 1096.9 1101.2 1105.2 13211.1 14850.5 15071.8 15106.1 15163.5 491549.0 495701.0 496342.0 502722.0 502825.0 58527.0 58772.1 58886.0 59267.0 59319.4 1435.3 1454.6 1473.5 1478.2 1483.7 28566.1 28763.6 29141.0 29290.0 29291.9 52322.2 54893.2 55924.7 56756.4 56832.0 470961.3 471071.0 472994.0 477328.0 479608.0 41179.6 41316.0 41929.0 42498.4 42519.6 45434.8 45822.8 45884.0 46042.8 46594.5 30648.0 36871.4 37447.3 37740.6 38461.6 36718.6 37106.6 37237.4 37812.4 38091.6 36302.5 36885.4 36900.3 37204.8 37266.7 32050.4 33293.5 33318.8 33469.0 33479.5 24237.8 24544.6 24660.5 25120.2 25259.4 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | [#2] jquery.html Cycles:180 Average:32502.74 Median:24919.51 stddev:95233.08 (382.2%) stddev-sans-first:95469.72 16:11:02 INFO - PROCESS | 2220 | Values: 395.9 401.0 401.7 408.9 412.3 19624.5 19942.3 20398.7 20423.4 20951.2 19727.5 19907.4 21014.3 21266.5 21295.7 5284.4 5618.9 6158.0 6174.1 6274.1 5920.2 5959.8 5972.5 6007.8 6062.5 6014.5 6102.0 6110.1 6126.8 6129.5 5178.2 5558.0 6057.1 6071.8 6111.8 5259.1 5275.3 5284.0 5286.8 5294.6 17868.3 21508.9 24454.1 25384.9 25549.1 2182.0 2188.8 2203.6 2268.6 2390.0 14289.8 14317.4 14701.7 15076.4 15269.0 1770.6 1813.9 1814.5 1839.0 1849.6 96751.5 98523.0 98587.0 99278.0 99865.5 10958.8 11033.6 11144.9 11160.3 11351.2 11567.4 13625.7 13772.4 13792.4 13806.6 8490.2 8664.1 8710.9 8736.0 8748.8 6828.5 7010.7 7464.0 8667.0 8674.8 6669.5 6800.8 6805.6 6875.6 6879.1 1910.5 1922.5 1924.1 1924.1 1932.0 986.5 996.3 1056.1 1092.6 1112.3 16826.4 18318.4 20660.8 21095.8 21099.6 919.2 971.3 978.0 1069.5 1115.5 11138.7 11472.9 11526.9 11545.2 11570.4 561620.0 574781.0 576020.0 583343.0 592366.0 86541.0 89266.7 90798.1 95873.0 96526.0 1007.2 1013.1 1017.6 1024.3 1053.9 17165.4 18730.3 18850.2 19077.8 19136.5 47342.0 48086.8 48590.0 48933.5 49719.5 66567.7 67022.0 67516.7 67847.2 68637.7 6230.3 6969.7 7494.8 7752.8 7978.4 8773.0 12605.7 12629.0 12829.6 12957.2 10873.5 10955.9 11189.6 11396.8 11432.9 9455.4 10812.9 11041.4 11076.4 11244.2 11114.6 11322.6 11363.6 11385.8 11635.9 8068.3 8159.4 8280.8 8337.4 8397.7 10397.7 10591.5 10945.7 10977.6 11327.8 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | [#3] mootools.html Cycles:180 Average:5621.86 Median:5010.95 stddev:3114.17 (62.1%) stddev-sans-first:3108.19 16:11:02 INFO - PROCESS | 2220 | Values: 1593.1 1631.7 1905.5 1955.4 1991.3 7772.6 8147.8 8603.7 8955.8 9011.9 7454.1 8543.0 8648.1 8665.2 8691.1 2276.0 2297.8 2302.4 2315.7 2317.7 2382.0 2421.5 2433.5 2438.3 2468.9 2451.9 2457.0 2464.1 2473.2 2500.0 2406.3 2457.5 2457.5 2468.6 2469.8 2256.0 2273.3 2460.3 2460.3 2460.8 8803.3 8837.3 8866.8 8885.8 8902.3 5150.3 5340.5 5358.6 5374.2 5374.7 2822.5 3441.5 3443.8 3587.7 3606.6 995.2 1020.6 1024.3 1025.4 1029.9 9960.1 10703.5 11265.3 11284.0 11284.9 4694.7 4737.8 4819.9 4827.0 4832.6 9681.9 9687.5 9692.0 9695.4 9699.2 5219.3 5267.2 5305.2 5372.8 5376.2 4349.6 4799.7 4845.9 5006.7 5015.2 3968.0 4007.5 4043.4 4120.0 4179.3 1182.2 1348.7 1349.6 1351.9 1358.3 848.5 864.6 865.4 898.5 914.9 4794.3 5660.1 5738.3 5872.5 6146.4 911.6 920.6 943.3 944.3 958.7 3697.3 3711.7 3830.8 3885.8 3895.9 9586.2 10871.2 10959.2 11229.2 11247.9 10461.9 10698.8 10904.6 11021.9 11239.6 8231.8 8235.6 8369.3 8423.4 8434.4 4464.9 5548.2 5638.2 5673.1 5737.3 8173.9 9616.3 9654.6 9974.4 10010.9 8433.2 9781.9 9840.7 10139.6 10206.8 6712.0 7367.1 7481.9 7646.8 7711.1 7068.2 7629.3 8323.5 8413.5 8414.4 6401.5 6903.8 7404.9 7485.3 7644.5 7383.4 7451.0 7519.8 7522.2 7529.1 6736.0 6763.9 6817.5 6866.9 6897.1 4368.3 4692.3 4777.6 4780.2 4954.6 4675.2 4852.9 4877.1 4878.8 4905.7 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | [#4] prototype.html Cycles:180 Average:4826.89 Median:5315.68 stddev:1500.73 (28.2%) stddev-sans-first:1465.44 16:11:02 INFO - PROCESS | 2220 | Values: 269.3 297.9 313.2 329.6 331.3 5383.0 5503.0 5546.7 5612.0 5668.6 5014.4 5511.1 5610.9 5639.9 5795.4 3699.3 4032.0 4248.5 4293.9 4305.5 3568.9 4278.0 4305.4 4320.6 4338.7 3365.4 3439.0 4219.2 4236.6 4279.2 4044.3 4261.7 4272.8 4276.1 4310.2 3422.6 3705.8 3709.4 3711.1 3724.5 4430.8 5155.6 5336.9 5410.8 5440.3 4869.3 4871.2 4875.9 4894.6 4920.6 4894.9 5752.6 5853.1 5922.1 6012.6 4071.7 4074.7 4079.8 4084.6 4089.5 5007.9 5878.8 6231.6 6267.8 6272.0 3453.0 3646.8 3826.6 4130.8 4132.3 3278.3 3490.8 3840.0 3848.8 4216.4 4794.9 4808.0 4869.8 4875.6 4912.5 5428.4 5478.0 5672.2 5976.3 6007.7 1426.8 1481.9 1544.4 1553.4 1558.1 984.0 1006.5 1007.5 1035.8 1036.5 5279.9 5533.6 6013.8 6027.4 6083.7 6252.7 6576.6 6616.6 6642.4 6642.6 5356.5 5398.8 5696.7 5716.7 5727.7 3528.9 3539.2 3560.1 3569.5 3577.7 4964.7 6446.3 6523.9 7016.2 7094.8 5147.9 5589.9 6108.4 6403.4 6404.4 5844.9 5984.7 6242.3 6484.9 6493.3 5661.0 5827.9 6194.0 6225.7 6234.8 6148.8 6150.1 6166.2 6175.3 6188.4 5507.2 6090.6 6115.4 6236.3 6437.5 3742.8 4040.2 4061.7 4089.7 4102.7 5608.2 5822.4 6237.6 6275.6 6296.5 5650.8 5752.4 5784.0 6040.6 6043.7 5173.7 5294.5 6022.3 6036.4 6042.9 4667.0 4753.2 5011.8 5094.5 5352.9 5281.5 5436.8 5786.9 5903.8 5988.4 4710.3 4799.2 5551.1 5808.2 5909.5 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - PROCESS | 2220 | [#5] yui.html Cycles:180 Average:17837.04 Median:601.57 stddev:24790.73 (4121.0%) stddev-sans-first:24826.80 16:11:02 INFO - PROCESS | 2220 | Values: 680.0 705.7 712.7 729.7 806.4 11937.0 15116.2 15263.6 15268.8 15314.0 13872.6 13946.9 14287.6 14302.2 14332.7 726.8 910.9 921.8 929.3 930.2 903.2 918.5 939.5 940.0 942.7 950.9 972.9 977.2 978.5 979.1 860.3 945.6 951.1 953.4 971.1 658.4 708.1 712.5 717.2 719.3 15044.4 15214.2 15354.7 15358.8 15455.5 11738.7 12179.1 12637.6 12706.9 12783.1 9311.6 10036.0 10081.5 10319.8 10407.4 3460.1 3479.0 3523.5 3555.2 3621.6 54987.0 75069.6 78227.0 78289.2 78537.6 9548.3 9569.8 9571.2 9837.1 9847.3 21782.2 22286.7 22309.6 22370.8 22552.4 7383.8 7449.5 7469.7 7521.4 7760.1 3963.2 4022.4 4025.6 4025.6 4039.9 3471.9 3521.0 3556.4 3561.8 3572.4 615.1 625.1 626.9 634.2 634.9 499.2 515.2 566.6 600.5 602.7 7749.7 8133.8 8145.8 8255.0 8260.1 493.2 575.9 581.7 585.7 604.4 3763.2 3841.1 3843.3 3924.9 3965.7 85879.0 87025.0 90366.0 90391.0 97205.0 62528.4 75072.8 79784.6 81668.0 82406.9 73456.7 73561.6 74630.0 74680.0 75004.6 7637.6 8166.9 8287.4 8346.1 8376.7 45158.5 45536.8 45777.0 46050.5 46946.3 60816.7 61197.7 61285.0 62009.7 63169.0 10774.6 10892.4 10929.6 11009.2 11159.8 12089.5 12123.4 12171.4 12251.4 12332.5 11057.8 11224.4 11265.5 11387.9 11627.4 11466.9 11543.9 11619.8 11750.2 11760.0 10502.8 10601.5 11259.8 11472.8 11498.6 9550.9 9572.2 9600.0 9783.9 9900.5 9431.6 9984.6 10057.3 10072.3 10081.1 16:11:02 INFO - PROCESS | 2220 | -------- Summary: end -------- 16:11:02 INFO - PROCESS | 2220 | 16:11:02 INFO - TEST-INFO | 2220: exit 0 16:11:02 INFO - TEST-OK | dromaeo_css | took 1111154ms 16:11:02 INFO - TEST-START | kraken 16:11:02 INFO - operating with platform_type : mac_ 16:11:02 INFO - Initialising browser for kraken test... 16:11:02 INFO - TEST-INFO | started process 2224 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmp6zBoxO/profile http://localhost:57533/getInfo.html) 16:11:05 INFO - PROCESS | 2224 | __metrics Screen width/height:1600/1200 16:11:05 INFO - PROCESS | 2224 | colorDepth:24 16:11:05 INFO - PROCESS | 2224 | Browser inner width/height: 1024/689 16:11:05 INFO - PROCESS | 2224 | __metrics 16:11:05 ERROR - PROCESS | 2224 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 16:11:06 INFO - TEST-INFO | 2224: exit 0 16:11:06 INFO - Browser initialized. 16:11:06 INFO - Running cycle 1/1 for kraken test... 16:11:06 INFO - TEST-INFO | started process 2227 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmp6zBoxO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:18 INFO - PROCESS | 2227 | RSS: Main: 249630720 16:11:18 INFO - PROCESS | 2227 | 16:11:21 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57533/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:22 INFO - PROCESS | 2227 | RSS: Main: 261701632 16:11:22 INFO - PROCESS | 2227 | 16:11:25 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57533/tests/kraken/driver.html?testName=audio-dft) 16:11:26 INFO - PROCESS | 2227 | RSS: Main: 271233024 16:11:26 INFO - PROCESS | 2227 | 16:11:29 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57533/tests/kraken/driver.html?testName=audio-fft) 16:11:30 INFO - PROCESS | 2227 | RSS: Main: 274223104 16:11:30 INFO - PROCESS | 2227 | 16:11:32 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57533/tests/kraken/driver.html?testName=audio-oscillator) 16:11:32 INFO - PROCESS | 2227 | RSS: Main: 276287488 16:11:32 INFO - PROCESS | 2227 | 16:11:34 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57533/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:11:35 INFO - PROCESS | 2227 | RSS: Main: 276602880 16:11:35 INFO - PROCESS | 2227 | 16:11:38 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57533/tests/kraken/driver.html?testName=imaging-darkroom) 16:11:38 INFO - PROCESS | 2227 | RSS: Main: 277078016 16:11:38 INFO - PROCESS | 2227 | 16:11:41 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57533/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:42 INFO - PROCESS | 2227 | RSS: Main: 277278720 16:11:42 INFO - PROCESS | 2227 | 16:11:45 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57533/tests/kraken/driver.html?testName=json-parse-financial) 16:11:46 INFO - PROCESS | 2227 | RSS: Main: 277741568 16:11:46 INFO - PROCESS | 2227 | 16:11:47 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57533/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:48 INFO - PROCESS | 2227 | RSS: Main: 276500480 16:11:48 INFO - PROCESS | 2227 | 16:11:50 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:51 INFO - PROCESS | 2227 | RSS: Main: 276484096 16:11:51 INFO - PROCESS | 2227 | 16:11:53 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:53 INFO - PROCESS | 2227 | RSS: Main: 276934656 16:11:53 INFO - PROCESS | 2227 | 16:11:56 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:57 INFO - PROCESS | 2227 | RSS: Main: 276996096 16:11:57 INFO - PROCESS | 2227 | 16:12:00 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:12:01 INFO - PROCESS | 2227 | RSS: Main: 277524480 16:12:01 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:57533/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57533/tests/kraken/driver.html?testName=ai-astar) 16:12:03 INFO - PROCESS | 2227 | RSS: Main: 278589440 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | __start_tp_report 16:12:03 INFO - PROCESS | 2227 | _x_x_mozilla_page_load 16:12:03 INFO - PROCESS | 2227 | _x_x_mozilla_page_load_details 16:12:03 INFO - PROCESS | 2227 | |i|pagename|runs| 16:12:03 INFO - PROCESS | 2227 | |0;ai-astar;148;161;153;176;145;129;141;173;159;117 16:12:03 INFO - PROCESS | 2227 | |1;audio-beat-detection;176;108;170;207;157;144;145;122;167;123 16:12:03 INFO - PROCESS | 2227 | |2;audio-dft;210;246;180;269;278;203;198;198;199;195 16:12:03 INFO - PROCESS | 2227 | |3;audio-fft;69;79;74;135;55;58;82;57;91;81 16:12:03 INFO - PROCESS | 2227 | |4;audio-oscillator;80;87;137;77;80;82;117;83;79;121 16:12:03 INFO - PROCESS | 2227 | |5;imaging-gaussian-blur;87;87;88;85;89;84;93;87;89;85 16:12:03 INFO - PROCESS | 2227 | |6;imaging-darkroom;104;94;93;94;101;152;95;94;94;94 16:12:03 INFO - PROCESS | 2227 | |7;imaging-desaturate;80;147;145;89;89;90;91;90;132;153 16:12:03 INFO - PROCESS | 2227 | |8;json-parse-financial;59;61;59;60;66;76;72;80;91;61 16:12:03 INFO - PROCESS | 2227 | |9;json-stringify-tinderbox;64;66;69;74;72;78;76;59;87;100 16:12:03 INFO - PROCESS | 2227 | |10;stanford-crypto-aes;104;93;83;83;83;81;86;83;84;83 16:12:03 INFO - PROCESS | 2227 | |11;stanford-crypto-ccm;154;147;140;140;139;132;153;167;211;152 16:12:03 INFO - PROCESS | 2227 | |12;stanford-crypto-pbkdf2;204;210;216;202;206;201;196;205;249;210 16:12:03 INFO - PROCESS | 2227 | |13;stanford-crypto-sha256-iterative;72;70;69;67;73;74;75;70;70;68 16:12:03 INFO - PROCESS | 2227 | __end_tp_report 16:12:03 INFO - PROCESS | 2227 | __start_cc_report 16:12:03 INFO - PROCESS | 2227 | _x_x_mozilla_cycle_collect,1090 16:12:03 INFO - PROCESS | 2227 | __end_cc_report 16:12:03 INFO - PROCESS | 2227 | __startTimestamp1461712323330__endTimestamp 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | ------- Summary: start ------- 16:12:03 INFO - PROCESS | 2227 | Number of tests: 14 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#0] ai-astar Cycles:10 Average:150.20 Median:156.00 stddev:18.43 (11.8%) stddev-sans-first:19.53 16:12:03 INFO - PROCESS | 2227 | Values: 148.0 161.0 153.0 176.0 145.0 129.0 141.0 173.0 159.0 117.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:162.00 stddev:29.75 (18.4%) stddev-sans-first:30.25 16:12:03 INFO - PROCESS | 2227 | Values: 176.0 108.0 170.0 207.0 157.0 144.0 145.0 122.0 167.0 123.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#2] audio-dft Cycles:10 Average:217.60 Median:206.50 stddev:34.00 (16.5%) stddev-sans-first:35.96 16:12:03 INFO - PROCESS | 2227 | Values: 210.0 246.0 180.0 269.0 278.0 203.0 198.0 198.0 199.0 195.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#3] audio-fft Cycles:10 Average:78.10 Median:76.50 stddev:23.36 (30.5%) stddev-sans-first:24.54 16:12:03 INFO - PROCESS | 2227 | Values: 69.0 79.0 74.0 135.0 55.0 58.0 82.0 57.0 91.0 81.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:80.00 stddev:21.92 (27.4%) stddev-sans-first:22.64 16:12:03 INFO - PROCESS | 2227 | Values: 80.0 87.0 137.0 77.0 80.0 82.0 117.0 83.0 79.0 121.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:87.50 stddev:2.59 (3.0%) stddev-sans-first:2.74 16:12:03 INFO - PROCESS | 2227 | Values: 87.0 87.0 88.0 85.0 89.0 84.0 93.0 87.0 89.0 85.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#6] imaging-darkroom Cycles:10 Average:101.50 Median:94.00 stddev:18.11 (19.3%) stddev-sans-first:19.19 16:12:03 INFO - PROCESS | 2227 | Values: 104.0 94.0 93.0 94.0 101.0 152.0 95.0 94.0 94.0 94.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#7] imaging-desaturate Cycles:10 Average:110.60 Median:89.00 stddev:29.56 (33.2%) stddev-sans-first:29.21 16:12:03 INFO - PROCESS | 2227 | Values: 80.0 147.0 145.0 89.0 89.0 90.0 91.0 90.0 132.0 153.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:69.00 stddev:10.95 (15.9%) stddev-sans-first:11.06 16:12:03 INFO - PROCESS | 2227 | Values: 59.0 61.0 59.0 60.0 66.0 76.0 72.0 80.0 91.0 61.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#9] json-stringify-tinderbox Cycles:10 Average:74.50 Median:73.00 stddev:11.93 (16.3%) stddev-sans-first:12.03 16:12:03 INFO - PROCESS | 2227 | Values: 64.0 66.0 69.0 74.0 72.0 78.0 76.0 59.0 87.0 100.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:83.00 stddev:7.04 (8.5%) stddev-sans-first:3.50 16:12:03 INFO - PROCESS | 2227 | Values: 104.0 93.0 83.0 83.0 83.0 81.0 86.0 83.0 84.0 83.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:152.50 stddev:22.54 (14.8%) stddev-sans-first:23.90 16:12:03 INFO - PROCESS | 2227 | Values: 154.0 147.0 140.0 140.0 139.0 132.0 153.0 167.0 211.0 152.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.90 Median:208.00 stddev:14.81 (7.1%) stddev-sans-first:15.56 16:12:03 INFO - PROCESS | 2227 | Values: 204.0 210.0 216.0 202.0 206.0 201.0 196.0 205.0 249.0 210.0 16:12:03 INFO - PROCESS | 2227 | 16:12:03 INFO - PROCESS | 2227 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:71.00 stddev:2.62 (3.7%) stddev-sans-first:2.74 16:12:03 INFO - PROCESS | 2227 | Values: 72.0 70.0 69.0 67.0 73.0 74.0 75.0 70.0 70.0 68.0 16:12:03 INFO - PROCESS | 2227 | -------- Summary: end -------- 16:12:03 INFO - PROCESS | 2227 | 16:12:04 INFO - TEST-INFO | 2227: exit 0 16:12:04 INFO - TEST-OK | kraken | took 61377ms 16:12:04 INFO - SUITE-END | took 1172s 16:12:04 INFO - Completed test suite (00:19:32) 16:12:04 INFO - javascript benchmark 16:12:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3419.914502137446, 3424.726021918246, 3465.0, 3468.583769947605, 3504.9299014019703, 5838.299404071512, 5844.824655260344, 5876.324222714389, 5890.087036509337, 5905.527557795377, 5694.2312787773635, 5818.5926985110955, 5820.15607736879, 5919.408059194078, 5952.672603006833, 5192.273081768546, 5253.789848406059, 5269.1832765921345, 5302.867428314304, 5339.279197308372, 311.8994397189886, 5129.307543481638, 5222.608304377168, 5259.447757985414, 5262.842114736545, 3910.9244957636733, 4232.899446143994, 5283.154695248781, 5298.549623282006, 5313.149896016619, 5289.735513224324, 5315.282436871012, 5316.989771943318, 5317.9468205317835, 5352.839414817562, 5160.277561141444, 5174.43081261061, 5224.451432599561, 5262.158054711266, 5306.336707911511, 5578.553715702734, 5628.380878103405, 5651.208830763697, 5696.1740547620775, 5719.256496655408, 6096.889554637394, 6208.441240288395, 6209.5032397408095, 6210.96894515529, 6222.43998040174, 6165.229346331708, 6207.068939659042, 6217.942949698562, 6238.064290356438, 6257.906131408033, 5989.760409583611, 6017.0974353846395, 6021.939780602138, 6038.33578306386, 6091.933911565458, 6163.1679723237685, 6253.374662533801, 6328.208973878265, 6368.490520758328, 6374.139491168726, 5290.0, 6083.300420451616, 6121.051237058174, 6130.153553065376, 6166.105914642351, 6250.9062364064575, 6270.7805226816845, 6288.308286088527, 6312.684365781693, 6321.431071203614, 5543.334799824047, 5968.403159684084, 6208.844778880474, 6213.565050446426, 6225.844353891189, 6206.441420272197, 6215.533834962396, 6219.813405597839, 6244.7814326499265, 6259.6870156492, 4981.551660350513, 5001.224810154432, 5007.574356179693, 5010.89978200434, 5017.523335283142, 4342.544032876565, 4439.311906654474, 4488.910221795546, 4512.052468981485, 4552.089582083597, 4453.0, 5494.972525137348, 5546.722663866871, 5934.554908381889, 6021.126936594257, 6224.253089629274, 6253.624782513064, 6264.060390941304, 6270.968645156745, 6293.653849038255, 5836.124581312837, 5917.881642367214, 6013.0, 6029.698515074228, 6032.1856549365275, 5444.673319600836, 5465.9180112298345, 5471.945280547144, 5492.725363731797, 5496.203050557647, 6517.9674101630135, 6549.2468366137555, 6552.082708420825, 6556.147700798866, 6557.0, 6123.343628574205, 6231.40801623845, 6285.748570057147, 6309.558330916975, 6314.715837787402, 5547.2511210986, 6460.773872914426, 6507.414332710079, 6516.674166291761, 6542.084108224947, 6089.360617135314, 6169.444749972524, 6179.721912513858, 6228.813135605939, 6229.5327850410495, 4846.297286893451, 5716.456936591017, 6181.134641150153, 6233.812985610439, 6302.779402721067, 4801.375821143229, 6147.262328520606, 6219.0982307566055, 6219.253689557282, 6228.5951413158, 4926.531979461945, 6112.72492737819, 6168.784092556919, 6179.783707570394, 6211.440970312694, 4708.340832283415, 5012.724300163381, 5988.101784732325, 6121.602095863933, 6139.508839292756, 6221.720022598904, 6223.408776166257, 6231.750729970931, 6249.25008998923, 6268.404501572343, 5951.7619295227705, 6027.668478233739, 6065.0, 6107.633541987495, 6160.322364539987], "value": 5664.972064061784, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.93507376504164, 501.4433978284105, 524.4729047307455, 604.274870155813, 625.146674788913, 37353.87938361847, 38185.42721859174, 38190.809045954804, 38243.80878095609, 38302.04244893872, 38645.42031869524, 39327.60672393275, 39333.60666393335, 39341.81974540769, 39452.40821387681, 9397.107274808883, 9613.0, 9625.51872406377, 9637.662681806138, 9683.080107389822, 9480.715578532656, 9632.711018669415, 9694.369865958726, 9698.321117521777, 9753.268504862128, 9656.75858103546, 9664.613415463373, 9689.467079310634, 9709.029097090304, 9720.513974301291, 8918.152775486318, 9082.000979892273, 9145.72562823113, 9681.322307438482, 9715.319927605071, 8915.484367657515, 9732.854007189897, 9737.56180971858, 9748.31761776676, 9752.90247097527, 32293.192670183198, 38583.22833543314, 38823.61176388228, 38962.80518597389, 38966.0, 2811.9718802812117, 3039.7720170987263, 3081.1218802641138, 3200.647928727838, 3410.198603328216, 26474.6028809568, 26488.337791555172, 26505.808491283642, 27035.324116897038, 27371.86314068417, 2592.494463579603, 2644.7884169266413, 2678.9866050669816, 2680.142354446578, 2718.415540658748, 349748.0, 352056.0, 354173.0, 355004.0, 355224.0, 29242.707572924424, 35269.64730352664, 35342.46986295208, 35394.64605353965, 35425.0, 36114.0, 36252.27495450129, 51950.70123246949, 61515.38484615186, 62319.37680623226, 13084.28036458004, 13585.932070339584, 14269.78706809912, 15796.842031579767, 15912.363505459653, 12044.87955120455, 12418.937905310597, 12488.438020289108, 12498.875011249953, 12529.93735031319, 15542.067475951479, 18450.907745461187, 18703.438896833115, 18972.620547589246, 18997.955112468946, 7997.560134192675, 8048.476849004796, 8124.796880077927, 8134.518971713685, 8143.51138931666, 843.3548335523329, 1051.7370657335514, 1069.5989004123453, 1137.5279259107378, 1177.4877928101303, 23344.7665523346, 28761.856190719333, 29044.709552904624, 29535.113946581638, 29600.0, 1058.5554067291755, 1077.789830982951, 1096.9012788849043, 1101.168617693652, 1105.160078340467, 13211.075224734177, 14850.480233191787, 15071.849281507044, 15106.091888261573, 15163.545093647208, 491549.0, 495701.0, 496342.0, 502722.0, 502825.0, 58527.0, 58772.11841822376, 58886.0, 59267.0, 59319.40680593139, 1435.3135066297268, 1454.5999850041323, 1473.4774066797754, 1478.1869971515623, 1483.6958423523151, 28566.143015709564, 28763.568546472237, 29141.0, 29290.0, 29291.85354073216, 52322.21516677329, 54893.17660235179, 55924.720376397854, 56756.43243567512, 56832.0, 470961.29038709175, 471071.0, 472994.0, 477328.0, 479608.0, 41179.58820411757, 41315.96710082272, 41929.0, 42498.36252456154, 42519.5748042528, 45434.77282613566, 45822.77088614536, 45884.0, 46042.76978615086, 46594.534054659016, 30648.0, 36871.44692829663, 37447.25105497929, 37740.62259377371, 38461.615383846925, 36718.632813671524, 37106.628933710315, 37237.44143837791, 37812.43281350837, 38091.61908380881, 36302.46219222597, 36885.44671829978, 36900.261994759414, 37204.813975929945, 37266.69566565157, 32050.35899282048, 33293.50059749057, 33318.83340583282, 33468.99593012214, 33479.49780753242, 24237.75762242355, 24544.631830522914, 24660.50678986446, 25120.246392608253, 25259.368515787253], "value": 18986.060763859343, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.86144849302747, 400.9979950100249, 401.6525705264945, 408.8677546594509, 412.29291764623673, 19624.509387265327, 19942.302019429324, 20398.69401958972, 20423.387298381036, 20951.161953521878, 19727.5068123297, 19907.402777916686, 21014.264500742476, 21266.46833829155, 21295.68056479154, 5284.39229488608, 5618.887622247553, 6157.969210153943, 6174.135621013062, 6274.121622972787, 5920.171176035358, 5959.791407300745, 5972.522198224132, 6007.759689612432, 6062.514998800086, 6014.458698717114, 6101.969490152543, 6110.08348747687, 6126.7549298028025, 6129.509639228851, 5178.223266510035, 5557.972210138943, 6057.061155520902, 6071.787487437919, 6111.816645500619, 5259.13300778222, 5275.340582427197, 5283.973580132075, 5286.788528458857, 5294.602904782157, 17868.28526858937, 21508.924553772405, 24454.14410495624, 25384.87307563469, 25549.105781297752, 2181.974471998158, 2188.753817593863, 2203.623159490837, 2268.6029944759603, 2389.97610023901, 14289.785653215211, 14317.42730290787, 14701.705965880623, 15076.396944122222, 15269.0, 1770.6281680847037, 1813.9002354870477, 1814.5010122216497, 1839.0253165822135, 1849.6393203325356, 96751.51624241903, 98523.0, 98587.0, 99278.0072199276, 99865.5020174698, 10958.780824383468, 11033.558657653604, 11144.888551114385, 11160.330380177214, 11351.205415620827, 11567.363794991188, 13625.727485450236, 13772.380242889094, 13792.448302068005, 13806.585802425943, 8490.193431623986, 8664.090270521505, 8710.869336959951, 8736.0, 8748.825023499621, 6828.453723702191, 7010.719571217196, 7464.029676142067, 8666.956665216634, 8674.826503469896, 6669.466442684574, 6800.829979250559, 6805.557638753564, 6875.621840798804, 6879.140107486564, 1910.4937191644226, 1922.5385907382129, 1924.0668275886185, 1924.0956750327323, 1931.9903400483188, 986.4574484033752, 996.292632231124, 1056.1392465140887, 1092.5684354680016, 1112.2547892911768, 16826.411075612294, 18318.358857439925, 20660.79339206619, 21095.839728814757, 21099.578008439745, 919.1865199298669, 971.3346357744967, 977.9853302200472, 1069.4973362519604, 1115.5259014918627, 11138.66584002465, 11472.885271147348, 11526.88473115275, 11545.191836571528, 11570.363630000262, 561620.0, 574781.0, 576020.0, 583343.0, 592366.0, 86541.0, 89266.66100008505, 90798.09201908029, 95873.0, 96526.0, 1007.1942446043137, 1013.1135256650431, 1017.6438246613626, 1024.3085917005992, 1053.8840727519814, 17165.399211027554, 18730.34443794461, 18850.245990160787, 19077.809221907602, 19136.521586960436, 47342.0, 48086.759566201945, 48590.0, 48933.51066489289, 49719.50280497294, 66567.66716166388, 67022.0, 67516.6624166876, 67847.18822741932, 68637.65681171563, 6230.320961986389, 6969.721211151497, 7494.7633640450895, 7752.844943100994, 7978.441509094308, 8773.0, 12605.74788504243, 12629.0, 12829.55096571653, 12957.157784743962, 10873.510692018719, 10955.945220274167, 11189.552417903193, 11396.829047564459, 11432.885671143182, 9455.362716943438, 10812.891871081189, 11041.44792760375, 11076.446177691245, 11244.212905096565, 11114.610988615359, 11322.60370887048, 11363.602273920704, 11385.829212561652, 11635.88364116348, 8068.273855353046, 8159.388045896463, 8280.792980175303, 8337.374696897636, 8397.70608028716, 10397.688069357931, 10591.5233814477, 10945.726356841144, 10977.615783447542, 11327.830082548933], "value": 9512.369503044967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1593.1158207195006, 1631.657351956089, 1905.5331443796272, 1955.3840540229817, 1991.3372334101034, 7772.572508512029, 8147.837043259131, 8603.65585376585, 8955.820883582324, 9011.909880901198, 7454.1055073391135, 8542.957285213566, 8648.091950345217, 8665.220130188281, 8691.130886911305, 2275.977240227593, 2297.781710737486, 2302.3668491164894, 2315.69895913531, 2317.7450480447137, 2382.0, 2421.4914867877765, 2433.488967316857, 2438.2928950604305, 2468.9135880244194, 2451.9264422067367, 2457.041753716052, 2464.112919349033, 2473.2332976777166, 2500.0499810072147, 2406.2901444073864, 2457.4839283750343, 2457.5453541094953, 2468.642046903207, 2469.8147638927153, 2255.9735320429168, 2273.306641474366, 2460.2865169100937, 2460.2988148377804, 2460.803135749136, 8803.251723603502, 8837.293016558724, 8866.822663546758, 8885.822283554293, 8902.33232507558, 5150.3047088642925, 5340.492653197979, 5358.624896257264, 5374.16700411433, 5374.731263436852, 2822.5342818434933, 3441.4665726812386, 3443.8278086095597, 3587.712982961357, 3606.5672119345845, 995.1690338567305, 1020.6070662794878, 1024.2676486312323, 1025.3642741500319, 1029.9330543514648, 9960.053794889474, 10703.518341674644, 11265.26775759573, 11284.0, 11284.943575282154, 4694.718316900997, 4737.786799594027, 4819.927701084486, 4827.010462855106, 4832.63755218353, 9681.854772178423, 9687.467189304656, 9692.030796920393, 9695.369800962915, 9699.224062075016, 5219.269302297681, 5267.236250743621, 5305.230741542455, 5372.758225879845, 5376.247325374451, 4349.565043495689, 4799.688020278671, 4845.927311090337, 5006.674566153189, 5015.22264049073, 3967.980160099181, 4007.519097708294, 4043.3732771420478, 4120.011197312623, 4179.26862799009, 1182.2433642468827, 1348.7032852772375, 1349.6086135020735, 1351.8850897673808, 1358.2936872826076, 848.4781859156574, 864.6454953469048, 865.4158443050916, 898.4788822483075, 914.9222316103072, 4794.256890182028, 5660.122680984454, 5738.254026976467, 5872.530197584183, 6146.361929116543, 911.6262332443799, 920.5857364186143, 943.3019565521465, 944.2804722399445, 958.6644674363916, 3697.2975134724315, 3711.721620878445, 3830.751001184914, 3885.805709714503, 3895.8831235062994, 9586.185174260267, 10871.184661150446, 10959.232853700323, 11229.213955023233, 11247.87521247885, 10461.895381046092, 10698.839517407245, 10904.61833835812, 11021.944890275498, 11239.606613768643, 8231.753047408587, 8235.629396677044, 8369.288610468057, 8423.410361274651, 8434.409591328671, 4464.888377790581, 5548.223248745098, 5638.21065050885, 5673.120666296731, 5737.282839645044, 8173.918260817316, 9616.326857119935, 9654.613815447303, 9974.351667141613, 10010.899891000996, 8433.241008309282, 9781.85327220078, 9840.704778856645, 10139.594416223268, 10206.846897296398, 6712.0, 7367.115946086504, 7481.850362992818, 7646.8088297793, 7711.1132219795445, 7068.151821781419, 7629.2752188542, 8323.500589964378, 8413.45312554682, 8414.4109912308, 6401.519886008661, 6903.792886213421, 7404.888926666212, 7485.288897554724, 7644.541327520366, 7383.409327253914, 7450.96274518624, 7519.774406767806, 7522.247775222434, 7529.13414957265, 6736.02327662496, 6763.917773156271, 6817.488688348294, 6866.896996544956, 6897.103376561015, 4368.301071828492, 4692.343071969859, 4777.570018698334, 4780.211265141352, 4954.603631709527, 4675.158471475167, 4852.878678033077, 4877.097736918681, 4878.804847806048, 4905.705657660552], "value": 4551.3292169623655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.34414700205, 297.9215240828202, 313.1841552755072, 329.59459864366823, 331.3092202757251, 5383.031054410205, 5502.972485137579, 5546.667199968005, 5612.0, 5668.6395265136325, 5014.3982722073315, 5511.071125106221, 5610.887782244353, 5639.900219457208, 5795.449432303935, 3699.278640665065, 4031.9596804032026, 4248.511421186557, 4293.892652683677, 4305.461817272841, 3568.946465803015, 4277.97328641127, 4305.375720520523, 4320.611144996949, 4338.696291259613, 3365.444701624229, 3438.965610343902, 4219.2405367033925, 4236.639885609727, 4279.186954478655, 4044.3124668806227, 4261.6803739719335, 4272.764997925113, 4276.144771045803, 4310.181065597527, 3422.5528746945806, 3705.7776533407814, 3709.350863598859, 3711.0907827582146, 3724.4599533067553, 4430.756308403036, 5155.639105262633, 5336.866578335534, 5410.783568657249, 5440.347158340985, 4869.269609558594, 4871.171900776841, 4875.8781030474165, 4894.632902532288, 4920.606351491872, 4894.853154405374, 5752.648127689989, 5853.122031695238, 5922.09817189574, 6012.6392416455155, 4071.6742660587083, 4074.6740260779065, 4079.755214687128, 4084.550699423061, 4089.5297040840387, 5007.899842003176, 5878.764849406061, 6231.626102433823, 6267.811965640993, 6271.968640156816, 3452.9813704956596, 3646.835892384849, 3826.559945606291, 4130.834766609302, 4132.318167502389, 3278.344331133783, 3490.8254587270535, 3840.0, 3848.826802793881, 4216.373918430148, 4794.9211427428545, 4808.0, 4869.780859861315, 4875.634327425386, 4912.484189160158, 5428.430014848463, 5477.972610137003, 5672.205891175238, 5976.324560395413, 6007.729652165575, 1426.8216472940883, 1481.8518148185094, 1544.4246868194602, 1553.3553575266365, 1558.0885182168558, 983.9901600984042, 1006.5420233793607, 1007.4912169354576, 1035.7669524357111, 1036.4817591204398, 5279.894402111936, 5533.612647114744, 6013.819585412444, 6027.397260273938, 6083.665398403042, 6252.749450109905, 6576.638284894281, 6616.610511792577, 6642.4021838034805, 6642.601443913284, 5356.491133342326, 5398.811041613525, 5696.6582005079, 5716.685582293014, 5727.742251598688, 3528.8764893228613, 3539.203679172217, 3560.1099725068734, 3569.5002699622073, 3577.678008979204, 4964.67729597575, 6446.323136070645, 6523.934760652428, 7016.158061032709, 7094.822629434306, 5147.948520514822, 5589.916151257735, 6108.419700128481, 6403.391677790602, 6404.3595640436515, 5844.8538786529825, 5984.730687119177, 6242.344553821783, 6484.870302593922, 6493.31820158886, 5660.971695141498, 5827.863566604471, 6194.0, 6225.688715564113, 6234.75060997555, 6148.846278843064, 6150.10823430609, 6166.229221347332, 6175.289841668086, 6188.381161883775, 5507.173923911445, 6090.634561926298, 6115.388461153849, 6236.282827474717, 6437.485001199986, 3742.831572579295, 4040.1717647881524, 4061.6953728469894, 4089.693273004596, 4102.687140115135, 5608.186812912187, 5822.417758224144, 6237.594556353823, 6275.592086514362, 6296.512877413487, 5650.84157747669, 5752.367239603724, 5784.033428634117, 6040.637561746309, 6043.728032238472, 5173.663711858717, 5294.49702278283, 6022.307434644897, 6036.366181550874, 6042.939570604238, 4666.976665116788, 4753.191957367187, 5011.774470148779, 5094.490550944876, 5352.919706204332, 5281.498257665516, 5436.782528698808, 5786.884262314814, 5903.760210355702, 5988.443004818866, 4710.340552322609, 4799.232122860465, 5551.140367976612, 5808.157817116579, 5909.497692695997], "value": 4356.0788198141145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.9830004249897, 705.6894966214865, 712.7362875735978, 729.7154109897141, 806.4354951533926, 11937.0, 15116.244187790622, 15263.618409539768, 15268.84731152688, 15314.0, 13872.583822485316, 13946.860531394683, 14287.571372858805, 14302.213378264218, 14332.713345733078, 726.7529040126356, 910.8841668955542, 921.787988762585, 929.251952178496, 930.241852889896, 903.1600611431367, 918.4718786697642, 939.506758951549, 939.9906000940006, 942.7124726958275, 950.8906475755273, 972.9173020293283, 977.218225419665, 978.5400861595039, 979.1236843025488, 860.3117505995241, 945.5886689290181, 951.1487218939045, 953.4184147669915, 971.0532231074716, 658.4436151452063, 708.0688894104288, 712.479889680531, 717.160921721587, 719.2950908110081, 15044.398224071025, 15214.239288035555, 15354.692906141927, 15358.769618455732, 15455.459058932996, 11738.65005524367, 12179.147459677826, 12637.557685480964, 12706.872931270662, 12783.105182637126, 9311.627534898664, 10036.0, 10081.546330415149, 10319.84520232197, 10407.427591482465, 3460.1349662584353, 3479.0, 3523.4714792781033, 3555.231454658045, 3621.6378362163837, 54987.0, 75069.62465187693, 78227.0, 78289.21710782876, 78537.60731196307, 9548.331616786758, 9569.760755981017, 9571.18644915176, 9837.114659680663, 9847.261455390875, 21782.237621683165, 22286.665700014513, 22309.553808924054, 22370.77629223687, 22552.436189095402, 7383.778486645409, 7449.478536502393, 7469.663865126083, 7521.360684341783, 7760.068791745027, 3963.1677347757295, 4022.3966405039478, 4025.5571887092397, 4025.6376926076796, 4039.899002524902, 3471.8611255549495, 3520.982395088008, 3556.3776339140545, 3561.8245978826926, 3572.4105522589, 615.1326629452451, 625.0904933322006, 626.9090981807612, 634.2040738872778, 634.9238091429058, 499.16140883316376, 515.1911498946693, 566.5948846574719, 600.474584738354, 602.6745557399043, 7749.651265693057, 8133.75598732039, 8145.79635509117, 8254.958725206336, 8260.091389947102, 493.2305603258928, 575.9115272135667, 581.7236812514022, 585.6954383720441, 604.3865476541314, 3763.190913953458, 3841.0973421245985, 3843.3466310726685, 3924.9215015699524, 3965.7422267552515, 85879.0, 87025.0, 90366.0, 90391.0, 97205.0, 62528.374716253165, 75072.7604023654, 79784.6010769954, 81668.0, 82406.93982650482, 73456.69444874993, 73561.6321918387, 74630.0, 74680.0, 75004.62497687367, 7637.618119094134, 8166.877496837671, 8287.378446616407, 8346.081930987708, 8376.743488476777, 45158.54841451543, 45536.77231613821, 45777.0, 46050.53949460597, 46946.29580556226, 60816.69591652013, 61197.69401152966, 61285.0, 62009.68995154995, 63169.0, 10774.622888198875, 10892.40091794959, 10929.56281748753, 11009.229353945148, 11159.776804463703, 12089.516419343128, 12123.393830308272, 12171.39143042862, 12251.387430628254, 12332.50669973191, 11057.778844422906, 11224.382658953837, 11265.493052812806, 11387.886121138683, 11627.360495172847, 11466.885331146581, 11543.88456115428, 11619.767604648028, 11750.177487575787, 11760.000399966098, 10502.792178899525, 10601.522931468257, 11259.831102533632, 11472.827907581226, 11498.597549085742, 9550.886853813927, 9572.222332862197, 9599.952000240233, 9783.85324220123, 9900.50497475109, 9431.622735090794, 9984.608510004584, 10057.295989280972, 10072.345297555636, 10081.14310283634], "value": 6852.249465743213, "unit": "score"}], "name": "dromaeo_css", "value": 7197.3345259823545, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 161.0, 153.0, 176.0, 145.0, 129.0, 141.0, 173.0, 159.0, 117.0], "value": 150.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 108.0, 170.0, 207.0, 157.0, 144.0, 145.0, 122.0, 167.0, 123.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 246.0, 180.0, 269.0, 278.0, 203.0, 198.0, 198.0, 199.0, 195.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 74.0, 135.0, 55.0, 58.0, 82.0, 57.0, 91.0, 81.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 137.0, 77.0, 80.0, 82.0, 117.0, 83.0, 79.0, 121.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 85.0, 89.0, 84.0, 93.0, 87.0, 89.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 94.0, 93.0, 94.0, 101.0, 152.0, 95.0, 94.0, 94.0, 94.0], "value": 101.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 147.0, 145.0, 89.0, 89.0, 90.0, 91.0, 90.0, 132.0, 153.0], "value": 110.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 60.0, 66.0, 76.0, 72.0, 80.0, 91.0, 61.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 69.0, 74.0, 72.0, 78.0, 76.0, 59.0, 87.0, 100.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 93.0, 83.0, 83.0, 83.0, 81.0, 86.0, 83.0, 84.0, 83.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 147.0, 140.0, 140.0, 139.0, 132.0, 153.0, 167.0, 211.0, 152.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 210.0, 216.0, 202.0, 206.0, 201.0, 196.0, 205.0, 249.0, 210.0], "value": 209.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 69.0, 67.0, 73.0, 74.0, 75.0, 70.0, 70.0, 68.0], "value": 70.8, "unit": "score"}], "name": "kraken", "value": 1655.1, "alertThreshold": 2.0}]} 16:12:04 INFO - Return code: 0 16:12:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:12:04 ERROR - # TBPL SUCCESS # 16:12:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:12:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:12:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:12:04 INFO - 2016-04-26 16:12:04.646 screenresolution[2232:22852] starting screenresolution argv=screenresolution get 16:12:04 INFO - 2016-04-26 16:12:04.648 screenresolution[2232:22852] Display 0: 1600x1200x32@60 16:12:04 INFO - 2016-04-26 16:12:04.654 screenresolution[2233:22855] starting screenresolution argv=screenresolution list 16:12:04 INFO - Available Modes on Display 0 16:12:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:12:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:12:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:12:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:12:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:12:04 INFO - Intel Iris: 16:12:04 INFO - Chipset Model: Intel Iris 16:12:04 INFO - Type: GPU 16:12:04 INFO - Bus: Built-In 16:12:04 INFO - VRAM (Dynamic, Max): 1536 MB 16:12:04 INFO - Vendor: Intel (0x8086) 16:12:04 INFO - Device ID: 0x0a2e 16:12:04 INFO - Revision ID: 0x0009 16:12:04 INFO - Displays: 16:12:04 INFO - Display: 16:12:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:12:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:12:04 INFO - Display Serial Number: 1600x1200 60 16:12:04 INFO - Main Display: Yes 16:12:04 INFO - Mirror: Off 16:12:04 INFO - Online: Yes 16:12:04 INFO - Rotation: Supported 16:12:04 INFO - Return code: 0 16:12:04 INFO - Running post-action listener: _resource_record_post_action 16:12:04 INFO - Running post-run listener: _resource_record_post_run 16:12:05 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 16384; Write bytes: 696201216; Read time: 1; Write time: 2112 16:12:05 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380310016; Write bytes: 374416896; Read time: 28578; Write time: 716 16:12:05 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 16384; Write bytes: 298605056; Read time: 1; Write time: 1348 16:12:05 INFO - Running post-run listener: _upload_blobber_files 16:12:05 INFO - Blob upload gear active. 16:12:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:12:05 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 16:12:05 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'] 16:12:05 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 16:12:06 INFO - (blobuploader) - INFO - Open directory for files ... 16:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:12:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:07 INFO - (blobuploader) - INFO - Done attempting. 16:12:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:12:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:12:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:08 INFO - (blobuploader) - INFO - Done attempting. 16:12:08 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:08 INFO - Return code: 0 16:12:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a91d05382e07bb99336e21837f108e3c4175fbc99e2d37bead2fbdc5be70926d97a7e5022c9c66495fa783d11ad825c3a9c70a1432b588ee0f57f67678508d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd37a4b16ef0d16f15e3326f6817325dcd156b48e3cf62d1ba4af08ef637062b95bcaac32c88a6e7ee4fcb5eb540fa544eeb7a89b828054a51fe6244e7b82549"} 16:12:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:08 INFO - Contents: 16:12:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a91d05382e07bb99336e21837f108e3c4175fbc99e2d37bead2fbdc5be70926d97a7e5022c9c66495fa783d11ad825c3a9c70a1432b588ee0f57f67678508d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd37a4b16ef0d16f15e3326f6817325dcd156b48e3cf62d1ba4af08ef637062b95bcaac32c88a6e7ee4fcb5eb540fa544eeb7a89b828054a51fe6244e7b82549"} 16:12:08 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:08 INFO - Copying logs to upload dir... 16:12:08 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.594648 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-26 16:12:08.270173) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 16:12:08.273481) ========= 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.KzeznnkS07/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.8sebif21SY/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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/9a91d05382e07bb99336e21837f108e3c4175fbc99e2d37bead2fbdc5be70926d97a7e5022c9c66495fa783d11ad825c3a9c70a1432b588ee0f57f67678508d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd37a4b16ef0d16f15e3326f6817325dcd156b48e3cf62d1ba4af08ef637062b95bcaac32c88a6e7ee4fcb5eb540fa544eeb7a89b828054a51fe6244e7b82549"} build_url:https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/XffMD0uXTRqDit_U0xXslA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a91d05382e07bb99336e21837f108e3c4175fbc99e2d37bead2fbdc5be70926d97a7e5022c9c66495fa783d11ad825c3a9c70a1432b588ee0f57f67678508d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd37a4b16ef0d16f15e3326f6817325dcd156b48e3cf62d1ba4af08ef637062b95bcaac32c88a6e7ee4fcb5eb540fa544eeb7a89b828054a51fe6244e7b82549"}' ========= master_lag: 0.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 16:12:08.568311) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:12:08.568921) ========= 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.KzeznnkS07/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.8sebif21SY/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.005235 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:12:08.619187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 16:12:08.619657) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 16:12:08.624597) ========= ========= Total master_lag: 2.61 =========