builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0338 starttime: 1476460732.09 results: success (0) buildid: 20161014060324 builduid: cc12e163b33e4b2fb41aa379f63cd7aa revision: 0c899672fff6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.087644) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.088068) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.088392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.112479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.112901) ========= 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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-10-14 08:58:52-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2016-10-14 08:58:52 (17.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106321 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.241961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.242343) ========= 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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.035516 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.297479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:52.297840) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0c899672fff6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0c899672fff6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MpVO6lbrLj/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.f8iSbpwPyu/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-10-14 08:58:52,371 Setting DEBUG logging. 2016-10-14 08:58:52,371 attempt 1/10 2016-10-14 08:58:52,371 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0c899672fff6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 08:58:53,010 unpacking tar archive at: mozilla-central-0c899672fff6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.900979 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:53.220904) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:53.221341) ========= script_repo_revision: 0c899672fff6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:53.221810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:53.222137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 08:58:53.240265) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-14 08:58:53.240632) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MpVO6lbrLj/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.f8iSbpwPyu/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:58:53 INFO - MultiFileLogger online at 20161014 08:58:53 in /builds/slave/test 08:58:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:58:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:58:53 INFO - {'append_to_log': False, 08:58:53 INFO - 'base_work_dir': '/builds/slave/test', 08:58:53 INFO - 'blob_upload_branch': 'Firefox', 08:58:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:58:53 INFO - 'branch': 'Firefox', 08:58:53 INFO - 'buildbot_json_path': 'buildprops.json', 08:58:53 INFO - 'config_files': ('talos/mac_config.py',), 08:58:53 INFO - 'default_actions': ('clobber', 08:58:53 INFO - 'read-buildbot-config', 08:58:53 INFO - 'download-and-extract', 08:58:53 INFO - 'populate-webroot', 08:58:53 INFO - 'create-virtualenv', 08:58:53 INFO - 'install', 08:58:53 INFO - 'run-tests'), 08:58:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:58:53 INFO - 'download_minidump_stackwalk': True, 08:58:53 INFO - 'download_symbols': 'ondemand', 08:58:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:58:53 INFO - 'tooltool.py': '/tools/tooltool.py', 08:58:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:58:53 INFO - '/tools/misc-python/virtualenv.py')}, 08:58:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:58:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:58:53 INFO - 'installer_path': 'installer.exe', 08:58:53 INFO - 'log_level': 'info', 08:58:53 INFO - 'log_name': 'talos', 08:58:53 INFO - 'log_to_console': True, 08:58:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:58:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:58:53 INFO - 'opt_config_files': (), 08:58:53 INFO - 'pip_index': False, 08:58:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:58:53 INFO - 'cmd': ('bash', 08:58:53 INFO - '-c', 08:58:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:58:53 INFO - 'enabled': True, 08:58:53 INFO - 'halt_on_failure': False, 08:58:53 INFO - 'name': 'check_screen_resolution'},), 08:58:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:58:53 INFO - 'cmd': ('bash', 08:58:53 INFO - '-c', 08:58:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:58:53 INFO - 'enabled': True, 08:58:53 INFO - 'halt_on_failure': False, 08:58:53 INFO - 'name': 'check_screen_resolution'},), 08:58:53 INFO - 'run_cmd_checks_enabled': True, 08:58:53 INFO - 'sps_profile': False, 08:58:53 INFO - 'sps_profile_interval': 0, 08:58:53 INFO - 'suite': 'dromaeojs-e10s', 08:58:53 INFO - 'system_bits': '32', 08:58:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:58:53 INFO - 'title': 't-yosemite-r7-0338', 08:58:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:58:53 INFO - 'use_talos_json': True, 08:58:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:58:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:58:53 INFO - 'work_dir': 'build'} 08:58:53 INFO - [mozharness: 2016-10-14 15:58:53.438521Z] Running clobber step. 08:58:53 INFO - Running pre-action listener: _resource_record_pre_action 08:58:53 INFO - Running main action method: clobber 08:58:53 INFO - rmtree: /builds/slave/test/build 08:58:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:58:55 INFO - Running post-action listener: _resource_record_post_action 08:58:55 INFO - [mozharness: 2016-10-14 15:58:55.588189Z] Finished clobber step (success) 08:58:55 INFO - [mozharness: 2016-10-14 15:58:55.588315Z] Running read-buildbot-config step. 08:58:55 INFO - Running pre-action listener: _resource_record_pre_action 08:58:55 INFO - Running main action method: read_buildbot_config 08:58:55 INFO - Using buildbot properties: 08:58:55 INFO - { 08:58:55 INFO - "project": "", 08:58:55 INFO - "product": "firefox", 08:58:55 INFO - "script_repo_revision": "production", 08:58:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:58:55 INFO - "repository": "", 08:58:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:58:55 INFO - "buildid": "20161014060324", 08:58:55 INFO - "stage_platform": "macosx64", 08:58:55 INFO - "basedir": "/builds/slave/test", 08:58:55 INFO - "nightly_build": "True", 08:58:55 INFO - "buildnumber": 19, 08:58:55 INFO - "slavename": "t-yosemite-r7-0338", 08:58:55 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:58:55 INFO - "platform": "yosemite_r7", 08:58:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:58:55 INFO - "branch": "mozilla-central", 08:58:55 INFO - "repo_path": "mozilla-central", 08:58:55 INFO - "revision": "0c899672fff6", 08:58:55 INFO - "pgo_build": "False", 08:58:55 INFO - "builduid": "cc12e163b33e4b2fb41aa379f63cd7aa", 08:58:55 INFO - "slavebuilddir": "test" 08:58:55 INFO - } 08:58:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:58:55 INFO - Running post-action listener: _resource_record_post_action 08:58:55 INFO - [mozharness: 2016-10-14 15:58:55.591644Z] Finished read-buildbot-config step (success) 08:58:55 INFO - [mozharness: 2016-10-14 15:58:55.591766Z] Running download-and-extract step. 08:58:55 INFO - Running pre-action listener: _resource_record_pre_action 08:58:55 INFO - Running main action method: download_and_extract 08:58:55 INFO - mkdir: /builds/slave/test/build/tests 08:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:55 INFO - trying https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:58:55 INFO - Downloading https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:58:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:58:56 INFO - Downloaded 1199 bytes. 08:58:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:58:56 INFO - Using the following test package requirements: 08:58:56 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:58:56 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:58:56 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:58:56 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'jsshell-mac.zip'], 08:58:56 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:58:56 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:58:56 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:58:56 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:58:56 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:58:56 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:58:56 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:58:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:58:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:58:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:58:56 INFO - Fetch https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:58:57 INFO - Http code: 200 08:58:57 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 08:58:57 INFO - x-amz-cf-id: _DyjI6a_fmKgsiFQcn_vr2--VRTuzGgph-aZkO8kAQBkCwkwUI_Mbw== 08:58:57 INFO - x-amz-version-id: 79ar5oblkmkwB3Af44h7LN7Jip16BziA 08:58:57 INFO - Expected file size: 16517006 08:58:57 INFO - Obtained file size: 16517006 08:59:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:59:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:59:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:59:04 INFO - Fetch https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:59:05 INFO - Http code: 200 08:59:05 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 08:59:05 INFO - x-amz-cf-id: F0reWgu-kY5Uqk-XEOpSrr_oORS86AjJutuYIGAKCXhjoxNrFhowxw== 08:59:05 INFO - x-amz-version-id: 79ar5oblkmkwB3Af44h7LN7Jip16BziA 08:59:05 INFO - Expected file size: 16517006 08:59:05 INFO - Obtained file size: 16517006 08:59:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:59:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:59:11 INFO - Fetch https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:59:12 INFO - Http code: 200 08:59:12 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 08:59:12 INFO - x-amz-cf-id: i3XL_xgG5mbJGG4Hm0wjOfGDWKFxlCQrEZcgsyftNiieCcKOaCkA8w== 08:59:12 INFO - x-amz-version-id: OIPIrHjIm5KxO3dATVMP10W.973hhzKI 08:59:12 INFO - Expected file size: 14409006 08:59:12 INFO - Obtained file size: 14409006 08:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:13 INFO - trying https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:59:13 INFO - Downloading https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:59:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:59:16 INFO - Downloaded 112078794 bytes. 08:59:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:59:16 INFO - mkdir: /builds/slave/test/properties 08:59:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:59:16 INFO - Writing to file /builds/slave/test/properties/build_url 08:59:16 INFO - Contents: 08:59:16 INFO - build_url:https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:59:16 INFO - Running post-action listener: _resource_record_post_action 08:59:16 INFO - Running post-action listener: set_extra_try_arguments 08:59:16 INFO - [mozharness: 2016-10-14 15:59:16.699516Z] Finished download-and-extract step (success) 08:59:16 INFO - [mozharness: 2016-10-14 15:59:16.699635Z] Running populate-webroot step. 08:59:16 INFO - Running pre-action listener: _resource_record_pre_action 08:59:16 INFO - Running main action method: populate_webroot 08:59:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:59:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:59:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:59:16 INFO - '--tppagecycles', 08:59:16 INFO - '7'], 08:59:16 INFO - 'tests': ['tsvgm']}}, 08:59:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:59:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:59:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:59:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:59:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['damp', 'tps']}, 08:59:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'tests': ['damp', 'tps']}, 08:59:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['dromaeo_dom']}, 08:59:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:59:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['basic_compositor_video']}, 08:59:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:59:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['a11yr', 08:59:16 INFO - 'ts_paint', 08:59:16 INFO - 'tpaint', 08:59:16 INFO - 'sessionrestore', 08:59:16 INFO - 'sessionrestore_no_auto_restore', 08:59:16 INFO - 'tabpaint']}, 08:59:16 INFO - 'other-e10s': {'tests': ['a11yr', 08:59:16 INFO - 'ts_paint', 08:59:16 INFO - 'tpaint', 08:59:16 INFO - 'sessionrestore', 08:59:16 INFO - 'sessionrestore_no_auto_restore', 08:59:16 INFO - 'tabpaint']}, 08:59:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:59:16 INFO - 'ts_paint', 08:59:16 INFO - 'tpaint', 08:59:16 INFO - 'sessionrestore', 08:59:16 INFO - 'sessionrestore_no_auto_restore', 08:59:16 INFO - 'tabpaint']}, 08:59:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:59:16 INFO - 'ts_paint', 08:59:16 INFO - 'tpaint', 08:59:16 INFO - 'sessionrestore', 08:59:16 INFO - 'sessionrestore_no_auto_restore', 08:59:16 INFO - 'tabpaint']}, 08:59:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['a11yr', 08:59:16 INFO - 'ts_paint', 08:59:16 INFO - 'tpaint', 08:59:16 INFO - 'sessionrestore', 08:59:16 INFO - 'sessionrestore_no_auto_restore', 08:59:16 INFO - 'tabpaint']}, 08:59:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['a11yr', 08:59:16 INFO - 'ts_paint', 08:59:16 INFO - 'tpaint', 08:59:16 INFO - 'sessionrestore', 08:59:16 INFO - 'sessionrestore_no_auto_restore', 08:59:16 INFO - 'tabpaint']}, 08:59:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['tsvgx', 08:59:16 INFO - 'tsvgr_opacity', 08:59:16 INFO - 'tart', 08:59:16 INFO - 'tscrollx', 08:59:16 INFO - 'cart']}, 08:59:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:59:16 INFO - 'tsvgr_opacity', 08:59:16 INFO - 'tart', 08:59:16 INFO - 'tscrollx', 08:59:16 INFO - 'cart']}, 08:59:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'talos_options': ['--disable-e10s'], 08:59:16 INFO - 'tests': ['tp5o']}, 08:59:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'tests': ['tp5o']}, 08:59:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'talos_options': ['--disable-e10s', 08:59:16 INFO - '--xperf_path', 08:59:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:16 INFO - 'tests': ['tp5n']}, 08:59:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:16 INFO - 'talos_options': ['--xperf_path', 08:59:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:16 INFO - 'tests': ['tp5n']}}, 08:59:16 INFO - 'talos.zip': {'path': '', 08:59:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:59:16 INFO - Running post-action listener: _resource_record_post_action 08:59:16 INFO - [mozharness: 2016-10-14 15:59:16.718426Z] Finished populate-webroot step (success) 08:59:16 INFO - [mozharness: 2016-10-14 15:59:16.718561Z] Running create-virtualenv step. 08:59:16 INFO - Running pre-action listener: _resource_record_pre_action 08:59:16 INFO - Running main action method: create_virtualenv 08:59:16 INFO - Creating virtualenv /builds/slave/test/build/venv 08:59:16 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 08:59:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:59:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:59:16 INFO - Using real prefix '/tools/python27' 08:59:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:59:17 INFO - Installing distribute.............................................................................................................................................................................................done. 08:59:20 INFO - Installing pip.................done. 08:59:20 INFO - Return code: 0 08:59:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:59:20 INFO - Reading from file tmpfile_stdout 08:59:20 INFO - Output received: 08:59:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:59:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:20 INFO - 'HOME': '/Users/cltbld', 08:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:20 INFO - 'LOGNAME': 'cltbld', 08:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:20 INFO - 'MOZ_NO_REMOTE': '1', 08:59:20 INFO - 'NO_EM_RESTART': '1', 08:59:20 INFO - 'PAGER': '/bin/cat', 08:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:20 INFO - 'PWD': '/builds/slave/test', 08:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:20 INFO - 'SHELL': '/bin/bash', 08:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:20 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:20 INFO - 'USER': 'cltbld', 08:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:20 INFO - 'XPC_FLAGS': '0x0', 08:59:20 INFO - 'XPC_SERVICE_NAME': '0', 08:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:20 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 08:59:20 INFO - Cleaning up... 08:59:20 INFO - Return code: 0 08:59:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:20 INFO - 'HOME': '/Users/cltbld', 08:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:20 INFO - 'LOGNAME': 'cltbld', 08:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:20 INFO - 'MOZ_NO_REMOTE': '1', 08:59:20 INFO - 'NO_EM_RESTART': '1', 08:59:20 INFO - 'PAGER': '/bin/cat', 08:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:20 INFO - 'PWD': '/builds/slave/test', 08:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:20 INFO - 'SHELL': '/bin/bash', 08:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:20 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:20 INFO - 'USER': 'cltbld', 08:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:20 INFO - 'XPC_FLAGS': '0x0', 08:59:20 INFO - 'XPC_SERVICE_NAME': '0', 08:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:21 INFO - Downloading/unpacking psutil>=3.1.1 08:59:21 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 08:59:21 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 08:59:21 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 08:59:21 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 08:59:21 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 08:59:21 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 08:59:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:59:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:24 INFO - Installing collected packages: psutil 08:59:24 INFO - Running setup.py install for psutil 08:59:24 INFO - building 'psutil._psutil_osx' extension 08:59:24 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 08:59:25 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 08:59:25 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 08:59:25 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 08:59:25 INFO - building 'psutil._psutil_posix' extension 08:59:25 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 08:59:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:59:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:59:25 INFO - ^ 08:59:25 INFO - 1 warning generated. 08:59:25 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 08:59:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:25 INFO - Successfully installed psutil 08:59:25 INFO - Cleaning up... 08:59:25 INFO - Return code: 0 08:59:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:59: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 mozsystemmonitor==0.3 08:59:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:25 INFO - 'HOME': '/Users/cltbld', 08:59:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:25 INFO - 'LOGNAME': 'cltbld', 08:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:25 INFO - 'MOZ_NO_REMOTE': '1', 08:59:25 INFO - 'NO_EM_RESTART': '1', 08:59:25 INFO - 'PAGER': '/bin/cat', 08:59:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:25 INFO - 'PWD': '/builds/slave/test', 08:59:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:25 INFO - 'SHELL': '/bin/bash', 08:59:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:25 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:25 INFO - 'USER': 'cltbld', 08:59:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:25 INFO - 'XPC_FLAGS': '0x0', 08:59:25 INFO - 'XPC_SERVICE_NAME': '0', 08:59:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:59:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:59:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:59:28 INFO - Installing collected packages: mozsystemmonitor 08:59:28 INFO - Running setup.py install for mozsystemmonitor 08:59:28 INFO - Successfully installed mozsystemmonitor 08:59:28 INFO - Cleaning up... 08:59:28 INFO - Return code: 0 08:59:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:28 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59:28 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==2.5.1'] in /builds/slave/test/build 08:59:28 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==2.5.1 08:59:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:28 INFO - 'HOME': '/Users/cltbld', 08:59:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:28 INFO - 'LOGNAME': 'cltbld', 08:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:28 INFO - 'MOZ_NO_REMOTE': '1', 08:59:28 INFO - 'NO_EM_RESTART': '1', 08:59:28 INFO - 'PAGER': '/bin/cat', 08:59:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:28 INFO - 'PWD': '/builds/slave/test', 08:59:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:28 INFO - 'SHELL': '/bin/bash', 08:59:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:28 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:28 INFO - 'USER': 'cltbld', 08:59:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:28 INFO - 'XPC_FLAGS': '0x0', 08:59:28 INFO - 'XPC_SERVICE_NAME': '0', 08:59:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:29 INFO - Downloading/unpacking jsonschema==2.5.1 08:59:29 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 08:59:29 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 08:59:29 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 08:59:29 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 08:59:29 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 08:59:29 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 08:59:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:59:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:59:31 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 08:59:31 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 08:59:31 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 08:59:31 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 08:59:31 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 08:59:31 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 08:59:31 INFO - Downloading functools32-3.2.3-2.tar.gz 08:59:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:59:31 INFO - warning: no files found matching '*.txt' 08:59:31 INFO - no previously-included directories found matching 'build' 08:59:31 INFO - no previously-included directories found matching 'dist' 08:59:31 INFO - no previously-included directories found matching '.git*' 08:59:31 INFO - Installing collected packages: jsonschema, functools32 08:59:32 INFO - Running setup.py install for functools32 08:59:32 INFO - warning: no files found matching '*.txt' 08:59:32 INFO - no previously-included directories found matching 'build' 08:59:32 INFO - no previously-included directories found matching 'dist' 08:59:32 INFO - no previously-included directories found matching '.git*' 08:59:32 INFO - Successfully installed jsonschema functools32 08:59:32 INFO - Cleaning up... 08:59:32 INFO - Return code: 0 08:59:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:59: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 functools32==3.2.3-2 08:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:32 INFO - 'HOME': '/Users/cltbld', 08:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:32 INFO - 'LOGNAME': 'cltbld', 08:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:32 INFO - 'MOZ_NO_REMOTE': '1', 08:59:32 INFO - 'NO_EM_RESTART': '1', 08:59:32 INFO - 'PAGER': '/bin/cat', 08:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:32 INFO - 'PWD': '/builds/slave/test', 08:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:32 INFO - 'SHELL': '/bin/bash', 08:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:32 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:32 INFO - 'USER': 'cltbld', 08:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:32 INFO - 'XPC_FLAGS': '0x0', 08:59:32 INFO - 'XPC_SERVICE_NAME': '0', 08:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:59:32 INFO - Cleaning up... 08:59:32 INFO - Return code: 0 08:59:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59: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 08:59: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 08:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:32 INFO - 'HOME': '/Users/cltbld', 08:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:32 INFO - 'LOGNAME': 'cltbld', 08:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:32 INFO - 'MOZ_NO_REMOTE': '1', 08:59:32 INFO - 'NO_EM_RESTART': '1', 08:59:32 INFO - 'PAGER': '/bin/cat', 08:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:32 INFO - 'PWD': '/builds/slave/test', 08:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:32 INFO - 'SHELL': '/bin/bash', 08:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:32 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:32 INFO - 'USER': 'cltbld', 08:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:32 INFO - 'XPC_FLAGS': '0x0', 08:59:32 INFO - 'XPC_SERVICE_NAME': '0', 08:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:32 INFO - Downloading/unpacking blobuploader==1.2.4 08:59:32 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 08:59:32 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 08:59:32 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 08:59:32 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 08:59:32 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 08:59:32 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 08:59:35 INFO - Downloading blobuploader-1.2.4.tar.gz 08:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:59:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:59:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59:36 INFO - Downloading docopt-0.6.1.tar.gz 08:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:59:36 INFO - Installing collected packages: blobuploader, requests, docopt 08:59:36 INFO - Running setup.py install for blobuploader 08:59:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:59:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:59:36 INFO - Running setup.py install for requests 08:59:37 INFO - Running setup.py install for docopt 08:59:37 INFO - Successfully installed blobuploader requests docopt 08:59:37 INFO - Cleaning up... 08:59:37 INFO - Return code: 0 08:59:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59: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 08:59: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 08:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:37 INFO - 'HOME': '/Users/cltbld', 08:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:37 INFO - 'LOGNAME': 'cltbld', 08:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:37 INFO - 'MOZ_NO_REMOTE': '1', 08:59:37 INFO - 'NO_EM_RESTART': '1', 08:59:37 INFO - 'PAGER': '/bin/cat', 08:59:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:37 INFO - 'PWD': '/builds/slave/test', 08:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:37 INFO - 'SHELL': '/bin/bash', 08:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:37 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:37 INFO - 'USER': 'cltbld', 08:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:37 INFO - 'XPC_FLAGS': '0x0', 08:59:37 INFO - 'XPC_SERVICE_NAME': '0', 08:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:59:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-RqJ0Zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:59:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-3spxqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:59:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Tcl2IV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-jG1azz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-k8eQx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-jsLlqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-c5Tqq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-xosvZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-tzKqpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-a3oTlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-0V_znb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-2HSM8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:59:38 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-bi61Tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:59:39 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-x_2f47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:39 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-WUhI3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:59:39 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-xjjie5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:59:39 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-_MM7r1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:59:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:59:39 INFO - Running setup.py install for manifestparser 08:59:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:59:39 INFO - Running setup.py install for mozcrash 08:59:39 INFO - Running setup.py install for mozdebug 08:59:39 INFO - Running setup.py install for mozdevice 08:59:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Running setup.py install for mozfile 08:59:40 INFO - Running setup.py install for mozhttpd 08:59:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Running setup.py install for mozinfo 08:59:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Running setup.py install for mozInstall 08:59:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Running setup.py install for mozleak 08:59:40 INFO - Running setup.py install for mozlog 08:59:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:59:40 INFO - Running setup.py install for moznetwork 08:59:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:59:41 INFO - Running setup.py install for mozprocess 08:59:41 INFO - Running setup.py install for mozprofile 08:59:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:59:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:59:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:59:41 INFO - Running setup.py install for mozrunner 08:59:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:59:41 INFO - Running setup.py install for mozscreenshot 08:59:41 INFO - Running setup.py install for moztest 08:59:41 INFO - Running setup.py install for mozversion 08:59:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:59:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:59:42 INFO - Cleaning up... 08:59:42 INFO - Return code: 0 08:59:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59: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 08:59: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 08:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:42 INFO - 'HOME': '/Users/cltbld', 08:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:42 INFO - 'LOGNAME': 'cltbld', 08:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:42 INFO - 'MOZ_NO_REMOTE': '1', 08:59:42 INFO - 'NO_EM_RESTART': '1', 08:59:42 INFO - 'PAGER': '/bin/cat', 08:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:42 INFO - 'PWD': '/builds/slave/test', 08:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:42 INFO - 'SHELL': '/bin/bash', 08:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:42 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:42 INFO - 'USER': 'cltbld', 08:59:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:42 INFO - 'XPC_FLAGS': '0x0', 08:59:42 INFO - 'XPC_SERVICE_NAME': '0', 08:59:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:59:42 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-6ao14D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:59: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)) 08:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:59:42 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-UoRzkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:59: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)) 08:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:59:42 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-pl0JoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:59: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)) 08:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:59:42 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-eHWH63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:59: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)) 08:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:59:42 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-2mUD3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:59:42 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ZBcHc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:59:42 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-aPYs0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:59: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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-5BbW7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:59: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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-3hHycY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:59: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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-C02IVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-YM5NzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:59: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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-y3Vb1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-9YLDqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:59: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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-PdLq1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-qmGhyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:59:43 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-u1FpAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:59: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)) 08:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:59:44 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-WkXW1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59:46 INFO - Downloading blessings-1.6.tar.gz 08:59:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:59:46 INFO - Installing collected packages: blessings 08:59:46 INFO - Running setup.py install for blessings 08:59:47 INFO - Successfully installed blessings 08:59:47 INFO - Cleaning up... 08:59:47 INFO - Return code: 0 08:59:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:59:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:59:47 INFO - Reading from file tmpfile_stdout 08:59:47 INFO - Current package versions: 08:59:47 INFO - blessings == 1.6 08:59:47 INFO - blobuploader == 1.2.4 08:59:47 INFO - docopt == 0.6.1 08:59:47 INFO - functools32 == 3.2.3-2 08:59:47 INFO - jsonschema == 2.5.1 08:59:47 INFO - manifestparser == 1.1 08:59:47 INFO - mozInstall == 1.12 08:59:47 INFO - mozcrash == 0.17 08:59:47 INFO - mozdebug == 0.1 08:59:47 INFO - mozdevice == 0.48 08:59:47 INFO - mozfile == 1.2 08:59:47 INFO - mozhttpd == 0.7 08:59:47 INFO - mozinfo == 0.9 08:59:47 INFO - mozleak == 0.1 08:59:47 INFO - mozlog == 3.4 08:59:47 INFO - moznetwork == 0.27 08:59:47 INFO - mozprocess == 0.23 08:59:47 INFO - mozprofile == 0.28 08:59:47 INFO - mozrunner == 6.12 08:59:47 INFO - mozscreenshot == 0.1 08:59:47 INFO - mozsystemmonitor == 0.3 08:59:47 INFO - moztest == 0.7 08:59:47 INFO - mozversion == 1.4 08:59:47 INFO - psutil == 3.1.1 08:59:47 INFO - requests == 1.2.3 08:59:47 INFO - wsgiref == 0.1.2 08:59:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59: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 08:59: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 08:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:47 INFO - 'HOME': '/Users/cltbld', 08:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:47 INFO - 'LOGNAME': 'cltbld', 08:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:47 INFO - 'MOZ_NO_REMOTE': '1', 08:59:47 INFO - 'NO_EM_RESTART': '1', 08:59:47 INFO - 'PAGER': '/bin/cat', 08:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:47 INFO - 'PWD': '/builds/slave/test', 08:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:47 INFO - 'SHELL': '/bin/bash', 08:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:47 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:47 INFO - 'USER': 'cltbld', 08:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:47 INFO - 'XPC_FLAGS': '0x0', 08:59:47 INFO - 'XPC_SERVICE_NAME': '0', 08:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59: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)) 08:59:47 INFO - Cleaning up... 08:59:47 INFO - Return code: 0 08:59:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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 0x10824e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee41c5f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, '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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 08:59: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 08:59: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 08:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 08:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:47 INFO - 'HOME': '/Users/cltbld', 08:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:47 INFO - 'LOGNAME': 'cltbld', 08:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:47 INFO - 'MOZ_NO_REMOTE': '1', 08:59:47 INFO - 'NO_EM_RESTART': '1', 08:59:47 INFO - 'PAGER': '/bin/cat', 08:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:47 INFO - 'PWD': '/builds/slave/test', 08:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:47 INFO - 'SHELL': '/bin/bash', 08:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 08:59:47 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 08:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:47 INFO - 'USER': 'cltbld', 08:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:47 INFO - 'XPC_FLAGS': '0x0', 08:59:47 INFO - 'XPC_SERVICE_NAME': '0', 08:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:59:47 INFO - Cleaning up... 08:59:47 INFO - Return code: 0 08:59:47 INFO - Running post-action listener: _resource_record_post_action 08:59:47 INFO - Running post-action listener: _start_resource_monitoring 08:59:47 INFO - Starting resource monitoring. 08:59:47 INFO - [mozharness: 2016-10-14 15:59:47.998356Z] Finished create-virtualenv step (success) 08:59:47 INFO - [mozharness: 2016-10-14 15:59:47.999309Z] Running install step. 08:59:47 INFO - Running pre-action listener: _resource_record_pre_action 08:59:47 INFO - Running main action method: install 08:59:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:59:48 INFO - Reading from file tmpfile_stdout 08:59:48 INFO - Detecting whether we're running mozinstall >=1.0... 08:59:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:59:48 INFO - Reading from file tmpfile_stdout 08:59:48 INFO - Output received: 08:59:48 INFO - Usage: mozinstall [options] installer 08:59:48 INFO - Options: 08:59:48 INFO - -h, --help show this help message and exit 08:59:48 INFO - -d DEST, --destination=DEST 08:59:48 INFO - Directory to install application into. [default: 08:59:48 INFO - "/builds/slave/test"] 08:59:48 INFO - --app=APP Application being installed. [default: firefox] 08:59:48 INFO - mkdir: /builds/slave/test/build/application 08:59:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:00:24 INFO - Reading from file tmpfile_stdout 09:00:24 INFO - Output received: 09:00:24 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 09:00:24 INFO - Running post-action listener: _resource_record_post_action 09:00:24 INFO - [mozharness: 2016-10-14 16:00:24.088101Z] Finished install step (success) 09:00:24 INFO - [mozharness: 2016-10-14 16:00:24.088237Z] Running run-tests step. 09:00:24 INFO - Running pre-action listener: _resource_record_pre_action 09:00:24 INFO - Running main action method: run_tests 09:00:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:00:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:00:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:00:24 INFO - 2016-10-14 09:00:24.102 screenresolution[1643:7538] starting screenresolution argv=screenresolution get 09:00:24 INFO - 2016-10-14 09:00:24.105 screenresolution[1643:7538] Display 0: 1600x1200x32@60 09:00:24 INFO - 2016-10-14 09:00:24.111 screenresolution[1644:7540] starting screenresolution argv=screenresolution list 09:00:24 INFO - Available Modes on Display 0 09:00:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:00:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:00:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:00:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:00:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:00:24 INFO - Intel Iris: 09:00:24 INFO - Chipset Model: Intel Iris 09:00:24 INFO - Type: GPU 09:00:24 INFO - Bus: Built-In 09:00:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:00:24 INFO - Vendor: Intel (0x8086) 09:00:24 INFO - Device ID: 0x0a2e 09:00:24 INFO - Revision ID: 0x0009 09:00:24 INFO - Displays: 09:00:24 INFO - Display: 09:00:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:00:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:00:24 INFO - Display Serial Number: 1600x1200 60 09:00:24 INFO - Main Display: Yes 09:00:24 INFO - Mirror: Off 09:00:24 INFO - Online: Yes 09:00:24 INFO - Rotation: Supported 09:00:24 INFO - Return code: 0 09:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:24 INFO - Python 2.7.3 09:00:24 INFO - Return code: 0 09:00:24 INFO - grabbing minidump binary from tooltool 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:24 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108062d30>, '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 0x107e88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:24 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 09:00:24 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 09:00:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:00:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:24 INFO - Return code: 0 09:00:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:00:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:00:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 09:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0338 --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 09:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MpVO6lbrLj/Render', 09:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:24 INFO - 'HOME': '/Users/cltbld', 09:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:24 INFO - 'LOGNAME': 'cltbld', 09:00:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:24 INFO - 'MOZ_NO_REMOTE': '1', 09:00:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:24 INFO - 'NO_EM_RESTART': '1', 09:00:24 INFO - 'PAGER': '/bin/cat', 09:00:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:24 INFO - 'PWD': '/builds/slave/test', 09:00:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:24 INFO - 'SHELL': '/bin/bash', 09:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8iSbpwPyu/Listeners', 09:00:24 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:24 INFO - 'USER': 'cltbld', 09:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:24 INFO - 'XPC_FLAGS': '0x0', 09:00:24 INFO - 'XPC_SERVICE_NAME': '0', 09:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 09:00:24 INFO - mozversion application_buildid: 20161014060324 09:00:24 INFO - mozversion application_changeset: 0c899672fff6ae00f5b3affbec48ee4daac35fa1 09:00:24 INFO - mozversion application_display_name: Nightly 09:00:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:24 INFO - mozversion application_name: Firefox 09:00:24 INFO - mozversion application_remotingname: firefox 09:00:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:00:24 INFO - mozversion application_vendor: Mozilla 09:00:24 INFO - mozversion application_version: 52.0a1 09:00:24 INFO - mozversion platform_buildid: 20161014060324 09:00:24 INFO - mozversion platform_changeset: 0c899672fff6ae00f5b3affbec48ee4daac35fa1 09:00:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:00:24 INFO - mozversion platform_version: 52.0a1 09:00:24 INFO - using testdate: 1476460824 09:00:24 INFO - actual date: 1476460824 09:00:24 INFO - starting webserver on 'localhost:49267' 09:00:24 INFO - SUITE-START | Running 2 tests 09:00:24 INFO - TEST-START | dromaeo_css 09:00:24 INFO - operating with platform_type : mac_ 09:00:24 INFO - Initialising browser for dromaeo_css test... 09:00:24 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpMa5sIP/profile http://localhost:49267/getInfo.html) 09:00:27 INFO - PROCESS | 1654 | 2016-10-14 09:00:27.664 plugin-container[1657:7694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 09:00:27 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:27 INFO - PROCESS | 1654 | 2016-10-14 09:00:27.664 plugin-container[1657:7694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9603, name = 'com.apple.CFPasteboardClient' 09:00:27 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:27 INFO - PROCESS | 1654 | 2016-10-14 09:00:27.664 plugin-container[1657:7694] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:27 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:00:27 INFO - PROCESS | 1654 | colorDepth:24 09:00:27 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:00:27 INFO - PROCESS | 1654 | __metrics 09:00:27 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 09:00:28 INFO - TEST-INFO | 1654: exit 0 09:00:28 INFO - Browser initialized. 09:00:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:28 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpMa5sIP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:30 INFO - PROCESS | 1658 | 2016-10-14 09:00:30.913 plugin-container[1659:7816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 09:00:30 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:30 INFO - PROCESS | 1658 | 2016-10-14 09:00:30.914 plugin-container[1659:7816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:00:30 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:30 INFO - PROCESS | 1658 | 2016-10-14 09:00:30.914 plugin-container[1659:7816] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:40 INFO - PROCESS | 1658 | RSS: Main: 237137920 09:00:40 INFO - PROCESS | 1658 | 09:01:30 INFO - PROCESS | 1658 | 1476460890191 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] 09:03:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 09:03:45 INFO - PROCESS | 1658 | RSS: Main: 249434112 09:03:45 INFO - PROCESS | 1658 | 09:06:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 09:06:48 INFO - PROCESS | 1658 | RSS: Main: 263716864 09:06:48 INFO - PROCESS | 1658 | 09:09:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 09:09:51 INFO - PROCESS | 1658 | RSS: Main: 268865536 09:09:51 INFO - PROCESS | 1658 | 09:12:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 09:12:53 INFO - PROCESS | 1658 | RSS: Main: 268226560 09:12:53 INFO - PROCESS | 1658 | 09:15:56 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 09:15:57 INFO - PROCESS | 1658 | RSS: Main: 273457152 09:15:57 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 09:19:00 INFO - PROCESS | 1658 | RSS: Main: 276467712 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | __start_tp_report 09:19:00 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 09:19:00 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 09:19:00 INFO - PROCESS | 1658 | |i|pagename|runs| 09:19:00 INFO - PROCESS | 1658 | |0;dojo.html;3000.7899447038703;3416.8804091856773;3447.8965631031047;3475.6871881530665;3478.91302717432;5091.389033316007;5704.914426283609;5775.191473193751;5776.191333213348;5869.031609784386;5761;5768.509676677488;5807.30312362516;5880.647161170333;5912.142739302809;5216.504432078947;5250.3699556053325;5323.787048518055;5337.839864804062;5416.756245968922;5320.9467905321035;5383.219433182206;5386.676799392029;5412.052890744104;5470.699111548864;5167.715775632339;5210.6613070150315;5214.895702085956;5301.3373328333955;5323.627346085776;5230.660007099526;5248.763805628718;5268.604854635918;5279.102552566073;5289.285946397225;5255.1329030709885;5259.737013149327;5294.485119568213;5309.65487243328;5343.599230057761;4834.274858771178;5524.475174858382;5734.655920644775;5815.15680226369;5857.150713146612;6190.411910868505;6219.751209951596;6236.345183755686;6259.123722678829;6277.152584401093;6254.21822272216;6267.2792628847355;6271.749130034793;6276.811695649138;6281.497480201573;5160.638755287094;5746.856328591819;5820.7962721304975;5920.023196172583;5952.9107063394085;6399.744010239631;6406.327335629785;6408.1349017881985;6411;6416.454601358843;6177.81466556004;6217.9740342843825;6219.782307619212;6223.533235007392;6226.1905952226825;6130.979101202072;6144.525313924626;6186.1648677428875;6186.907196392058;6277.497800175975;6215.285242197115;6237.844053898689;6238.376162383816;6239.875202495925;6240.687965601793;6217.253929528395;6218.533609979269;6235.189425374763;6240.843978900564;6251.531135164881;4569.840055598104;4798.352222449995;4900.828471003498;4980.352554167935;5046.1169295373165;3638.599754027055;3773.8301776420126;4201.558836322142;4439.711418757835;4520.367148599199;5942.465178133989;6051.425114614105;6052.727627256784;6058.545609079249;6066.726997285133;6134.846628834315;6188.566800323934;6203.534734894811;6274.278457977391;6291.905621415683;4868.41579010521;5529.866377392625;5664.688442135722;5930;5996.4003599640555;5452.236686863842;5481.12302031681;5484.890302193934;5485.588580856452;5564.19319198722;6253.812385628438;6357.586756860789;6467.3209313023335;6502.187226596676;6513.967430162914;6170.660613666291;6237.220347456568;6253.843653908689;6329.936700632935;6340.397662222082;5455.1544510600925;6495.123158373748;6509.121268628674;6519.869602607826;6536.379043990813;6119.082137679384;6248.468880145057;6255.812325630239;6262.248530176408;6285.151504546828;6161.476274516716;6175.84560385994;6216.564840461305;6219.160413344141;6297.118403423433;5308.654937429054;6207.410296021871;6222.408871157233;6286.74853005893;6301.40136687014;6242.625442473466;6243.219597550306;6246.625202487865;6251.093591429309;6283.622982621057;5881.264841894763;5882.117682347682;6094.055421409688;6106.2061931948565;6106.764041036412;6200.596961197508;6256.624602523863;6265.749370025148;6266.248050234001;6275.309715931153;6032.4570788629235;6038.788642397495;6165.599236049642;6183.536234782319;6211.472024877936 09:19:00 INFO - PROCESS | 1658 | |1;ext.html;486.9967866195637;506.7963586482105;523.3301374240971;523.9939316512297;549.5795716277046;38994.41508377377;40949.59050409495;42116.36825447617;42526.78736606317;44941.651750447454;43871;44091.55908440923;44527.55472445275;44672.553274467246;44739.552604474025;9623.855642165374;9761.560729767178;9818.263630227726;9821.50892455378;9853.014698530162;9741.269404794633;9790.9510452448;9828.950855245714;9838.311318207729;9845.65540206093;7776.844463110734;9782.41305521671;9815.901840981605;9819.950900245489;9849.704508864745;8967.641294348217;9225.907740922572;9729.513524323791;9766.609335626566;9767.51162441875;9692.321537492378;9726.9513652432;9774.218062554979;9785.657501987396;9803.509824508747;41568.79215603933;41926.371104433456;41989.37015944763;42431.787841060905;42463.78768106171;2861.77105831533;3397.3375191837604;3403.864763215916;3881.844726210948;4108.794560271975;31225;31855.52216716751;31943.680563194303;32116;32726.6727332726;3063.417950589381;3070.17105381548;3075.6770539093295;3092.659807421182;3182.7294679952233;373337;374192;376982;377634;378068;56083.43916560864;61376;63104.68447657732;63725.681371592844;64369.67815160894;65881.67059164673;66363.00455493171;66491.33771655748;66772.66613666901;69153.3084669157;12856.228626282453;14293.785028272505;14348.426062957366;16515.504534863972;16668.33326666944;12791.04067194964;13002.349882506027;13011.544595939096;13039;13050.54323098687;17624.735628965576;18077.548061298574;18144.637107257782;18256.726149107773;18273;5354.651947623392;6162.322144564095;7063.117110361205;7095.716171353088;7097.89353159703;883.6907082521067;931.3527098666498;953.2135987810084;1011.4689787861284;1013.8469360289494;28070.841935306773;29542.96599619003;29726.554101688493;30674.386512270077;30979.845100774353;864.628209869762;911.9088091190828;1018.6282007067515;1081.561967403203;1222.4498975461079;13068.54260100892;15810.972286801321;15868.365265389255;15904.920475397781;16162.434314798926;593168;595550;597796;600966;601364;58254;59468.70265648644;60213;65135;65226;1415.5965549818252;1422.210673076432;1426.479335042702;1438.0796290374378;1443.5524987253984;25563.872180638977;31128.377432450772;32245;32448.268449128653;33473.991560506445;53272.73363633157;53536.73231633973;53776;53779;53787;585596;590282.4372903144;594497;594772;597629.011854938;26332.341691457867;26390.472190555698;26651.733482665702;26785.732142678324;26847.328816779736;47750.52249477461;48087.27869082036;48207.553773386855;48716.512834871195;48794.512054879;39348.409773853986;39399.80300098481;39539.40690889696;40198;40790;39051.80474097611;39428.40857387084;40241.59758402496;40538.79730601446;40726.38910416405;37980;38256.617433825304;38307.04232394212;38842.417363739;39149.80425097857;34079.4888076684;34820.30359392849;35276;35490.46764298486;35524;25704.357391065372;25792.226233213034;25971.74028259769;26384.208473745817;26576.468470630094 09:19:00 INFO - PROCESS | 1658 | |2;jquery.html;311.5887029121559;353.69405464273393;358.55001972524485;376.9038895081754;390.3130490337008;18868.62262754744;19305.806941930576;19371.225150993945;20519.179232830706;20741.377758667226;14914.850851491481;19803.801961980374;19847.007649617535;20328.288509902155;20361.389158325273;5597.300337457828;5685.573581981347;5703.230063941356;5757.107648314518;5827.475527202551;4886.804527818883;5365.114756065244;5615.101583746601;5693.6904511962275;5733.770649174028;5555.805546805863;5601.971990140044;5673.375928647866;5679.914801277984;5683.545316374701;5456.508914197721;5620.185073164409;5708.172315014342;5738.340090889559;5741.684207368593;4759.809607615691;4982.87542811429;5007.349044624212;5009.649324547283;5014.498550144993;22093.005814738375;23126;24469.63295550568;24630.261092167264;25049.373765655822;2222.4888275696517;2348.471593891378;2550.375158086262;2638.8680565971817;2778.458200650874;14548.908831837549;14981.850181498154;15368;15392.999455035388;15469.845301546953;1785.1252886085854;1834.1471215884678;1856.7679040119986;1865.2445759467437;1921.4523860699633;89879.55060224657;111834;115608;116403;119008.40495797552;8913.365603082064;9887.912329643734;10239.7440063999;10483.004114609252;10875.891241087646;12792.6801829955;12847.871521284855;12859.614211573667;12868.935655321664;12931.676708082374;7916.643751031217;8204.548749818818;8225.629846656915;8322;8331.250187483156;8210.3021243195;8211.58942052895;8245.711400101087;8344.749657510285;8412.242898139197;6318.873622527523;6483.481321494363;6486.44865186455;6556.541042127101;6748.358905903931;1543.2746609093708;1825.2151574822688;1835.4952388093382;1843.410108765209;1847.325726109987;904.9230815380766;953.1040821627648;957.4494665567327;996.3772642098688;1009.6819501856892;15790.946896993082;20660.483487912923;21406;21524.56950860974;21654.675179872316;981.4601968917067;987.5358581466843;1020.7805321855836;1028.3983869436463;1078.6925726168004;10219.948900255451;10678.092362149304;11276.32342059479;11456.541738330374;11515.827262591069;592600;597136;597952;607350;608824;93610.5319473412;93987;100817.49591251997;112285;121278;793.2464159048926;967.8209531236603;1002.1481740520645;1003.6838395905266;1008.9091981721533;18245.726314105035;18398.540036499195;18522.166502507687;18593.628127437645;18631.72052419188;55808.72095639496;56191.87616247734;56499.71750141388;57051.14423283571;58064.70967645135;80829.78755318793;81149.59425202836;85194.14805851862;85286;85356;6261.874762504815;7491.175970643334;7722;7824.765257042298;7846.5292082475235;8212.466189697889;11211.943940280247;11864.940675296568;12120.575779848015;12243.387830608612;8868.600912958804;9920.156786673213;10124.240681948737;10576.524056417325;10844.566217351217;10558.155347572329;10709.571617135227;10725.892741072801;10802.67591972242;10975.890241097486;10657.360558366523;10774.299670521392;10780.29928054674;10990.230683852373;11167.162462815484;7889.3294070004695;7903.960480197792;7921.128675845537;8023.558704271321;8130.552819594979;8804.559772011246;9499.952500237454;10515.316504427188;10745.570177192825;10838.349699018085 09:19:00 INFO - PROCESS | 1658 | |3;mootools.html;1530.4643374818816;1547.6517783498714;1746.9430994248487;1788.8551327150617;1838.5495553589374;7708.845823083535;7994.520328780277;8209.138040505743;8700.99938507071;8706.99869515006;8206.91793082069;8471.15288471154;8524.957375213131;8621.267192288647;8626.439281446701;2291.873946932918;2315.525317309956;2322.198841399714;2322.2568777990964;2351.024324905171;2296.3455415206586;2309.5727290451237;2339.3099035784485;2341.7658234176615;2344.7069116360453;2308.480591866825;2311.8556314624284;2325.767423257678;2329.04509151248;2345.718513778349;2262.0951619352227;2290.633498640218;2291.46150654596;2299.85050971688;2313.3060081975423;2246.483308838976;2254.864708117502;2263.0834512022657;2265.2071774878827;2269.1830940861278;8450.915490845136;8470.237678608955;8586.141385861363;8602.698905538276;8671.479711217347;5095.210242412395;5256.316678831766;5260.842174734764;5262.105442074857;5269.947300527022;3049.786514943955;3286.4248756467523;3348.983255083709;3431.897043088711;3479.1650003998857;937.5546615357719;950.9476978766165;955.2930831184942;956.0726095687241;959.9376040557411;9511.904880951102;10934.617288394868;11022.559097636085;11047.226694131414;11054.0051395374;3723.3298006358573;4139.606737359946;4316.136772645484;4661.790219440133;4695.272232803921;8300.584970751439;8762.342824288204;8777.253933415603;8805.515696636618;8823.602937867812;4507.459104907432;4886.364772579613;5091.41448733393;5128.3333166688835;5130.9230361544605;3938.724289299779;4301.65586753059;4452.332150177434;4614.515475875052;4756.167670657621;3606.1345277133823;3635.2911182319194;3654.2691461707764;3739.439084137401;3781.3193625147187;970.3304719743354;1136.198979719296;1139.5783560082823;1150.1891166727437;1150.8906653867868;804.5977011494252;859.7893516088474;876.5792419638609;935.5789894547481;958.6884262614539;5799;5915.438033386821;5973.581849270509;6099.664518451439;6139.846503837351;938.8263171313329;947.516766449116;958.7986522830145;963.3786207895868;993.2054356514759;3893.3186692329296;4003.610630414071;4051.1972172383007;4085.0400155963325;4086.9986853220557;10033.19734421259;10379.636712715019;10380.584776609003;10406.219533534864;10442.63450779219;9622.037796220322;10058.547365368577;10069.539916712036;10224.744381390376;10392.480375981171;7750.612469376509;7927.643256053491;7961.800954976172;7966.800829979297;7992.000999875016;5058.519440653279;5783.508401785895;5792.247007888947;5854.970725146347;6137.723802428991;9444.53609690499;9480.05199480074;9593.856092158483;9616.567254473706;9652.2278217741;9091.590878410356;9406.153446189875;9433.433993960385;9442;9575.808483830424;6684.532082754161;6910.481713871581;7125.5724656518705;7203.603801790951;7304.1235051792;7910.7231246906085;8189.262966333059;8219.383546233938;8238.917610823815;8244.587770611326;7212.603306818175;7383.593902335208;7386.815329616803;7413.8146546336775;7465.253474652492;7204.135503739585;7218.639068046472;7232.855342893007;7303.780886573411;7334.816629584303;5515.869246804476;6524.706388212447;6882.621455819978;6890;6898.379145876896;4945.085159245551;4969.577585905094;4991.101601711582;5026;5076.010178015252;4222.303319952173;4907.993861258362;4934.950650493449;5004.099262132851;5126.410462796677 09:19:00 INFO - PROCESS | 1658 | |4;prototype.html;248.28122585116088;303.27517233811193;303.33418147166964;306.35817961370935;308.876449420232;4335.436393268875;5551.252130195554;5630.887382252353;5651.632643878145;5657.632253903494;5037.5214354636255;5407.5133238008575;5446.945530544694;5474.39781624021;5533.280673512448;3959.6832253419657;4060.533038700543;4103.733257338264;4124.525679546846;4183.037901282707;4148.564400737924;4154.192926077151;4172.687048471362;4192.5597812229735;4206.768627725474;3978.482797236355;4128.545859955418;4137.234611596849;4170.687198460112;4200.222958752625;3905.3165696003084;4037.6971727120435;4118.5263694674895;4134.669226461906;4205.285101532747;3546.4680297955256;3561.8753343632848;3565.680698141678;3566.982165089184;3581.534400527915;4713.316569097462;5234.97382513085;5288.2596436498925;5303;5312.423474614848;3825.9234815303817;4270.4234928284595;4701.849760660366;4763.64272679547;4791.664583479123;5230.764615592269;5724.4561766632105;5829.912551311733;5832;5843.240378750777;3807.7905715185652;3963.1083006323347;3990.7206495545324;4003.019260281221;4013.237484877893;5298.258243845865;5678.517326027292;5820.5343572513675;6012.745220542297;6143.662098584576;3231.628362738268;3683;3898.434726964574;3960.5049368828895;3972.781497017634;3706.3328600851564;4015.056461731491;4044.9797751011056;4054.2904991626347;4057.736247143926;4693.825413669766;4767.523247675204;4769.403824521934;4770.403699537558;4772.6420518461255;5068.949310506848;5755.769769209269;5795.362510123883;5847.415258474203;5910.911336329958;1295.2811189789568;1361.2779833510617;1364.774812156003;1367.746966811143;1370.397025308861;737.7122922060294;766.7393086350668;831.2394159344133;837.8575642140851;842.2966822703112;4664.55686709762;4732.834350797705;4904.901901961941;5244.9737751310995;5304.92042619361;5225.686458812407;5887.381824908409;6101.572889897753;6104.542159338067;6110.236220472441;4806.423229212517;4980.327655766425;5421.834305624309;5562.721863906789;5633.605647604629;2994.296340360014;3092.1651154188244;3154.6056742907135;3155.8106513609255;3173.825439600798;5253.0134920555565;5518.503334699896;6273.529485288622;6279.968600157062;6323.841903952438;5590.776368945197;5855.736491857877;6065.302490213682;6079.422454866781;6171.783987560503;6183.783567575203;6227.626342419469;6266.780662676784;6300.495960323072;6324.367563243639;5065.4174769900455;5420.647657902224;5960.8807823844145;5995.340512543704;6023.241639105058;5384.923015396858;5496.8731410060045;5961;5994.850128746816;6007.8197654070445;5337.653052551572;6237.937620623736;6239.594426362272;6249.093881387264;6295.150154729053;3589.4974703541;3823.598522155245;3844.8462061518344;3967.325554655658;3981.183857309187;5106.259592359162;5599.552035837041;5794.449527294901;6108.419700128481;6138.723757431016;4894.363732714724;5498.5876059294915;5501.817109321515;5779.797707080232;5937.673427961504;4654;5150.845474635767;5554.972330118941;5715.971125197504;5959.212236329171;4647.512011238907;5761.654300741969;5786.07422812348;5804.738786754522;5832.766689332379;5242.951409717995;5459.317585301837;5541.251930989265;5853.736581853742;5919.253820122988;5789.421057894177;5798.202177573696;5800.854978625568;5857.882842343214;5879.7060146993335 09:19:00 INFO - PROCESS | 1658 | |5;yui.html;690.7064497588525;735.9595222262777;762.7597306848346;774.8721460958939;816.2735165702526;13355;15768.526944191664;16450.75323870143;16462.753058704115;16470.50588482346;14829;14899.478518251837;15051;15059.096454212755;15087.01934374265;896.7802888292377;917.4541148016953;918.6417297254076;920.3971398733806;922.6632279218104;807.4549678966704;868.7741187291318;895.9104089591021;913.2830727878617;925.0287198441644;973.0561355485167;974.5322245322214;974.8878878928944;975.5805003848342;978.7357413498352;958.702802131338;963.8987906269881;964.2045312617118;965.2422848064236;966.038791402552;681.9147606549182;705.9223485416651;716.7849645106447;716.9390601798854;719.7229066809264;13821.308934553334;13854.584362469142;13858.4456621735;13860.237686927216;13889;9827.459489728062;11741.119416043832;11818.349990750505;11929.343886086263;12717.173383730027;10767.030967212988;10773.622923197729;10854.72863178419;11070.612528561462;11226.775464490664;3548.840302186382;3661.8901432957055;3720.920932929447;3739.9626003739886;3883.320418926705;99576;100718;100920;105296;105585;8932.732018039469;9081.77295567603;9112.544372781467;9281;9621.470819105016;19746.30887918916;19832.50418739543;19882.60234795296;20004.799952000583;20006.199752009758;7669.7315593953945;7872.842543149105;7959.403044771665;7981;8065.395095367753;4281.764502952367;4437.955620443754;4444.288913773846;4457.086297309044;4460.1971645104195;3514.9297014059575;3637.890863274106;3638.9818050909576;3653.6894363978827;3793.729100751279;656.5404217048085;686.3136863136863;689.7551369263839;697.6407150317591;708.1077841919218;573.2891214893502;661.262692098307;666.8491378237787;668.2415458454689;691.9899184227414;8966.427122167612;9368.250539956924;9401.10689484498;9527.857082143773;9592.376495527767;596.8182997664649;623.5977794322637;625.4433554136822;654.8012956706428;667.3760034367882;3848.749831260959;4149.385807754346;4307;4468.687191896536;4960.479149689303;31989.04032879017;41489.54786582455;41707.58292417098;78285;143411;37353.132343383266;37535.744995700625;37818.43272350917;38105.80947095247;53667;72166.99999999895;73049;87896.56051719829;92228;104813;8714.956425217833;8829.64681412762;8885.555722213992;8974.820503590023;9176.908230917605;45160.54839451564;52580.9483810314;53793.38619841411;56757.71621141868;62402.68798655977;86270;89639.51802409985;91741.54129229311;94342;96514.00546920797;11784;11867.703307417383;11918.761624767629;12002.1598488105;12132.272063676208;10426;13228.933855330662;13393.263370514325;13459.394327255493;13479.326033698471;9755.658551950648;12068.275903445821;12118.151729379211;12132.514699411924;12478.500859965863;12100.878991210328;12174.794123198113;12228.266304021787;12247.938760306142;12608.49566017349;11938.880611193777;12175.33035683046;12226.87773122293;12529.185602936144;12535.185212961129;9941.30410871232;10408.49526432926;10496.79006419852;10623.36259824413;10707.571697132027;8808.74143869524;10238.33450825694;10294.3823370598;10425.843612345669;10433.582656693647 09:19:00 INFO - PROCESS | 1658 | __end_tp_report 09:19:00 INFO - PROCESS | 1658 | __start_cc_report 09:19:00 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,354 09:19:00 INFO - PROCESS | 1658 | __end_cc_report 09:19:00 INFO - PROCESS | 1658 | __startTimestamp1476461940258__endTimestamp 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | ------- Summary: start ------- 09:19:00 INFO - PROCESS | 1658 | Number of tests: 6 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5783.57 Median:6106.21 stddev:692.59 (11.3%) stddev-sans-first:659.41 09:19:00 INFO - PROCESS | 1658 | Values: 3000.8 3416.9 3447.9 3475.7 3478.9 5091.4 5704.9 5775.2 5776.2 5869.0 5761.0 5768.5 5807.3 5880.6 5912.1 5216.5 5250.4 5323.8 5337.8 5416.8 5320.9 5383.2 5386.7 5412.1 5470.7 5167.7 5210.7 5214.9 5301.3 5323.6 5230.7 5248.8 5268.6 5279.1 5289.3 5255.1 5259.7 5294.5 5309.7 5343.6 4834.3 5524.5 5734.7 5815.2 5857.2 6190.4 6219.8 6236.3 6259.1 6277.2 6254.2 6267.3 6271.7 6276.8 6281.5 5160.6 5746.9 5820.8 5920.0 5952.9 6399.7 6406.3 6408.1 6411.0 6416.5 6177.8 6218.0 6219.8 6223.5 6226.2 6131.0 6144.5 6186.2 6186.9 6277.5 6215.3 6237.8 6238.4 6239.9 6240.7 6217.3 6218.5 6235.2 6240.8 6251.5 4569.8 4798.4 4900.8 4980.4 5046.1 3638.6 3773.8 4201.6 4439.7 4520.4 5942.5 6051.4 6052.7 6058.5 6066.7 6134.8 6188.6 6203.5 6274.3 6291.9 4868.4 5529.9 5664.7 5930.0 5996.4 5452.2 5481.1 5484.9 5485.6 5564.2 6253.8 6357.6 6467.3 6502.2 6514.0 6170.7 6237.2 6253.8 6329.9 6340.4 5455.2 6495.1 6509.1 6519.9 6536.4 6119.1 6248.5 6255.8 6262.2 6285.2 6161.5 6175.8 6216.6 6219.2 6297.1 5308.7 6207.4 6222.4 6286.7 6301.4 6242.6 6243.2 6246.6 6251.1 6283.6 5881.3 5882.1 6094.1 6106.2 6106.8 6200.6 6256.6 6265.7 6266.2 6275.3 6032.5 6038.8 6165.6 6183.5 6211.5 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:67014.64 Median:22529.19 stddev:142081.19 (630.7%) stddev-sans-first:142391.97 09:19:00 INFO - PROCESS | 1658 | Values: 487.0 506.8 523.3 524.0 549.6 38994.4 40949.6 42116.4 42526.8 44941.7 43871.0 44091.6 44527.6 44672.6 44739.6 9623.9 9761.6 9818.3 9821.5 9853.0 9741.3 9791.0 9829.0 9838.3 9845.7 7776.8 9782.4 9815.9 9820.0 9849.7 8967.6 9225.9 9729.5 9766.6 9767.5 9692.3 9727.0 9774.2 9785.7 9803.5 41568.8 41926.4 41989.4 42431.8 42463.8 2861.8 3397.3 3403.9 3881.8 4108.8 31225.0 31855.5 31943.7 32116.0 32726.7 3063.4 3070.2 3075.7 3092.7 3182.7 373337.0 374192.0 376982.0 377634.0 378068.0 56083.4 61376.0 63104.7 63725.7 64369.7 65881.7 66363.0 66491.3 66772.7 69153.3 12856.2 14293.8 14348.4 16515.5 16668.3 12791.0 13002.3 13011.5 13039.0 13050.5 17624.7 18077.5 18144.6 18256.7 18273.0 5354.7 6162.3 7063.1 7095.7 7097.9 883.7 931.4 953.2 1011.5 1013.8 28070.8 29543.0 29726.6 30674.4 30979.8 864.6 911.9 1018.6 1081.6 1222.4 13068.5 15811.0 15868.4 15904.9 16162.4 593168.0 595550.0 597796.0 600966.0 601364.0 58254.0 59468.7 60213.0 65135.0 65226.0 1415.6 1422.2 1426.5 1438.1 1443.6 25563.9 31128.4 32245.0 32448.3 33474.0 53272.7 53536.7 53776.0 53779.0 53787.0 585596.0 590282.4 594497.0 594772.0 597629.0 26332.3 26390.5 26651.7 26785.7 26847.3 47750.5 48087.3 48207.6 48716.5 48794.5 39348.4 39399.8 39539.4 40198.0 40790.0 39051.8 39428.4 40241.6 40538.8 40726.4 37980.0 38256.6 38307.0 38842.4 39149.8 34079.5 34820.3 35276.0 35490.5 35524.0 25704.4 25792.2 25971.7 26384.2 26576.5 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34296.21 Median:2708.66 stddev:99707.34 (3681.1%) stddev-sans-first:99954.40 09:19:00 INFO - PROCESS | 1658 | Values: 311.6 353.7 358.6 376.9 390.3 18868.6 19305.8 19371.2 20519.2 20741.4 14914.9 19803.8 19847.0 20328.3 20361.4 5597.3 5685.6 5703.2 5757.1 5827.5 4886.8 5365.1 5615.1 5693.7 5733.8 5555.8 5602.0 5673.4 5679.9 5683.5 5456.5 5620.2 5708.2 5738.3 5741.7 4759.8 4982.9 5007.3 5009.6 5014.5 22093.0 23126.0 24469.6 24630.3 25049.4 2222.5 2348.5 2550.4 2638.9 2778.5 14548.9 14981.9 15368.0 15393.0 15469.8 1785.1 1834.1 1856.8 1865.2 1921.5 89879.6 111834.0 115608.0 116403.0 119008.4 8913.4 9887.9 10239.7 10483.0 10875.9 12792.7 12847.9 12859.6 12868.9 12931.7 7916.6 8204.5 8225.6 8322.0 8331.3 8210.3 8211.6 8245.7 8344.7 8412.2 6318.9 6483.5 6486.4 6556.5 6748.4 1543.3 1825.2 1835.5 1843.4 1847.3 904.9 953.1 957.4 996.4 1009.7 15790.9 20660.5 21406.0 21524.6 21654.7 981.5 987.5 1020.8 1028.4 1078.7 10219.9 10678.1 11276.3 11456.5 11515.8 592600.0 597136.0 597952.0 607350.0 608824.0 93610.5 93987.0 100817.5 112285.0 121278.0 793.2 967.8 1002.1 1003.7 1008.9 18245.7 18398.5 18522.2 18593.6 18631.7 55808.7 56191.9 56499.7 57051.1 58064.7 80829.8 81149.6 85194.1 85286.0 85356.0 6261.9 7491.2 7722.0 7824.8 7846.5 8212.5 11211.9 11864.9 12120.6 12243.4 8868.6 9920.2 10124.2 10576.5 10844.6 10558.2 10709.6 10725.9 10802.7 10975.9 10657.4 10774.3 10780.3 10990.2 11167.2 7889.3 7904.0 7921.1 8023.6 8130.6 8804.6 9500.0 10515.3 10745.6 10838.3 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5471.24 Median:5261.47 stddev:3014.72 (57.3%) stddev-sans-first:3008.64 09:19:00 INFO - PROCESS | 1658 | Values: 1530.5 1547.7 1746.9 1788.9 1838.5 7708.8 7994.5 8209.1 8701.0 8707.0 8206.9 8471.2 8525.0 8621.3 8626.4 2291.9 2315.5 2322.2 2322.3 2351.0 2296.3 2309.6 2339.3 2341.8 2344.7 2308.5 2311.9 2325.8 2329.0 2345.7 2262.1 2290.6 2291.5 2299.9 2313.3 2246.5 2254.9 2263.1 2265.2 2269.2 8450.9 8470.2 8586.1 8602.7 8671.5 5095.2 5256.3 5260.8 5262.1 5269.9 3049.8 3286.4 3349.0 3431.9 3479.2 937.6 950.9 955.3 956.1 959.9 9511.9 10934.6 11022.6 11047.2 11054.0 3723.3 4139.6 4316.1 4661.8 4695.3 8300.6 8762.3 8777.3 8805.5 8823.6 4507.5 4886.4 5091.4 5128.3 5130.9 3938.7 4301.7 4452.3 4614.5 4756.2 3606.1 3635.3 3654.3 3739.4 3781.3 970.3 1136.2 1139.6 1150.2 1150.9 804.6 859.8 876.6 935.6 958.7 5799.0 5915.4 5973.6 6099.7 6139.8 938.8 947.5 958.8 963.4 993.2 3893.3 4003.6 4051.2 4085.0 4087.0 10033.2 10379.6 10380.6 10406.2 10442.6 9622.0 10058.5 10069.5 10224.7 10392.5 7750.6 7927.6 7961.8 7966.8 7992.0 5058.5 5783.5 5792.2 5855.0 6137.7 9444.5 9480.1 9593.9 9616.6 9652.2 9091.6 9406.2 9433.4 9442.0 9575.8 6684.5 6910.5 7125.6 7203.6 7304.1 7910.7 8189.3 8219.4 8238.9 8244.6 7212.6 7383.6 7386.8 7413.8 7465.3 7204.1 7218.6 7232.9 7303.8 7334.8 5515.9 6524.7 6882.6 6890.0 6898.4 4945.1 4969.6 4991.1 5026.0 5076.0 4222.3 4908.0 4935.0 5004.1 5126.4 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4691.73 Median:5270.64 stddev:1466.56 (27.8%) stddev-sans-first:1432.25 09:19:00 INFO - PROCESS | 1658 | Values: 248.3 303.3 303.3 306.4 308.9 4335.4 5551.3 5630.9 5651.6 5657.6 5037.5 5407.5 5446.9 5474.4 5533.3 3959.7 4060.5 4103.7 4124.5 4183.0 4148.6 4154.2 4172.7 4192.6 4206.8 3978.5 4128.5 4137.2 4170.7 4200.2 3905.3 4037.7 4118.5 4134.7 4205.3 3546.5 3561.9 3565.7 3567.0 3581.5 4713.3 5235.0 5288.3 5303.0 5312.4 3825.9 4270.4 4701.8 4763.6 4791.7 5230.8 5724.5 5829.9 5832.0 5843.2 3807.8 3963.1 3990.7 4003.0 4013.2 5298.3 5678.5 5820.5 6012.7 6143.7 3231.6 3683.0 3898.4 3960.5 3972.8 3706.3 4015.1 4045.0 4054.3 4057.7 4693.8 4767.5 4769.4 4770.4 4772.6 5068.9 5755.8 5795.4 5847.4 5910.9 1295.3 1361.3 1364.8 1367.7 1370.4 737.7 766.7 831.2 837.9 842.3 4664.6 4732.8 4904.9 5245.0 5304.9 5225.7 5887.4 6101.6 6104.5 6110.2 4806.4 4980.3 5421.8 5562.7 5633.6 2994.3 3092.2 3154.6 3155.8 3173.8 5253.0 5518.5 6273.5 6280.0 6323.8 5590.8 5855.7 6065.3 6079.4 6171.8 6183.8 6227.6 6266.8 6300.5 6324.4 5065.4 5420.6 5960.9 5995.3 6023.2 5384.9 5496.9 5961.0 5994.9 6007.8 5337.7 6237.9 6239.6 6249.1 6295.2 3589.5 3823.6 3844.8 3967.3 3981.2 5106.3 5599.6 5794.4 6108.4 6138.7 4894.4 5498.6 5501.8 5779.8 5937.7 4654.0 5150.8 5555.0 5716.0 5959.2 4647.5 5761.7 5786.1 5804.7 5832.8 5243.0 5459.3 5541.3 5853.7 5919.3 5789.4 5798.2 5800.9 5857.9 5879.7 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:18603.41 Median:4458.64 stddev:27757.21 (622.5%) stddev-sans-first:27802.49 09:19:00 INFO - PROCESS | 1658 | Values: 690.7 736.0 762.8 774.9 816.3 13355.0 15768.5 16450.8 16462.8 16470.5 14829.0 14899.5 15051.0 15059.1 15087.0 896.8 917.5 918.6 920.4 922.7 807.5 868.8 895.9 913.3 925.0 973.1 974.5 974.9 975.6 978.7 958.7 963.9 964.2 965.2 966.0 681.9 705.9 716.8 716.9 719.7 13821.3 13854.6 13858.4 13860.2 13889.0 9827.5 11741.1 11818.3 11929.3 12717.2 10767.0 10773.6 10854.7 11070.6 11226.8 3548.8 3661.9 3720.9 3740.0 3883.3 99576.0 100718.0 100920.0 105296.0 105585.0 8932.7 9081.8 9112.5 9281.0 9621.5 19746.3 19832.5 19882.6 20004.8 20006.2 7669.7 7872.8 7959.4 7981.0 8065.4 4281.8 4438.0 4444.3 4457.1 4460.2 3514.9 3637.9 3639.0 3653.7 3793.7 656.5 686.3 689.8 697.6 708.1 573.3 661.3 666.8 668.2 692.0 8966.4 9368.3 9401.1 9527.9 9592.4 596.8 623.6 625.4 654.8 667.4 3848.7 4149.4 4307.0 4468.7 4960.5 31989.0 41489.5 41707.6 78285.0 143411.0 37353.1 37535.7 37818.4 38105.8 53667.0 72167.0 73049.0 87896.6 92228.0 104813.0 8715.0 8829.6 8885.6 8974.8 9176.9 45160.5 52580.9 53793.4 56757.7 62402.7 86270.0 89639.5 91741.5 94342.0 96514.0 11784.0 11867.7 11918.8 12002.2 12132.3 10426.0 13228.9 13393.3 13459.4 13479.3 9755.7 12068.3 12118.2 12132.5 12478.5 12100.9 12174.8 12228.3 12247.9 12608.5 11938.9 12175.3 12226.9 12529.2 12535.2 9941.3 10408.5 10496.8 10623.4 10707.6 8808.7 10238.3 10294.4 10425.8 10433.6 09:19:00 INFO - PROCESS | 1658 | -------- Summary: end -------- 09:19:00 INFO - PROCESS | 1658 | 09:19:00 INFO - TEST-INFO | 1658: exit 0 09:19:00 INFO - TEST-OK | dromaeo_css | took 1116232ms 09:19:00 INFO - TEST-START | kraken 09:19:00 INFO - operating with platform_type : mac_ 09:19:00 INFO - Initialising browser for kraken test... 09:19:00 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpJHumPM/profile http://localhost:49267/getInfo.html) 09:19:03 INFO - PROCESS | 1666 | 2016-10-14 09:19:03.621 plugin-container[1668:9913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 09:19:03 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:03 INFO - PROCESS | 1666 | 2016-10-14 09:19:03.622 plugin-container[1668:9913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 09:19:03 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:03 INFO - PROCESS | 1666 | 2016-10-14 09:19:03.622 plugin-container[1668:9913] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:03 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 09:19:03 INFO - PROCESS | 1666 | colorDepth:24 09:19:03 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 09:19:03 INFO - PROCESS | 1666 | __metrics 09:19:03 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 09:19:04 INFO - TEST-INFO | 1666: exit 0 09:19:04 INFO - Browser initialized. 09:19:04 INFO - Running cycle 1/1 for kraken test... 09:19:04 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpJHumPM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:06 INFO - PROCESS | 1669 | 2016-10-14 09:19:06.914 plugin-container[1670:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 09:19:06 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:06 INFO - PROCESS | 1669 | 2016-10-14 09:19:06.914 plugin-container[1670:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:19:06 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:06 INFO - PROCESS | 1669 | 2016-10-14 09:19:06.914 plugin-container[1670:10035] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:16 INFO - PROCESS | 1669 | RSS: Main: 237486080 09:19:16 INFO - PROCESS | 1669 | 09:19:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:20 INFO - PROCESS | 1669 | RSS: Main: 249872384 09:19:20 INFO - PROCESS | 1669 | 09:19:22 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 09:19:23 INFO - PROCESS | 1669 | RSS: Main: 257425408 09:19:23 INFO - PROCESS | 1669 | 09:19:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 09:19:27 INFO - PROCESS | 1669 | RSS: Main: 260239360 09:19:27 INFO - PROCESS | 1669 | 09:19:29 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 09:19:30 INFO - PROCESS | 1669 | RSS: Main: 262479872 09:19:30 INFO - PROCESS | 1669 | 09:19:32 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:19:32 INFO - PROCESS | 1669 | RSS: Main: 262991872 09:19:32 INFO - PROCESS | 1669 | 09:19:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 09:19:36 INFO - PROCESS | 1669 | RSS: Main: 263180288 09:19:36 INFO - PROCESS | 1669 | 09:19:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 09:19:39 INFO - PROCESS | 1669 | RSS: Main: 263163904 09:19:39 INFO - PROCESS | 1669 | 09:19:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 09:19:43 INFO - PROCESS | 1669 | RSS: Main: 263618560 09:19:43 INFO - PROCESS | 1669 | 09:19:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:19:45 INFO - PROCESS | 1669 | RSS: Main: 263241728 09:19:45 INFO - PROCESS | 1669 | 09:19:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:19:48 INFO - PROCESS | 1669 | RSS: Main: 263327744 09:19:48 INFO - PROCESS | 1669 | 09:19:50 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:19:51 INFO - PROCESS | 1669 | RSS: Main: 263393280 09:19:51 INFO - PROCESS | 1669 | 09:19:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:19:55 INFO - PROCESS | 1669 | RSS: Main: 263618560 09:19:55 INFO - PROCESS | 1669 | 09:19:57 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:19:58 INFO - PROCESS | 1669 | RSS: Main: 263704576 09:19:58 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 09:20:00 INFO - PROCESS | 1669 | RSS: Main: 265318400 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | __start_tp_report 09:20:00 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 09:20:00 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 09:20:00 INFO - PROCESS | 1669 | |i|pagename|runs| 09:20:00 INFO - PROCESS | 1669 | |0;ai-astar;170;173;137;119;118;118;119;117;120;119 09:20:00 INFO - PROCESS | 1669 | |1;audio-beat-detection;145;115;111;153;113;114;115;114;144;114 09:20:00 INFO - PROCESS | 1669 | |2;audio-dft;219;178;257;272;273;255;193;197;192;192 09:20:00 INFO - PROCESS | 1669 | |3;audio-fft;80;59;58;83;61;52;62;67;104;82 09:20:00 INFO - PROCESS | 1669 | |4;audio-oscillator;86;76;87;72;69;71;89;73;83;84 09:20:00 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;92;93;126;89;93;123;92;90;131;89 09:20:00 INFO - PROCESS | 1669 | |6;imaging-darkroom;96;98;104;131;96;97;96;97;97;96 09:20:00 INFO - PROCESS | 1669 | |7;imaging-desaturate;77;145;141;87;116;90;95;90;88;90 09:20:00 INFO - PROCESS | 1669 | |8;json-parse-financial;56;55;57;56;68;88;91;87;70;56 09:20:00 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;68;73;72;103;71;58;64;72;110;107 09:20:00 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;93;98;109;89;89;92;93;87;102;88 09:20:00 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;162;210;161;161;132;148;146;151;219;162 09:20:00 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;176;175;176;174;170;172;174;171;173;173 09:20:00 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;70;69;69;71;70;69;71;68;70;69 09:20:00 INFO - PROCESS | 1669 | __end_tp_report 09:20:00 INFO - PROCESS | 1669 | __start_cc_report 09:20:00 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,933 09:20:00 INFO - PROCESS | 1669 | __end_cc_report 09:20:00 INFO - PROCESS | 1669 | __startTimestamp1476462000581__endTimestamp 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | ------- Summary: start ------- 09:20:00 INFO - PROCESS | 1669 | Number of tests: 14 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:131.00 Median:119.50 stddev:22.13 (18.5%) stddev-sans-first:18.43 09:20:00 INFO - PROCESS | 1669 | Values: 170.0 173.0 137.0 119.0 118.0 118.0 119.0 117.0 120.0 119.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:123.80 Median:115.00 stddev:16.44 (14.3%) stddev-sans-first:15.55 09:20:00 INFO - PROCESS | 1669 | Values: 145.0 115.0 111.0 153.0 113.0 114.0 115.0 114.0 144.0 114.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:222.80 Median:237.00 stddev:37.45 (15.8%) stddev-sans-first:39.69 09:20:00 INFO - PROCESS | 1669 | Values: 219.0 178.0 257.0 272.0 273.0 255.0 193.0 197.0 192.0 192.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:70.80 Median:64.50 stddev:16.01 (24.8%) stddev-sans-first:16.63 09:20:00 INFO - PROCESS | 1669 | Values: 80.0 59.0 58.0 83.0 61.0 52.0 62.0 67.0 104.0 82.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:83.50 stddev:7.54 (9.0%) stddev-sans-first:7.56 09:20:00 INFO - PROCESS | 1669 | Values: 86.0 76.0 87.0 72.0 69.0 71.0 89.0 73.0 83.0 84.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:101.80 Median:91.00 stddev:17.33 (19.0%) stddev-sans-first:18.01 09:20:00 INFO - PROCESS | 1669 | Values: 92.0 93.0 126.0 89.0 93.0 123.0 92.0 90.0 131.0 89.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:96.50 stddev:10.88 (11.3%) stddev-sans-first:11.40 09:20:00 INFO - PROCESS | 1669 | Values: 96.0 98.0 104.0 131.0 96.0 97.0 96.0 97.0 97.0 96.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:89.00 stddev:23.79 (26.7%) stddev-sans-first:23.46 09:20:00 INFO - PROCESS | 1669 | Values: 77.0 145.0 141.0 87.0 116.0 90.0 95.0 90.0 88.0 90.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:69.00 stddev:14.96 (21.7%) stddev-sans-first:15.18 09:20:00 INFO - PROCESS | 1669 | Values: 56.0 55.0 57.0 56.0 68.0 88.0 91.0 87.0 70.0 56.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:79.80 Median:69.50 stddev:19.15 (27.6%) stddev-sans-first:19.83 09:20:00 INFO - PROCESS | 1669 | Values: 68.0 73.0 72.0 103.0 71.0 58.0 64.0 72.0 110.0 107.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:94.00 Median:90.50 stddev:7.04 (7.8%) stddev-sans-first:7.46 09:20:00 INFO - PROCESS | 1669 | Values: 93.0 98.0 109.0 89.0 89.0 92.0 93.0 87.0 102.0 88.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:165.20 Median:161.50 stddev:27.74 (17.2%) stddev-sans-first:29.40 09:20:00 INFO - PROCESS | 1669 | Values: 162.0 210.0 161.0 161.0 132.0 148.0 146.0 151.0 219.0 162.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:174.00 stddev:2.01 (1.2%) stddev-sans-first:1.90 09:20:00 INFO - PROCESS | 1669 | Values: 176.0 175.0 176.0 174.0 170.0 172.0 174.0 171.0 173.0 173.0 09:20:00 INFO - PROCESS | 1669 | 09:20:00 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 09:20:00 INFO - PROCESS | 1669 | Values: 70.0 69.0 69.0 71.0 70.0 69.0 71.0 68.0 70.0 69.0 09:20:00 INFO - PROCESS | 1669 | -------- Summary: end -------- 09:20:00 INFO - PROCESS | 1669 | 09:20:01 INFO - TEST-INFO | 1669: exit 0 09:20:01 INFO - TEST-OK | kraken | took 60472ms 09:20:01 INFO - SUITE-END | took 1176s 09:20:01 INFO - Completed test suite (00:19:37) 09:20:01 INFO - javascript benchmark 09:20:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7233.606784840801, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3000.7899447038703, 3416.8804091856773, 3447.8965631031047, 3475.6871881530665, 3478.91302717432, 5091.389033316007, 5704.914426283609, 5775.191473193751, 5776.191333213348, 5869.031609784386, 5761.0, 5768.509676677488, 5807.30312362516, 5880.647161170333, 5912.142739302809, 5216.504432078947, 5250.3699556053325, 5323.787048518055, 5337.839864804062, 5416.756245968922, 5320.9467905321035, 5383.219433182206, 5386.676799392029, 5412.052890744104, 5470.699111548864, 5167.715775632339, 5210.6613070150315, 5214.895702085956, 5301.3373328333955, 5323.627346085776, 5230.660007099526, 5248.763805628718, 5268.604854635918, 5279.102552566073, 5289.285946397225, 5255.1329030709885, 5259.737013149327, 5294.485119568213, 5309.65487243328, 5343.599230057761, 4834.274858771178, 5524.475174858382, 5734.655920644775, 5815.15680226369, 5857.150713146612, 6190.411910868505, 6219.751209951596, 6236.345183755686, 6259.123722678829, 6277.152584401093, 6254.21822272216, 6267.2792628847355, 6271.749130034793, 6276.811695649138, 6281.497480201573, 5160.638755287094, 5746.856328591819, 5820.7962721304975, 5920.023196172583, 5952.9107063394085, 6399.744010239631, 6406.327335629785, 6408.1349017881985, 6411.0, 6416.454601358843, 6177.81466556004, 6217.9740342843825, 6219.782307619212, 6223.533235007392, 6226.1905952226825, 6130.979101202072, 6144.525313924626, 6186.1648677428875, 6186.907196392058, 6277.497800175975, 6215.285242197115, 6237.844053898689, 6238.376162383816, 6239.875202495925, 6240.687965601793, 6217.253929528395, 6218.533609979269, 6235.189425374763, 6240.843978900564, 6251.531135164881, 4569.840055598104, 4798.352222449995, 4900.828471003498, 4980.352554167935, 5046.1169295373165, 3638.599754027055, 3773.8301776420126, 4201.558836322142, 4439.711418757835, 4520.367148599199, 5942.465178133989, 6051.425114614105, 6052.727627256784, 6058.545609079249, 6066.726997285133, 6134.846628834315, 6188.566800323934, 6203.534734894811, 6274.278457977391, 6291.905621415683, 4868.41579010521, 5529.866377392625, 5664.688442135722, 5930.0, 5996.4003599640555, 5452.236686863842, 5481.12302031681, 5484.890302193934, 5485.588580856452, 5564.19319198722, 6253.812385628438, 6357.586756860789, 6467.3209313023335, 6502.187226596676, 6513.967430162914, 6170.660613666291, 6237.220347456568, 6253.843653908689, 6329.936700632935, 6340.397662222082, 5455.1544510600925, 6495.123158373748, 6509.121268628674, 6519.869602607826, 6536.379043990813, 6119.082137679384, 6248.468880145057, 6255.812325630239, 6262.248530176408, 6285.151504546828, 6161.476274516716, 6175.84560385994, 6216.564840461305, 6219.160413344141, 6297.118403423433, 5308.654937429054, 6207.410296021871, 6222.408871157233, 6286.74853005893, 6301.40136687014, 6242.625442473466, 6243.219597550306, 6246.625202487865, 6251.093591429309, 6283.622982621057, 5881.264841894763, 5882.117682347682, 6094.055421409688, 6106.2061931948565, 6106.764041036412, 6200.596961197508, 6256.624602523863, 6265.749370025148, 6266.248050234001, 6275.309715931153, 6032.4570788629235, 6038.788642397495, 6165.599236049642, 6183.536234782319, 6211.472024877936], "value": 5737.2081794475225, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.9967866195637, 506.7963586482105, 523.3301374240971, 523.9939316512297, 549.5795716277046, 38994.41508377377, 40949.59050409495, 42116.36825447617, 42526.78736606317, 44941.651750447454, 43871.0, 44091.55908440923, 44527.55472445275, 44672.553274467246, 44739.552604474025, 9623.855642165374, 9761.560729767178, 9818.263630227726, 9821.50892455378, 9853.014698530162, 9741.269404794633, 9790.9510452448, 9828.950855245714, 9838.311318207729, 9845.65540206093, 7776.844463110734, 9782.41305521671, 9815.901840981605, 9819.950900245489, 9849.704508864745, 8967.641294348217, 9225.907740922572, 9729.513524323791, 9766.609335626566, 9767.51162441875, 9692.321537492378, 9726.9513652432, 9774.218062554979, 9785.657501987396, 9803.509824508747, 41568.79215603933, 41926.371104433456, 41989.37015944763, 42431.787841060905, 42463.78768106171, 2861.77105831533, 3397.3375191837604, 3403.864763215916, 3881.844726210948, 4108.794560271975, 31225.0, 31855.52216716751, 31943.680563194303, 32116.0, 32726.6727332726, 3063.417950589381, 3070.17105381548, 3075.6770539093295, 3092.659807421182, 3182.7294679952233, 373337.0, 374192.0, 376982.0, 377634.0, 378068.0, 56083.43916560864, 61376.0, 63104.68447657732, 63725.681371592844, 64369.67815160894, 65881.67059164673, 66363.00455493171, 66491.33771655748, 66772.66613666901, 69153.3084669157, 12856.228626282453, 14293.785028272505, 14348.426062957366, 16515.504534863972, 16668.33326666944, 12791.04067194964, 13002.349882506027, 13011.544595939096, 13039.0, 13050.54323098687, 17624.735628965576, 18077.548061298574, 18144.637107257782, 18256.726149107773, 18273.0, 5354.651947623392, 6162.322144564095, 7063.117110361205, 7095.716171353088, 7097.89353159703, 883.6907082521067, 931.3527098666498, 953.2135987810084, 1011.4689787861284, 1013.8469360289494, 28070.841935306773, 29542.96599619003, 29726.554101688493, 30674.386512270077, 30979.845100774353, 864.628209869762, 911.9088091190828, 1018.6282007067515, 1081.561967403203, 1222.4498975461079, 13068.54260100892, 15810.972286801321, 15868.365265389255, 15904.920475397781, 16162.434314798926, 593168.0, 595550.0, 597796.0, 600966.0, 601364.0, 58254.0, 59468.70265648644, 60213.0, 65135.0, 65226.0, 1415.5965549818252, 1422.210673076432, 1426.479335042702, 1438.0796290374378, 1443.5524987253984, 25563.872180638977, 31128.377432450772, 32245.0, 32448.268449128653, 33473.991560506445, 53272.73363633157, 53536.73231633973, 53776.0, 53779.0, 53787.0, 585596.0, 590282.4372903144, 594497.0, 594772.0, 597629.011854938, 26332.341691457867, 26390.472190555698, 26651.733482665702, 26785.732142678324, 26847.328816779736, 47750.52249477461, 48087.27869082036, 48207.553773386855, 48716.512834871195, 48794.512054879, 39348.409773853986, 39399.80300098481, 39539.40690889696, 40198.0, 40790.0, 39051.80474097611, 39428.40857387084, 40241.59758402496, 40538.79730601446, 40726.38910416405, 37980.0, 38256.617433825304, 38307.04232394212, 38842.417363739, 39149.80425097857, 34079.4888076684, 34820.30359392849, 35276.0, 35490.46764298486, 35524.0, 25704.357391065372, 25792.226233213034, 25971.74028259769, 26384.208473745817, 26576.468470630094], "value": 20018.66115606915, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.5887029121559, 353.69405464273393, 358.55001972524485, 376.9038895081754, 390.3130490337008, 18868.62262754744, 19305.806941930576, 19371.225150993945, 20519.179232830706, 20741.377758667226, 14914.850851491481, 19803.801961980374, 19847.007649617535, 20328.288509902155, 20361.389158325273, 5597.300337457828, 5685.573581981347, 5703.230063941356, 5757.107648314518, 5827.475527202551, 4886.804527818883, 5365.114756065244, 5615.101583746601, 5693.6904511962275, 5733.770649174028, 5555.805546805863, 5601.971990140044, 5673.375928647866, 5679.914801277984, 5683.545316374701, 5456.508914197721, 5620.185073164409, 5708.172315014342, 5738.340090889559, 5741.684207368593, 4759.809607615691, 4982.87542811429, 5007.349044624212, 5009.649324547283, 5014.498550144993, 22093.005814738375, 23126.0, 24469.63295550568, 24630.261092167264, 25049.373765655822, 2222.4888275696517, 2348.471593891378, 2550.375158086262, 2638.8680565971817, 2778.458200650874, 14548.908831837549, 14981.850181498154, 15368.0, 15392.999455035388, 15469.845301546953, 1785.1252886085854, 1834.1471215884678, 1856.7679040119986, 1865.2445759467437, 1921.4523860699633, 89879.55060224657, 111834.0, 115608.0, 116403.0, 119008.40495797552, 8913.365603082064, 9887.912329643734, 10239.7440063999, 10483.004114609252, 10875.891241087646, 12792.6801829955, 12847.871521284855, 12859.614211573667, 12868.935655321664, 12931.676708082374, 7916.643751031217, 8204.548749818818, 8225.629846656915, 8322.0, 8331.250187483156, 8210.3021243195, 8211.58942052895, 8245.711400101087, 8344.749657510285, 8412.242898139197, 6318.873622527523, 6483.481321494363, 6486.44865186455, 6556.541042127101, 6748.358905903931, 1543.2746609093708, 1825.2151574822688, 1835.4952388093382, 1843.410108765209, 1847.325726109987, 904.9230815380766, 953.1040821627648, 957.4494665567327, 996.3772642098688, 1009.6819501856892, 15790.946896993082, 20660.483487912923, 21406.0, 21524.56950860974, 21654.675179872316, 981.4601968917067, 987.5358581466843, 1020.7805321855836, 1028.3983869436463, 1078.6925726168004, 10219.948900255451, 10678.092362149304, 11276.32342059479, 11456.541738330374, 11515.827262591069, 592600.0, 597136.0, 597952.0, 607350.0, 608824.0, 93610.5319473412, 93987.0, 100817.49591251997, 112285.0, 121278.0, 793.2464159048926, 967.8209531236603, 1002.1481740520645, 1003.6838395905266, 1008.9091981721533, 18245.726314105035, 18398.540036499195, 18522.166502507687, 18593.628127437645, 18631.72052419188, 55808.72095639496, 56191.87616247734, 56499.71750141388, 57051.14423283571, 58064.70967645135, 80829.78755318793, 81149.59425202836, 85194.14805851862, 85286.0, 85356.0, 6261.874762504815, 7491.175970643334, 7722.0, 7824.765257042298, 7846.5292082475235, 8212.466189697889, 11211.943940280247, 11864.940675296568, 12120.575779848015, 12243.387830608612, 8868.600912958804, 9920.156786673213, 10124.240681948737, 10576.524056417325, 10844.566217351217, 10558.155347572329, 10709.571617135227, 10725.892741072801, 10802.67591972242, 10975.890241097486, 10657.360558366523, 10774.299670521392, 10780.29928054674, 10990.230683852373, 11167.162462815484, 7889.3294070004695, 7903.960480197792, 7921.128675845537, 8023.558704271321, 8130.552819594979, 8804.559772011246, 9499.952500237454, 10515.316504427188, 10745.570177192825, 10838.349699018085], "value": 9414.831782565354, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1530.4643374818816, 1547.6517783498714, 1746.9430994248487, 1788.8551327150617, 1838.5495553589374, 7708.845823083535, 7994.520328780277, 8209.138040505743, 8700.99938507071, 8706.99869515006, 8206.91793082069, 8471.15288471154, 8524.957375213131, 8621.267192288647, 8626.439281446701, 2291.873946932918, 2315.525317309956, 2322.198841399714, 2322.2568777990964, 2351.024324905171, 2296.3455415206586, 2309.5727290451237, 2339.3099035784485, 2341.7658234176615, 2344.7069116360453, 2308.480591866825, 2311.8556314624284, 2325.767423257678, 2329.04509151248, 2345.718513778349, 2262.0951619352227, 2290.633498640218, 2291.46150654596, 2299.85050971688, 2313.3060081975423, 2246.483308838976, 2254.864708117502, 2263.0834512022657, 2265.2071774878827, 2269.1830940861278, 8450.915490845136, 8470.237678608955, 8586.141385861363, 8602.698905538276, 8671.479711217347, 5095.210242412395, 5256.316678831766, 5260.842174734764, 5262.105442074857, 5269.947300527022, 3049.786514943955, 3286.4248756467523, 3348.983255083709, 3431.897043088711, 3479.1650003998857, 937.5546615357719, 950.9476978766165, 955.2930831184942, 956.0726095687241, 959.9376040557411, 9511.904880951102, 10934.617288394868, 11022.559097636085, 11047.226694131414, 11054.0051395374, 3723.3298006358573, 4139.606737359946, 4316.136772645484, 4661.790219440133, 4695.272232803921, 8300.584970751439, 8762.342824288204, 8777.253933415603, 8805.515696636618, 8823.602937867812, 4507.459104907432, 4886.364772579613, 5091.41448733393, 5128.3333166688835, 5130.9230361544605, 3938.724289299779, 4301.65586753059, 4452.332150177434, 4614.515475875052, 4756.167670657621, 3606.1345277133823, 3635.2911182319194, 3654.2691461707764, 3739.439084137401, 3781.3193625147187, 970.3304719743354, 1136.198979719296, 1139.5783560082823, 1150.1891166727437, 1150.8906653867868, 804.5977011494252, 859.7893516088474, 876.5792419638609, 935.5789894547481, 958.6884262614539, 5799.0, 5915.438033386821, 5973.581849270509, 6099.664518451439, 6139.846503837351, 938.8263171313329, 947.516766449116, 958.7986522830145, 963.3786207895868, 993.2054356514759, 3893.3186692329296, 4003.610630414071, 4051.1972172383007, 4085.0400155963325, 4086.9986853220557, 10033.19734421259, 10379.636712715019, 10380.584776609003, 10406.219533534864, 10442.63450779219, 9622.037796220322, 10058.547365368577, 10069.539916712036, 10224.744381390376, 10392.480375981171, 7750.612469376509, 7927.643256053491, 7961.800954976172, 7966.800829979297, 7992.000999875016, 5058.519440653279, 5783.508401785895, 5792.247007888947, 5854.970725146347, 6137.723802428991, 9444.53609690499, 9480.05199480074, 9593.856092158483, 9616.567254473706, 9652.2278217741, 9091.590878410356, 9406.153446189875, 9433.433993960385, 9442.0, 9575.808483830424, 6684.532082754161, 6910.481713871581, 7125.5724656518705, 7203.603801790951, 7304.1235051792, 7910.7231246906085, 8189.262966333059, 8219.383546233938, 8238.917610823815, 8244.587770611326, 7212.603306818175, 7383.593902335208, 7386.815329616803, 7413.8146546336775, 7465.253474652492, 7204.135503739585, 7218.639068046472, 7232.855342893007, 7303.780886573411, 7334.816629584303, 5515.869246804476, 6524.706388212447, 6882.621455819978, 6890.0, 6898.379145876896, 4945.085159245551, 4969.577585905094, 4991.101601711582, 5026.0, 5076.010178015252, 4222.303319952173, 4907.993861258362, 4934.950650493449, 5004.099262132851, 5126.410462796677], "value": 4415.415058083231, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.28122585116088, 303.27517233811193, 303.33418147166964, 306.35817961370935, 308.876449420232, 4335.436393268875, 5551.252130195554, 5630.887382252353, 5651.632643878145, 5657.632253903494, 5037.5214354636255, 5407.5133238008575, 5446.945530544694, 5474.39781624021, 5533.280673512448, 3959.6832253419657, 4060.533038700543, 4103.733257338264, 4124.525679546846, 4183.037901282707, 4148.564400737924, 4154.192926077151, 4172.687048471362, 4192.5597812229735, 4206.768627725474, 3978.482797236355, 4128.545859955418, 4137.234611596849, 4170.687198460112, 4200.222958752625, 3905.3165696003084, 4037.6971727120435, 4118.5263694674895, 4134.669226461906, 4205.285101532747, 3546.4680297955256, 3561.8753343632848, 3565.680698141678, 3566.982165089184, 3581.534400527915, 4713.316569097462, 5234.97382513085, 5288.2596436498925, 5303.0, 5312.423474614848, 3825.9234815303817, 4270.4234928284595, 4701.849760660366, 4763.64272679547, 4791.664583479123, 5230.764615592269, 5724.4561766632105, 5829.912551311733, 5832.0, 5843.240378750777, 3807.7905715185652, 3963.1083006323347, 3990.7206495545324, 4003.019260281221, 4013.237484877893, 5298.258243845865, 5678.517326027292, 5820.5343572513675, 6012.745220542297, 6143.662098584576, 3231.628362738268, 3683.0, 3898.434726964574, 3960.5049368828895, 3972.781497017634, 3706.3328600851564, 4015.056461731491, 4044.9797751011056, 4054.2904991626347, 4057.736247143926, 4693.825413669766, 4767.523247675204, 4769.403824521934, 4770.403699537558, 4772.6420518461255, 5068.949310506848, 5755.769769209269, 5795.362510123883, 5847.415258474203, 5910.911336329958, 1295.2811189789568, 1361.2779833510617, 1364.774812156003, 1367.746966811143, 1370.397025308861, 737.7122922060294, 766.7393086350668, 831.2394159344133, 837.8575642140851, 842.2966822703112, 4664.55686709762, 4732.834350797705, 4904.901901961941, 5244.9737751310995, 5304.92042619361, 5225.686458812407, 5887.381824908409, 6101.572889897753, 6104.542159338067, 6110.236220472441, 4806.423229212517, 4980.327655766425, 5421.834305624309, 5562.721863906789, 5633.605647604629, 2994.296340360014, 3092.1651154188244, 3154.6056742907135, 3155.8106513609255, 3173.825439600798, 5253.0134920555565, 5518.503334699896, 6273.529485288622, 6279.968600157062, 6323.841903952438, 5590.776368945197, 5855.736491857877, 6065.302490213682, 6079.422454866781, 6171.783987560503, 6183.783567575203, 6227.626342419469, 6266.780662676784, 6300.495960323072, 6324.367563243639, 5065.4174769900455, 5420.647657902224, 5960.8807823844145, 5995.340512543704, 6023.241639105058, 5384.923015396858, 5496.8731410060045, 5961.0, 5994.850128746816, 6007.8197654070445, 5337.653052551572, 6237.937620623736, 6239.594426362272, 6249.093881387264, 6295.150154729053, 3589.4974703541, 3823.598522155245, 3844.8462061518344, 3967.325554655658, 3981.183857309187, 5106.259592359162, 5599.552035837041, 5794.449527294901, 6108.419700128481, 6138.723757431016, 4894.363732714724, 5498.5876059294915, 5501.817109321515, 5779.797707080232, 5937.673427961504, 4654.0, 5150.845474635767, 5554.972330118941, 5715.971125197504, 5959.212236329171, 4647.512011238907, 5761.654300741969, 5786.07422812348, 5804.738786754522, 5832.766689332379, 5242.951409717995, 5459.317585301837, 5541.251930989265, 5853.736581853742, 5919.253820122988, 5789.421057894177, 5798.202177573696, 5800.854978625568, 5857.882842343214, 5879.7060146993335], "value": 4207.405484569049, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.7064497588525, 735.9595222262777, 762.7597306848346, 774.8721460958939, 816.2735165702526, 13355.0, 15768.526944191664, 16450.75323870143, 16462.753058704115, 16470.50588482346, 14829.0, 14899.478518251837, 15051.0, 15059.096454212755, 15087.01934374265, 896.7802888292377, 917.4541148016953, 918.6417297254076, 920.3971398733806, 922.6632279218104, 807.4549678966704, 868.7741187291318, 895.9104089591021, 913.2830727878617, 925.0287198441644, 973.0561355485167, 974.5322245322214, 974.8878878928944, 975.5805003848342, 978.7357413498352, 958.702802131338, 963.8987906269881, 964.2045312617118, 965.2422848064236, 966.038791402552, 681.9147606549182, 705.9223485416651, 716.7849645106447, 716.9390601798854, 719.7229066809264, 13821.308934553334, 13854.584362469142, 13858.4456621735, 13860.237686927216, 13889.0, 9827.459489728062, 11741.119416043832, 11818.349990750505, 11929.343886086263, 12717.173383730027, 10767.030967212988, 10773.622923197729, 10854.72863178419, 11070.612528561462, 11226.775464490664, 3548.840302186382, 3661.8901432957055, 3720.920932929447, 3739.9626003739886, 3883.320418926705, 99576.0, 100718.0, 100920.0, 105296.0, 105585.0, 8932.732018039469, 9081.77295567603, 9112.544372781467, 9281.0, 9621.470819105016, 19746.30887918916, 19832.50418739543, 19882.60234795296, 20004.799952000583, 20006.199752009758, 7669.7315593953945, 7872.842543149105, 7959.403044771665, 7981.0, 8065.395095367753, 4281.764502952367, 4437.955620443754, 4444.288913773846, 4457.086297309044, 4460.1971645104195, 3514.9297014059575, 3637.890863274106, 3638.9818050909576, 3653.6894363978827, 3793.729100751279, 656.5404217048085, 686.3136863136863, 689.7551369263839, 697.6407150317591, 708.1077841919218, 573.2891214893502, 661.262692098307, 666.8491378237787, 668.2415458454689, 691.9899184227414, 8966.427122167612, 9368.250539956924, 9401.10689484498, 9527.857082143773, 9592.376495527767, 596.8182997664649, 623.5977794322637, 625.4433554136822, 654.8012956706428, 667.3760034367882, 3848.749831260959, 4149.385807754346, 4307.0, 4468.687191896536, 4960.479149689303, 31989.04032879017, 41489.54786582455, 41707.58292417098, 78285.0, 143411.0, 37353.132343383266, 37535.744995700625, 37818.43272350917, 38105.80947095247, 53667.0, 72166.99999999895, 73049.0, 87896.56051719829, 92228.0, 104813.0, 8714.956425217833, 8829.64681412762, 8885.555722213992, 8974.820503590023, 9176.908230917605, 45160.54839451564, 52580.9483810314, 53793.38619841411, 56757.71621141868, 62402.68798655977, 86270.0, 89639.51802409985, 91741.54129229311, 94342.0, 96514.00546920797, 11784.0, 11867.703307417383, 11918.761624767629, 12002.1598488105, 12132.272063676208, 10426.0, 13228.933855330662, 13393.263370514325, 13459.394327255493, 13479.326033698471, 9755.658551950648, 12068.275903445821, 12118.151729379211, 12132.514699411924, 12478.500859965863, 12100.878991210328, 12174.794123198113, 12228.266304021787, 12247.938760306142, 12608.49566017349, 11938.880611193777, 12175.33035683046, 12226.87773122293, 12529.185602936144, 12535.185212961129, 9941.30410871232, 10408.49526432926, 10496.79006419852, 10623.36259824413, 10707.571697132027, 8808.74143869524, 10238.33450825694, 10294.3823370598, 10425.843612345669, 10433.582656693647], "value": 7130.984888856217, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 137.0, 119.0, 118.0, 118.0, 119.0, 117.0, 120.0, 119.0], "value": 131.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 111.0, 153.0, 113.0, 114.0, 115.0, 114.0, 144.0, 114.0], "value": 123.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 178.0, 257.0, 272.0, 273.0, 255.0, 193.0, 197.0, 192.0, 192.0], "value": 222.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 83.0, 61.0, 52.0, 62.0, 67.0, 104.0, 82.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 76.0, 87.0, 72.0, 69.0, 71.0, 89.0, 73.0, 83.0, 84.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 126.0, 89.0, 93.0, 123.0, 92.0, 90.0, 131.0, 89.0], "value": 101.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 104.0, 131.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 145.0, 141.0, 87.0, 116.0, 90.0, 95.0, 90.0, 88.0, 90.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 56.0, 68.0, 88.0, 91.0, 87.0, 70.0, 56.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 72.0, 103.0, 71.0, 58.0, 64.0, 72.0, 110.0, 107.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 98.0, 109.0, 89.0, 89.0, 92.0, 93.0, 87.0, 102.0, 88.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 210.0, 161.0, 161.0, 132.0, 148.0, 146.0, 151.0, 219.0, 162.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 175.0, 176.0, 174.0, 170.0, 172.0, 174.0, 171.0, 173.0, 173.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 71.0, 70.0, 69.0, 71.0, 68.0, 70.0, 69.0], "value": 69.6, "unit": "score"}]}]} 09:20:01 INFO - Return code: 0 09:20:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:01 ERROR - # TBPL SUCCESS # 09:20:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:01 INFO - 2016-10-14 09:20:01.938 screenresolution[1674:10418] starting screenresolution argv=screenresolution get 09:20:01 INFO - 2016-10-14 09:20:01.941 screenresolution[1674:10418] Display 0: 1600x1200x32@60 09:20:01 INFO - 2016-10-14 09:20:01.946 screenresolution[1675:10421] starting screenresolution argv=screenresolution list 09:20:01 INFO - Available Modes on Display 0 09:20:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:02 INFO - Intel Iris: 09:20:02 INFO - Chipset Model: Intel Iris 09:20:02 INFO - Type: GPU 09:20:02 INFO - Bus: Built-In 09:20:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:02 INFO - Vendor: Intel (0x8086) 09:20:02 INFO - Device ID: 0x0a2e 09:20:02 INFO - Revision ID: 0x0009 09:20:02 INFO - Displays: 09:20:02 INFO - Display: 09:20:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:02 INFO - Display Serial Number: 1600x1200 60 09:20:02 INFO - Main Display: Yes 09:20:02 INFO - Mirror: Off 09:20:02 INFO - Online: Yes 09:20:02 INFO - Rotation: Supported 09:20:02 INFO - Return code: 0 09:20:02 INFO - Running post-action listener: _resource_record_post_action 09:20:02 INFO - [mozharness: 2016-10-14 16:20:02.187849Z] Finished run-tests step (success) 09:20:02 INFO - Running post-run listener: _resource_record_post_run 09:20:02 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38635520; Write bytes: 711561216; Read time: 246; Write time: 1979 09:20:02 INFO - TinderboxPrint: CPU usage
54.6% 09:20:02 INFO - TinderboxPrint: I/O read bytes / time
38,635,520 / 246 09:20:02 INFO - TinderboxPrint: I/O write bytes / time
711,561,216 / 1,979 09:20:02 INFO - TinderboxPrint: CPU idle
2,202.6 (45.4%) 09:20:02 INFO - TinderboxPrint: CPU system
984.7 (20.3%) 09:20:02 INFO - TinderboxPrint: CPU user
1,666.5 (34.3%) 09:20:02 INFO - TinderboxPrint: Swap in / out
446,607,360 / 0 09:20:02 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 413452288; Write bytes: 410150400; Read time: 31723; Write time: 837 09:20:02 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 30416896; Write bytes: 300055040; Read time: 178; Write time: 1137 09:20:02 INFO - Running post-run listener: _upload_blobber_files 09:20:02 INFO - Blob upload gear active. 09:20:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:02 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 09:20:02 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:02 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:20:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:03 INFO - (blobuploader) - INFO - Done attempting. 09:20:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:20:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:20:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:05 INFO - (blobuploader) - INFO - Done attempting. 09:20:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:07 INFO - (blobuploader) - INFO - Done attempting. 09:20:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:07 INFO - Return code: 0 09:20:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d1552203022f9cb03d33302bdeb40b3f516c8c97a5363b3e69362ee60e7a930b8b52f2101ce91af2b82fda5b2376f0afd26555b5544401d000a134b5fe316a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d360abfb3b396f7a3e7e56e36620748977acc6cb3b45c069a156a957db0a7560c236e09cceac93405bdc234c2f7764bdffb200bfc4ce4cb3099efc196bc7549", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2531e7af1db97afab816edbf1e385acf1103bd13b06cc19c079666155e1c6252018471d3dc3a90d1c4d82542883d557be24a949c45bc26331338fbf2aff5062"} 09:20:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:07 INFO - Contents: 09:20:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d1552203022f9cb03d33302bdeb40b3f516c8c97a5363b3e69362ee60e7a930b8b52f2101ce91af2b82fda5b2376f0afd26555b5544401d000a134b5fe316a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d360abfb3b396f7a3e7e56e36620748977acc6cb3b45c069a156a957db0a7560c236e09cceac93405bdc234c2f7764bdffb200bfc4ce4cb3099efc196bc7549", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2531e7af1db97afab816edbf1e385acf1103bd13b06cc19c079666155e1c6252018471d3dc3a90d1c4d82542883d557be24a949c45bc26331338fbf2aff5062"} 09:20:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:07 INFO - Copying logs to upload dir... 09:20:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.387922 ========= master_lag: 2.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-14 09:20:09.780065) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:09.787584) ========= 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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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/Firefox/sha512/3d1552203022f9cb03d33302bdeb40b3f516c8c97a5363b3e69362ee60e7a930b8b52f2101ce91af2b82fda5b2376f0afd26555b5544401d000a134b5fe316a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d360abfb3b396f7a3e7e56e36620748977acc6cb3b45c069a156a957db0a7560c236e09cceac93405bdc234c2f7764bdffb200bfc4ce4cb3099efc196bc7549", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2531e7af1db97afab816edbf1e385acf1103bd13b06cc19c079666155e1c6252018471d3dc3a90d1c4d82542883d557be24a949c45bc26331338fbf2aff5062"} build_url:https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011169 build_url: 'https://queue.taskcluster.net/v1/task/aRl1RoL9R-aO9hN9l3WOWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d1552203022f9cb03d33302bdeb40b3f516c8c97a5363b3e69362ee60e7a930b8b52f2101ce91af2b82fda5b2376f0afd26555b5544401d000a134b5fe316a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d360abfb3b396f7a3e7e56e36620748977acc6cb3b45c069a156a957db0a7560c236e09cceac93405bdc234c2f7764bdffb200bfc4ce4cb3099efc196bc7549", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2531e7af1db97afab816edbf1e385acf1103bd13b06cc19c079666155e1c6252018471d3dc3a90d1c4d82542883d557be24a949c45bc26331338fbf2aff5062"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:09.822603) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:09.822928) ========= 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.MpVO6lbrLj/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.f8iSbpwPyu/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.005387 ========= master_lag: 0.61 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:10.437094) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 09:20:10.437409) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 09:20:10.437827) ========= ========= Total master_lag: 2.85 =========