builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0273 starttime: 1485364920.98 results: success (0) buildid: 20170125065947 builduid: bca17e3c41cb43258759a3448bff9e46 revision: 2cea7106779920aef1eb4572ed9ae9b63939a928 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:00.985483) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:00.986338) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:00.986860) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:01.013794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:01.014541) ========= 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.iZRHUknhOL/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.myHw0WCc4R/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 --2017-01-25 09:22:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2017-01-25 09:22:01 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131557 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:01.171272) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:01.171592) ========= 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.iZRHUknhOL/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.myHw0WCc4R/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.025512 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:01.220123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 09:22:01.220471) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2cea7106779920aef1eb4572ed9ae9b63939a928 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2cea7106779920aef1eb4572ed9ae9b63939a928 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iZRHUknhOL/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.myHw0WCc4R/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 2017-01-25 09:22:01,327 truncating revision to first 12 chars 2017-01-25 09:22:01,327 Setting DEBUG logging. 2017-01-25 09:22:01,327 attempt 1/10 2017-01-25 09:22:01,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2cea71067799?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-25 09:22:02,427 unpacking tar archive at: mozilla-beta-2cea71067799/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359406 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 09:22:02.604245) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:02.604578) ========= script_repo_revision: 2cea7106779920aef1eb4572ed9ae9b63939a928 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:02.604956) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:02.605220) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 09:22:02.625130) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-25 09:22:02.625541) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iZRHUknhOL/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.myHw0WCc4R/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:02 INFO - MultiFileLogger online at 20170125 09:22:02 in /builds/slave/test 09:22:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:22:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:22:02 INFO - {'append_to_log': False, 09:22:02 INFO - 'base_work_dir': '/builds/slave/test', 09:22:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:22:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:22:02 INFO - 'branch': 'Mozilla-Beta', 09:22:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:22:02 INFO - 'config_files': ('talos/mac_config.py',), 09:22:02 INFO - 'default_actions': ('clobber', 09:22:02 INFO - 'read-buildbot-config', 09:22:02 INFO - 'download-and-extract', 09:22:02 INFO - 'populate-webroot', 09:22:02 INFO - 'create-virtualenv', 09:22:02 INFO - 'install', 09:22:02 INFO - 'run-tests'), 09:22:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:22:02 INFO - 'download_minidump_stackwalk': True, 09:22:02 INFO - 'download_symbols': 'ondemand', 09:22:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:22:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:22:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:22:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:22:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:22:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:22:02 INFO - 'installer_path': 'installer.exe', 09:22:02 INFO - 'log_level': 'info', 09:22:02 INFO - 'log_name': 'talos', 09:22:02 INFO - 'log_to_console': True, 09:22:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:22:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:22:02 INFO - 'opt_config_files': (), 09:22:02 INFO - 'pip_index': False, 09:22:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:22:02 INFO - 'cmd': ('bash', 09:22:02 INFO - '-c', 09:22:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:22:02 INFO - 'enabled': True, 09:22:02 INFO - 'halt_on_failure': False, 09:22:02 INFO - 'name': 'check_screen_resolution'},), 09:22:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:22:02 INFO - 'cmd': ('bash', 09:22:02 INFO - '-c', 09:22:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:22:02 INFO - 'enabled': True, 09:22:02 INFO - 'halt_on_failure': False, 09:22:02 INFO - 'name': 'check_screen_resolution'},), 09:22:02 INFO - 'run_cmd_checks_enabled': True, 09:22:02 INFO - 'sps_profile': False, 09:22:02 INFO - 'sps_profile_interval': 0, 09:22:02 INFO - 'suite': 'dromaeojs-e10s', 09:22:02 INFO - 'system_bits': '32', 09:22:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:22:02 INFO - 'title': 't-yosemite-r7-0273', 09:22:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:22:02 INFO - 'use_talos_json': True, 09:22:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:22:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:22:02 INFO - 'work_dir': 'build'} 09:22:02 INFO - [mozharness: 2017-01-25 17:22:02.840658Z] Running clobber step. 09:22:02 INFO - Running pre-action listener: _resource_record_pre_action 09:22:02 INFO - Running main action method: clobber 09:22:02 INFO - rmtree: /builds/slave/test/build 09:22:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:22:05 INFO - Running post-action listener: _resource_record_post_action 09:22:05 INFO - [mozharness: 2017-01-25 17:22:05.248265Z] Finished clobber step (success) 09:22:05 INFO - [mozharness: 2017-01-25 17:22:05.248416Z] Running read-buildbot-config step. 09:22:05 INFO - Running pre-action listener: _resource_record_pre_action 09:22:05 INFO - Running main action method: read_buildbot_config 09:22:05 INFO - Using buildbot properties: 09:22:05 INFO - { 09:22:05 INFO - "project": "", 09:22:05 INFO - "product": "firefox", 09:22:05 INFO - "script_repo_revision": "production", 09:22:05 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:22:05 INFO - "repository": "", 09:22:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:22:05 INFO - "buildid": "20170125065947", 09:22:05 INFO - "pgo_build": "False", 09:22:05 INFO - "basedir": "/builds/slave/test", 09:22:05 INFO - "buildnumber": 51, 09:22:05 INFO - "slavename": "t-yosemite-r7-0273", 09:22:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:22:05 INFO - "platform": "yosemite_r7", 09:22:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:22:05 INFO - "branch": "mozilla-beta", 09:22:05 INFO - "repo_path": "releases/mozilla-beta", 09:22:05 INFO - "revision": "2cea7106779920aef1eb4572ed9ae9b63939a928", 09:22:05 INFO - "stage_platform": "macosx64", 09:22:05 INFO - "builduid": "bca17e3c41cb43258759a3448bff9e46", 09:22:05 INFO - "slavebuilddir": "test" 09:22:05 INFO - } 09:22:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 09:22:05 INFO - Running post-action listener: _resource_record_post_action 09:22:05 INFO - [mozharness: 2017-01-25 17:22:05.251764Z] Finished read-buildbot-config step (success) 09:22:05 INFO - [mozharness: 2017-01-25 17:22:05.251872Z] Running download-and-extract step. 09:22:05 INFO - Running pre-action listener: _resource_record_pre_action 09:22:05 INFO - Running main action method: download_and_extract 09:22:05 INFO - mkdir: /builds/slave/test/build/tests 09:22:05 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:22:05 INFO - trying https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 09:22:05 INFO - Downloading https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:22:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 09:22:06 INFO - Downloaded 1165 bytes. 09:22:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:22:06 INFO - Using the following test package requirements: 09:22:06 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:22:06 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:22:06 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 09:22:06 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:22:06 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 09:22:06 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:22:06 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:22:06 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 09:22:06 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:22:06 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:22:06 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 09:22:06 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:22:06 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 09:22:06 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:22:06 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 09:22:06 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:22:06 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 09:22:06 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 09:22:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:22:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:22:06 INFO - Fetch https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:22:06 INFO - Http code: 200 09:22:06 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 09:22:06 INFO - x-amz-cf-id: yYXyAwNhWVaJQMv2lDzDfWlwr9M7PkfADqNomHKjiyyhm9VNAgX6iA== 09:22:06 INFO - x-amz-version-id: .bqQTauSsxoENxCOfrfbmL8RpDNJ8gzM 09:22:07 INFO - Expected file size: 16086213 09:22:07 INFO - Obtained file size: 16086213 09:22:13 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:22:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:22:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:22:13 INFO - Fetch https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:22:14 INFO - Http code: 200 09:22:14 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 09:22:14 INFO - x-amz-cf-id: D7b83n5EBDRh145zC7bkLBOf4ejAVqlf3HqjVMYrHrb3GuCoWVl1bA== 09:22:14 INFO - x-amz-version-id: .bqQTauSsxoENxCOfrfbmL8RpDNJ8gzM 09:22:14 INFO - Expected file size: 16086213 09:22:14 INFO - Obtained file size: 16086213 09:22:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 09:22:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 09:22:20 INFO - Fetch https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 09:22:22 INFO - Http code: 200 09:22:22 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 09:22:22 INFO - x-amz-cf-id: PBX9yt8mb29D0xypmV953WrmwWaoGheE6zY1sV3sNSammR8VqtdNgA== 09:22:22 INFO - x-amz-version-id: Z.u804wweO4gHhBe4QWSBT4QRPlf4emF 09:22:22 INFO - Expected file size: 14402992 09:22:22 INFO - Obtained file size: 14402992 09:22:22 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:22:22 INFO - trying https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:22:22 INFO - Downloading https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 09:22:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 09:22:25 INFO - Downloaded 82124699 bytes. 09:22:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:22:25 INFO - mkdir: /builds/slave/test/properties 09:22:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:22:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:22:25 INFO - Contents: 09:22:25 INFO - build_url:https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:22:25 INFO - Running post-action listener: _resource_record_post_action 09:22:25 INFO - Running post-action listener: set_extra_try_arguments 09:22:25 INFO - [mozharness: 2017-01-25 17:22:25.759537Z] Finished download-and-extract step (success) 09:22:25 INFO - [mozharness: 2017-01-25 17:22:25.759688Z] Running populate-webroot step. 09:22:25 INFO - Running pre-action listener: _resource_record_pre_action 09:22:25 INFO - Running main action method: populate_webroot 09:22:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:22:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:22:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:22:25 INFO - '--tppagecycles', 09:22:25 INFO - '7'], 09:22:25 INFO - 'tests': ['tsvgm']}}, 09:22:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:22:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:22:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:22:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:22:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['damp', 'tps']}, 09:22:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'tests': ['damp', 'tps']}, 09:22:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['dromaeo_dom']}, 09:22:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:22:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['basic_compositor_video']}, 09:22:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:22:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['a11yr', 09:22:25 INFO - 'ts_paint', 09:22:25 INFO - 'tpaint', 09:22:25 INFO - 'sessionrestore', 09:22:25 INFO - 'sessionrestore_no_auto_restore', 09:22:25 INFO - 'tabpaint']}, 09:22:25 INFO - 'other-e10s': {'tests': ['a11yr', 09:22:25 INFO - 'ts_paint', 09:22:25 INFO - 'tpaint', 09:22:25 INFO - 'sessionrestore', 09:22:25 INFO - 'sessionrestore_no_auto_restore', 09:22:25 INFO - 'tabpaint']}, 09:22:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:22:25 INFO - 'ts_paint', 09:22:25 INFO - 'tpaint', 09:22:25 INFO - 'sessionrestore', 09:22:25 INFO - 'sessionrestore_no_auto_restore', 09:22:25 INFO - 'tabpaint']}, 09:22:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:22:25 INFO - 'ts_paint', 09:22:25 INFO - 'tpaint', 09:22:25 INFO - 'sessionrestore', 09:22:25 INFO - 'sessionrestore_no_auto_restore', 09:22:25 INFO - 'tabpaint']}, 09:22:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['a11yr', 09:22:25 INFO - 'ts_paint', 09:22:25 INFO - 'tpaint', 09:22:25 INFO - 'sessionrestore', 09:22:25 INFO - 'sessionrestore_no_auto_restore', 09:22:25 INFO - 'tabpaint']}, 09:22:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['a11yr', 09:22:25 INFO - 'ts_paint', 09:22:25 INFO - 'tpaint', 09:22:25 INFO - 'sessionrestore', 09:22:25 INFO - 'sessionrestore_no_auto_restore', 09:22:25 INFO - 'tabpaint']}, 09:22:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['tsvgx', 09:22:25 INFO - 'tsvgr_opacity', 09:22:25 INFO - 'tart', 09:22:25 INFO - 'tscrollx', 09:22:25 INFO - 'cart']}, 09:22:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:22:25 INFO - 'tsvgr_opacity', 09:22:25 INFO - 'tart', 09:22:25 INFO - 'tscrollx', 09:22:25 INFO - 'cart']}, 09:22:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'talos_options': ['--disable-e10s'], 09:22:25 INFO - 'tests': ['tp5o']}, 09:22:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'tests': ['tp5o']}, 09:22:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'talos_options': ['--disable-e10s', 09:22:25 INFO - '--xperf_path', 09:22:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:25 INFO - 'tests': ['tp5n']}, 09:22:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:25 INFO - 'talos_options': ['--xperf_path', 09:22:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:25 INFO - 'tests': ['tp5n']}}, 09:22:25 INFO - 'talos.zip': {'path': '', 09:22:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:22:25 INFO - Running post-action listener: _resource_record_post_action 09:22:25 INFO - [mozharness: 2017-01-25 17:22:25.778963Z] Finished populate-webroot step (success) 09:22:25 INFO - [mozharness: 2017-01-25 17:22:25.779075Z] Running create-virtualenv step. 09:22:25 INFO - Running pre-action listener: _resource_record_pre_action 09:22:25 INFO - Running main action method: create_virtualenv 09:22:25 INFO - Creating virtualenv /builds/slave/test/build/venv 09:22:25 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 09:22:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:22:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:22:25 INFO - Using real prefix '/tools/python27' 09:22:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:22:26 INFO - Installing distribute.............................................................................................................................................................................................done. 09:22:29 INFO - Installing pip.................done. 09:22:29 INFO - Return code: 0 09:22:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:22:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:22:29 INFO - Reading from file tmpfile_stdout 09:22:29 INFO - Output received: 09:22:29 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) 09:22:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:22:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:29 INFO - 'HOME': '/Users/cltbld', 09:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:29 INFO - 'LOGNAME': 'cltbld', 09:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:29 INFO - 'MOZ_NO_REMOTE': '1', 09:22:29 INFO - 'NO_EM_RESTART': '1', 09:22:29 INFO - 'PAGER': '/bin/cat', 09:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:29 INFO - 'PWD': '/builds/slave/test', 09:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:29 INFO - 'SHELL': '/bin/bash', 09:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:29 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:29 INFO - 'USER': 'cltbld', 09:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:29 INFO - 'XPC_FLAGS': '0x0', 09:22:29 INFO - 'XPC_SERVICE_NAME': '0', 09:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:22:30 INFO - Cleaning up... 09:22:30 INFO - Return code: 0 09:22:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:30 INFO - 'HOME': '/Users/cltbld', 09:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:30 INFO - 'LOGNAME': 'cltbld', 09:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:30 INFO - 'MOZ_NO_REMOTE': '1', 09:22:30 INFO - 'NO_EM_RESTART': '1', 09:22:30 INFO - 'PAGER': '/bin/cat', 09:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:30 INFO - 'PWD': '/builds/slave/test', 09:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:30 INFO - 'SHELL': '/bin/bash', 09:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:30 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:30 INFO - 'USER': 'cltbld', 09:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:30 INFO - 'XPC_FLAGS': '0x0', 09:22:30 INFO - 'XPC_SERVICE_NAME': '0', 09:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:30 INFO - Downloading/unpacking psutil>=3.1.1 09:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:22:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:33 INFO - Installing collected packages: psutil 09:22:33 INFO - Running setup.py install for psutil 09:22:33 INFO - building 'psutil._psutil_osx' extension 09:22:33 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 09:22:34 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 09:22:34 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 09:22:34 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 09:22:34 INFO - building 'psutil._psutil_posix' extension 09:22:34 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 09:22:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:22:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:22:34 INFO - ^ 09:22:34 INFO - 1 warning generated. 09:22:34 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 09:22:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:34 INFO - Successfully installed psutil 09:22:34 INFO - Cleaning up... 09:22:35 INFO - Return code: 0 09:22:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:22:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:35 INFO - 'HOME': '/Users/cltbld', 09:22:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:35 INFO - 'LOGNAME': 'cltbld', 09:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:35 INFO - 'MOZ_NO_REMOTE': '1', 09:22:35 INFO - 'NO_EM_RESTART': '1', 09:22:35 INFO - 'PAGER': '/bin/cat', 09:22:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:35 INFO - 'PWD': '/builds/slave/test', 09:22:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:35 INFO - 'SHELL': '/bin/bash', 09:22:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:35 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:35 INFO - 'USER': 'cltbld', 09:22:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:35 INFO - 'XPC_FLAGS': '0x0', 09:22:35 INFO - 'XPC_SERVICE_NAME': '0', 09:22:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:22:38 INFO - Installing collected packages: mozsystemmonitor 09:22:38 INFO - Running setup.py install for mozsystemmonitor 09:22:38 INFO - Successfully installed mozsystemmonitor 09:22:38 INFO - Cleaning up... 09:22:38 INFO - Return code: 0 09:22:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:38 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 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:38 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 09:22:38 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 09:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:38 INFO - 'HOME': '/Users/cltbld', 09:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:38 INFO - 'LOGNAME': 'cltbld', 09:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:38 INFO - 'MOZ_NO_REMOTE': '1', 09:22:38 INFO - 'NO_EM_RESTART': '1', 09:22:38 INFO - 'PAGER': '/bin/cat', 09:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:38 INFO - 'PWD': '/builds/slave/test', 09:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:38 INFO - 'SHELL': '/bin/bash', 09:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:38 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:38 INFO - 'USER': 'cltbld', 09:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:38 INFO - 'XPC_FLAGS': '0x0', 09:22:38 INFO - 'XPC_SERVICE_NAME': '0', 09:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:38 INFO - Downloading/unpacking jsonschema==2.5.1 09:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:22:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:41 INFO - Downloading functools32-3.2.3-2.tar.gz 09:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:22:41 INFO - warning: no files found matching '*.txt' 09:22:41 INFO - no previously-included directories found matching 'build' 09:22:41 INFO - no previously-included directories found matching 'dist' 09:22:41 INFO - no previously-included directories found matching '.git*' 09:22:41 INFO - Installing collected packages: jsonschema, functools32 09:22:41 INFO - Running setup.py install for functools32 09:22:41 INFO - warning: no files found matching '*.txt' 09:22:41 INFO - no previously-included directories found matching 'build' 09:22:41 INFO - no previously-included directories found matching 'dist' 09:22:41 INFO - no previously-included directories found matching '.git*' 09:22:41 INFO - Successfully installed jsonschema functools32 09:22:41 INFO - Cleaning up... 09:22:42 INFO - Return code: 0 09:22:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:42 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 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:42 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 09:22:42 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 09:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:42 INFO - 'HOME': '/Users/cltbld', 09:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:42 INFO - 'LOGNAME': 'cltbld', 09:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:42 INFO - 'MOZ_NO_REMOTE': '1', 09:22:42 INFO - 'NO_EM_RESTART': '1', 09:22:42 INFO - 'PAGER': '/bin/cat', 09:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:42 INFO - 'PWD': '/builds/slave/test', 09:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:42 INFO - 'SHELL': '/bin/bash', 09:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:42 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:42 INFO - 'USER': 'cltbld', 09:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:42 INFO - 'XPC_FLAGS': '0x0', 09:22:42 INFO - 'XPC_SERVICE_NAME': '0', 09:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:22:42 INFO - Cleaning up... 09:22:42 INFO - Return code: 0 09:22:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:42 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 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:42 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 09:22:42 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 09:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:42 INFO - 'HOME': '/Users/cltbld', 09:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:42 INFO - 'LOGNAME': 'cltbld', 09:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:42 INFO - 'MOZ_NO_REMOTE': '1', 09:22:42 INFO - 'NO_EM_RESTART': '1', 09:22:42 INFO - 'PAGER': '/bin/cat', 09:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:42 INFO - 'PWD': '/builds/slave/test', 09:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:42 INFO - 'SHELL': '/bin/bash', 09:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:42 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:42 INFO - 'USER': 'cltbld', 09:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:42 INFO - 'XPC_FLAGS': '0x0', 09:22:42 INFO - 'XPC_SERVICE_NAME': '0', 09:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:42 INFO - Downloading/unpacking blobuploader==1.2.4 09:22:42 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 09:22:42 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 09:22:42 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 09:22:42 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 09:22:42 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 09:22:42 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 09:22:45 INFO - Downloading blobuploader-1.2.4.tar.gz 09:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:22:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:22:45 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 09:22:45 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 09:22:45 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 09:22:45 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 09:22:45 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 09:22:45 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 09:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:22:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:22:45 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 09:22:45 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 09:22:45 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 09:22:45 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 09:22:45 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 09:22:45 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 09:22:46 INFO - Downloading docopt-0.6.1.tar.gz 09:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:22:46 INFO - Installing collected packages: blobuploader, requests, docopt 09:22:46 INFO - Running setup.py install for blobuploader 09:22:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:22:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:22:46 INFO - Running setup.py install for requests 09:22:47 INFO - Running setup.py install for docopt 09:22:47 INFO - Successfully installed blobuploader requests docopt 09:22:47 INFO - Cleaning up... 09:22:47 INFO - Return code: 0 09:22:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:47 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 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:47 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 09:22:47 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 09:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:47 INFO - 'HOME': '/Users/cltbld', 09:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:47 INFO - 'LOGNAME': 'cltbld', 09:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:47 INFO - 'MOZ_NO_REMOTE': '1', 09:22:47 INFO - 'NO_EM_RESTART': '1', 09:22:47 INFO - 'PAGER': '/bin/cat', 09:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:47 INFO - 'PWD': '/builds/slave/test', 09:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:47 INFO - 'SHELL': '/bin/bash', 09:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:47 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:47 INFO - 'USER': 'cltbld', 09:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:47 INFO - 'XPC_FLAGS': '0x0', 09:22:47 INFO - 'XPC_SERVICE_NAME': '0', 09:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:47 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-WguRbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:47 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-JeizgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:47 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-3e0vkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-G0WvDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-6Ejecr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-pSBsrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-mpYobo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-bEhpQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-j0tXup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-NeTmcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-7lhi8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-zuLmsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:48 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-3jWMdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:49 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-_NHDNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:49 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-9QhBeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:49 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-PFvcyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:49 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-VaHo5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:22:49 INFO - Running setup.py install for manifestparser 09:22:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:22:49 INFO - Running setup.py install for mozcrash 09:22:49 INFO - Running setup.py install for mozdebug 09:22:49 INFO - Running setup.py install for mozdevice 09:22:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Running setup.py install for mozfile 09:22:50 INFO - Running setup.py install for mozhttpd 09:22:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Running setup.py install for mozinfo 09:22:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Running setup.py install for mozInstall 09:22:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Running setup.py install for mozleak 09:22:50 INFO - Running setup.py install for mozlog 09:22:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:22:50 INFO - Running setup.py install for moznetwork 09:22:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:22:51 INFO - Running setup.py install for mozprocess 09:22:51 INFO - Running setup.py install for mozprofile 09:22:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:22:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:22:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:22:51 INFO - Running setup.py install for mozrunner 09:22:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:22:51 INFO - Running setup.py install for mozscreenshot 09:22:51 INFO - Running setup.py install for moztest 09:22:51 INFO - Running setup.py install for mozversion 09:22:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:22:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:22:52 INFO - Cleaning up... 09:22:52 INFO - Return code: 0 09:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:52 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 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:52 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 09:22:52 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 09:22:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:52 INFO - 'HOME': '/Users/cltbld', 09:22:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:52 INFO - 'LOGNAME': 'cltbld', 09:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:52 INFO - 'MOZ_NO_REMOTE': '1', 09:22:52 INFO - 'NO_EM_RESTART': '1', 09:22:52 INFO - 'PAGER': '/bin/cat', 09:22:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:52 INFO - 'PWD': '/builds/slave/test', 09:22:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:52 INFO - 'SHELL': '/bin/bash', 09:22:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:52 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:52 INFO - 'USER': 'cltbld', 09:22:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:52 INFO - 'XPC_FLAGS': '0x0', 09:22:52 INFO - 'XPC_SERVICE_NAME': '0', 09:22:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:52 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-82VuvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:52 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)) 09:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:52 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-AOHE27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:52 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-a5PVfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:52 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)) 09:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:52 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-jRGW1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:52 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)) 09:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:52 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-51Sgbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:52 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)) 09:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:52 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-OslfAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:52 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)) 09:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:52 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-U6E63h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-_mBEJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-VznEjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-qXmknI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-GdkMPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-0EoUNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-JsERTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-CuaoZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-RRwPAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:53 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)) 09:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:53 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-DNpRqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:54 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-1LBQtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:54 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)) 09:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:22:54 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)) 09:22:54 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)) 09:22:54 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)) 09:22:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:56 INFO - Downloading blessings-1.6.tar.gz 09:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:22:56 INFO - Installing collected packages: blessings 09:22:56 INFO - Running setup.py install for blessings 09:22:56 INFO - Successfully installed blessings 09:22:56 INFO - Cleaning up... 09:22:57 INFO - Return code: 0 09:22:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:22:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:57 INFO - Reading from file tmpfile_stdout 09:22:57 INFO - Current package versions: 09:22:57 INFO - blessings == 1.6 09:22:57 INFO - blobuploader == 1.2.4 09:22:57 INFO - docopt == 0.6.1 09:22:57 INFO - functools32 == 3.2.3-2 09:22:57 INFO - jsonschema == 2.5.1 09:22:57 INFO - manifestparser == 1.1 09:22:57 INFO - mozInstall == 1.12 09:22:57 INFO - mozcrash == 1.0 09:22:57 INFO - mozdebug == 0.1 09:22:57 INFO - mozdevice == 0.48 09:22:57 INFO - mozfile == 1.2 09:22:57 INFO - mozhttpd == 0.7 09:22:57 INFO - mozinfo == 0.9 09:22:57 INFO - mozleak == 0.1 09:22:57 INFO - mozlog == 3.4 09:22:57 INFO - moznetwork == 0.27 09:22:57 INFO - mozprocess == 0.23 09:22:57 INFO - mozprofile == 0.28 09:22:57 INFO - mozrunner == 6.13 09:22:57 INFO - mozscreenshot == 0.1 09:22:57 INFO - mozsystemmonitor == 0.3 09:22:57 INFO - moztest == 0.8 09:22:57 INFO - mozversion == 1.4 09:22:57 INFO - psutil == 3.1.1 09:22:57 INFO - requests == 1.2.3 09:22:57 INFO - wsgiref == 0.1.2 09:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:58 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:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:58 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 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:58 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 09:22:58 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 09:22:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:58 INFO - 'HOME': '/Users/cltbld', 09:22:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:58 INFO - 'LOGNAME': 'cltbld', 09:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:58 INFO - 'MOZ_NO_REMOTE': '1', 09:22:58 INFO - 'NO_EM_RESTART': '1', 09:22:58 INFO - 'PAGER': '/bin/cat', 09:22:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:58 INFO - 'PWD': '/builds/slave/test', 09:22:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:58 INFO - 'SHELL': '/bin/bash', 09:22:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:58 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:58 INFO - 'USER': 'cltbld', 09:22:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:58 INFO - 'XPC_FLAGS': '0x0', 09:22:58 INFO - 'XPC_SERVICE_NAME': '0', 09:22:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 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)) 09:22:58 INFO - Cleaning up... 09:22:58 INFO - Return code: 0 09:22:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:22:58 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:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:58 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:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:59 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 0x100ac4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59156abf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, '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.iZRHUknhOL/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.myHw0WCc4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 09:22:59 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 09:22:59 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 09:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:59 INFO - 'HOME': '/Users/cltbld', 09:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:59 INFO - 'LOGNAME': 'cltbld', 09:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:59 INFO - 'MOZ_NO_REMOTE': '1', 09:22:59 INFO - 'NO_EM_RESTART': '1', 09:22:59 INFO - 'PAGER': '/bin/cat', 09:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:59 INFO - 'PWD': '/builds/slave/test', 09:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:59 INFO - 'SHELL': '/bin/bash', 09:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:22:59 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:59 INFO - 'USER': 'cltbld', 09:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:59 INFO - 'XPC_FLAGS': '0x0', 09:22:59 INFO - 'XPC_SERVICE_NAME': '0', 09:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:22:59 INFO - Cleaning up... 09:22:59 INFO - Return code: 0 09:22:59 INFO - Running post-action listener: _resource_record_post_action 09:22:59 INFO - Running post-action listener: _start_resource_monitoring 09:22:59 INFO - Starting resource monitoring. 09:22:59 INFO - [mozharness: 2017-01-25 17:22:59.417967Z] Finished create-virtualenv step (success) 09:22:59 INFO - [mozharness: 2017-01-25 17:22:59.418930Z] Running install step. 09:22:59 INFO - Running pre-action listener: _resource_record_pre_action 09:22:59 INFO - Running main action method: install 09:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:59 INFO - Reading from file tmpfile_stdout 09:22:59 INFO - Detecting whether we're running mozinstall >=1.0... 09:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:22:59 INFO - Reading from file tmpfile_stdout 09:22:59 INFO - Output received: 09:22:59 INFO - Usage: mozinstall [options] installer 09:22:59 INFO - Options: 09:22:59 INFO - -h, --help show this help message and exit 09:22:59 INFO - -d DEST, --destination=DEST 09:22:59 INFO - Directory to install application into. [default: 09:22:59 INFO - "/builds/slave/test"] 09:22:59 INFO - --app=APP Application being installed. [default: firefox] 09:22:59 INFO - mkdir: /builds/slave/test/build/application 09:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:23:21 INFO - Reading from file tmpfile_stdout 09:23:21 INFO - Output received: 09:23:21 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:23:21 INFO - Running post-action listener: _resource_record_post_action 09:23:21 INFO - [mozharness: 2017-01-25 17:23:21.027889Z] Finished install step (success) 09:23:21 INFO - [mozharness: 2017-01-25 17:23:21.028016Z] Running run-tests step. 09:23:21 INFO - Running pre-action listener: _resource_record_pre_action 09:23:21 INFO - Running main action method: run_tests 09:23:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:21 INFO - 2017-01-25 09:23:21.041 screenresolution[1628:7240] starting screenresolution argv=screenresolution get 09:23:21 INFO - 2017-01-25 09:23:21.043 screenresolution[1628:7240] Display 0: 1600x1200x32@60 09:23:21 INFO - 2017-01-25 09:23:21.048 screenresolution[1629:7243] starting screenresolution argv=screenresolution list 09:23:21 INFO - Available Modes on Display 0 09:23:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:21 INFO - Intel Iris: 09:23:21 INFO - Chipset Model: Intel Iris 09:23:21 INFO - Type: GPU 09:23:21 INFO - Bus: Built-In 09:23:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:21 INFO - Vendor: Intel (0x8086) 09:23:21 INFO - Device ID: 0x0a2e 09:23:21 INFO - Revision ID: 0x0009 09:23:21 INFO - Displays: 09:23:21 INFO - Display: 09:23:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:21 INFO - Display Serial Number: 1600x1200 60 09:23:21 INFO - Main Display: Yes 09:23:21 INFO - Mirror: Off 09:23:21 INFO - Online: Yes 09:23:21 INFO - Rotation: Supported 09:23:21 INFO - Return code: 0 09:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:23:21 INFO - Python 2.7.3 09:23:21 INFO - Return code: 0 09:23:21 INFO - grabbing minidump binary from tooltool 09:23:21 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:23:21 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 0x1008d8d30>, '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 0x10073edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:23:21 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:23:21 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:23:21 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:23:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:23:21 INFO - Return code: 0 09:23:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:23:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:23:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:23:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:23:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0273 --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:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZRHUknhOL/Render', 09:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:21 INFO - 'HOME': '/Users/cltbld', 09:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:21 INFO - 'LOGNAME': 'cltbld', 09:23:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:23:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:21 INFO - 'MOZ_NO_REMOTE': '1', 09:23:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:23:21 INFO - 'NO_EM_RESTART': '1', 09:23:21 INFO - 'PAGER': '/bin/cat', 09:23:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:21 INFO - 'PWD': '/builds/slave/test', 09:23:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:21 INFO - 'SHELL': '/bin/bash', 09:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.myHw0WCc4R/Listeners', 09:23:21 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 09:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:21 INFO - 'USER': 'cltbld', 09:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:21 INFO - 'XPC_FLAGS': '0x0', 09:23:21 INFO - 'XPC_SERVICE_NAME': '0', 09:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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:23:21 INFO - mozversion application_buildid: 20170125065947 09:23:21 INFO - mozversion application_changeset: 2cea7106779920aef1eb4572ed9ae9b63939a928 09:23:21 INFO - mozversion application_display_name: Firefox 09:23:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:23:21 INFO - mozversion application_name: Firefox 09:23:21 INFO - mozversion application_remotingname: firefox 09:23:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:23:21 INFO - mozversion application_vendor: Mozilla 09:23:21 INFO - mozversion application_version: 52.0 09:23:21 INFO - mozversion platform_buildid: 20170125065947 09:23:21 INFO - mozversion platform_changeset: 2cea7106779920aef1eb4572ed9ae9b63939a928 09:23:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:23:21 INFO - mozversion platform_version: 52.0 09:23:21 INFO - using testdate: 1485365001 09:23:21 INFO - actual date: 1485365001 09:23:21 INFO - starting webserver on 'localhost:49241' 09:23:21 INFO - SUITE-START | Running 2 tests 09:23:21 INFO - TEST-START | dromaeo_css 09:23:21 INFO - operating with platform_type : mac_ 09:23:21 INFO - Initialising browser for dromaeo_css test... 09:23:21 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpqARmiG/profile http://localhost:49241/getInfo.html) 09:23:24 INFO - PROCESS | 1639 | 2017-01-25 09:23:24.645 plugin-container[1642:7423] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 09:23:24 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:24 INFO - PROCESS | 1639 | 2017-01-25 09:23:24.646 plugin-container[1642:7423] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:23:24 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:24 INFO - PROCESS | 1639 | 2017-01-25 09:23:24.646 plugin-container[1642:7423] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:23:24 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 09:23:24 INFO - PROCESS | 1639 | colorDepth:24 09:23:24 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 09:23:24 INFO - PROCESS | 1639 | __metrics 09:23:24 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:23:25 INFO - TEST-INFO | 1639: exit 0 09:23:25 INFO - Browser initialized. 09:23:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:23:25 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpqARmiG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:23:27 INFO - PROCESS | 1643 | 2017-01-25 09:23:27.921 plugin-container[1644:7549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:23:27 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:27 INFO - PROCESS | 1643 | 2017-01-25 09:23:27.924 plugin-container[1644:7549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 09:23:27 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:27 INFO - PROCESS | 1643 | 2017-01-25 09:23:27.924 plugin-container[1644:7549] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:23:37 INFO - PROCESS | 1643 | RSS: Main: 176984064 09:23:37 INFO - PROCESS | 1643 | 09:26:40 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:26:42 INFO - PROCESS | 1643 | RSS: Main: 181379072 09:26:42 INFO - PROCESS | 1643 | 09:29:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:29:45 INFO - PROCESS | 1643 | RSS: Main: 193097728 09:29:45 INFO - PROCESS | 1643 | 09:32:47 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:32:47 INFO - PROCESS | 1643 | RSS: Main: 195858432 09:32:47 INFO - PROCESS | 1643 | 09:35:49 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:35:50 INFO - PROCESS | 1643 | RSS: Main: 198893568 09:35:50 INFO - PROCESS | 1643 | 09:38:52 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:38:53 INFO - PROCESS | 1643 | RSS: Main: 200732672 09:38:53 INFO - PROCESS | 1643 | 09:41:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:41:56 INFO - PROCESS | 1643 | RSS: Main: 201199616 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | __start_tp_report 09:41:56 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 09:41:56 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 09:41:56 INFO - PROCESS | 1643 | |i|pagename|runs| 09:41:56 INFO - PROCESS | 1643 | |0;dojo.html;3639.3994990826513;3657.4513822926556;3678.8344524496397;3688.760230585012;3711.164987877728;6028.306744724353;6105.877882442349;6115.480184184344;6145.446909778119;6153.16932214151;6103.938960610392;6170.938290617092;6182.072689096638;6198.659073750942;6236.501079913607;5441.455854414567;5493.890122197554;5506.834794956158;5510.559155267589;5562.332520097574;4778.952210477885;5105.593408018513;5565.916511252334;5572.721363931808;5597.8600534986745;5340.813071542497;5348.625596208267;5534.0592099343;5545.3622833374075;5548.5838562108;5412.648177868446;5476.315460567449;5518.255035570187;5522.696251706155;5579.637323573955;5369.7583608737705;5463.945360546383;5485.807996720096;5493.011257973563;5516.393196748354;5946.940530594682;6168.845778855564;6181.81454556364;6197.03945888388;6198.721057552421;4973.52751488608;6270.3416141305415;6345.111684364147;6347.460465860408;6370.394812492806;6475.967620161916;6508.414242718178;6519.3154718754795;6539.803805885831;6550.181227346582;5600.691961942132;6217.96891015542;6226.533010024266;6231.065340198916;6260.436560709512;6562.5406221564035;6585.242697089896;6633.2703402625675;6682.766103186365;6693.765718199839;6371.745130194833;6387.265464471552;6389.712462939179;6391.808245752635;6395.616263024233;6307.369263073656;6328.854600236852;6536.313687062885;6540;6540.574862633914;6307.842303942346;6354.936450635434;6364.363563643691;6453.419192272718;6465.353464653497;5375.086235339923;6412.775552855627;6436.259830119502;6452.967735161388;6457.806265812033;5092.898142037138;5210.76551555181;5216.347956505437;5232.738363081907;5253.50091741284;3831.7700937943814;4634.9536504634525;4645.372874661877;4654.953450465452;4748.525147485224;4860.975695121572;5968.761249550056;6198.81403557894;6228.719707613168;6243;6267.4986001119805;6509.121268628674;6534.477241820643;6561.212654481493;6568.441682456949;6113.962834745899;6131.682521312785;6137.8673257933415;6162.476189523851;6232.56372053961;5741.339745929188;5741.45456181662;5742.799002034993;5743.310802703619;5764.7117644117625;6724.495662825308;6738.898916516354;6764.627945463047;6764.729410823709;6774.187097548227;6342.556021078664;6393.552451328363;6443.130177426175;6484.157059582224;6497.837554061186;6660.433863121689;6743.797686069426;6755.088063111417;6761.0534525167495;6766.86466270662;6433.388828061326;6448.355164483514;6448.5486015980305;6458.095866578588;6536.509761767981;6143.665407145256;6301.369863013662;6401.263854656774;6429.7428102875365;6524.478041756743;6309.873802524015;6325.620462772248;6327.96836015817;6342.365763423805;6347.9525878231425;6317.399847014526;6348.746050158126;6363.522735794926;6377.075324078076;6441.484681225397;6127.877442451037;6152.969235153795;6177.84555386119;6193.659348735863;6195.845103872439;6185.84535386619;6289;6294.213223347082;6299.527535434952;6299.811005669837;6231.376862313732;6232.937670623236;6242.126102345766;6245.281792593728;6257.061440783918 09:41:56 INFO - PROCESS | 1643 | |1;ext.html;479.85364463838533;494.3869601693901;512.3365242011596;517.6376536424499;565.0337922153116;31034.84482577587;38965.83102506926;40438.393424098664;40485.797571012175;40681.38977915334;41900;42459.78770106153;42484.36273455901;42556.78721606388;42645.36031959523;9356.625734970594;9538.952305238465;9583;9605.327627066074;9729.902700973005;9381.76545586359;9436.764080897963;9477.47873866937;9566.521673916277;9567.043295670446;8180.713675021376;9577.233821294314;9613.327067105305;9620.374675646095;9652.17956473697;9333.113354231336;9489.193418559431;9493.240540756722;9511.571979260882;9526.618935242583;9565.904340956571;9654.420734755937;9669.081437263449;9734.659286924994;9751.561179746857;39820;40621.79689101536;40990.795046024876;41091.972700682425;41222.38166427506;3276.705096541299;3453.5510383649967;3485.686288234071;3662.8901132966053;4148.377743338493;30916;31127.533087003714;31497.685023149934;32115;32144.839275803704;3199.2001999500126;3286.3263031078573;3310.867565297385;3348.2466445049668;3371.089805752458;511162;514169.4291528556;517744;517800;518203.81796181935;65107;65317.67341163264;65387.673061634385;65583;65697.01454478186;57554.712226439435;57590.424095758506;69011.30988690049;69171;69321.65339173272;14516.491922782652;16399.83600164007;17030.233639486254;17280.481585552454;17323.133843307784;12863.871361286454;12968.35158242084;12985.935070324587;12989.675258118623;13037.543685970946;19322.80677193238;19967.301144459874;19981;19985.000749962444;19999.400017999484;8018.759437216776;8023.879641805378;8063.879041814377;8115.553644549606;8135.837283254302;891.5765011619429;893.4817805672694;1005.9245556583286;1056.6513050693252;1084.3006260961793;29557.408851823275;31087.222819429244;31300.247180213406;31390.37219255602;31670;988.7577543501889;1084.224779282801;1097.550004498152;1118.9440527973568;1128.1820680006929;15984;16025.038497690175;16253.59366015859;16255.51233462998;16470.929389589877;698021;699490;699521;699566;707277;45985;58380.708096459246;59246.70376648089;59768.40231597628;60031;1297.740451909622;1321.5506727712625;1405.0726520496423;1408.9295535223198;1417.4755340524073;29822.403551928404;30423.391532169677;31926;33025.50461743124;33244.50133248052;46505.76747116243;58193.127103092644;59318;59325;59391.40608594032;643870;645733;656118.7194063999;656251.7187414032;657965;43861.78069109634;43923.78038109917;44637.55362446334;44803.775981119885;44931.97806098668;48005;48144.51855481401;48492.03015939732;48821.26768098411;48971.26543101785;36301.45547816838;39449.2110157801;39966.80099597017;40747.777566673045;42006.579934200265;39965.200695986496;40237.59762402456;40887;41436.792816035726;41582.58417415787;38559.6144038556;39402;39465.210695786496;39480.80259598684;40570.59429405668;30305.78776848901;33366.83316583402;34614.96155115351;36269.81865090658;36414.08964775902;26319.605205921544;26692.8665356672;26752;26812.195634131007;26886.32784180411 09:41:56 INFO - PROCESS | 1643 | |2;jquery.html;388.58809661758556;389.9044734040161;391.3444979659071;399.5365376163651;417.3301850529899;19625.607487850233;20440.999999999964;20862.06120724567;20885.895570522163;21111.472213194676;20509.38471845847;20635.277765278217;20866.89566552169;20969.37091887242;21056.368308950718;5720.342160651537;6134.355892631276;6180.165677633507;6182.505399568046;6196.442320191182;5874.823755287348;6032.336442991267;6070.028795392738;6102.572819902609;6140.53945954053;6033.245844269466;6048.96975515124;6053.51571874249;6061.787837425691;6066.787662431816;5730.398308177623;6026.60827046245;6055.576109672303;6069.1199776032445;6085.786997455112;5247.685138891678;5303.416624171338;5321.4412486688725;5333.919991200135;5342.038433082043;25231.738413079274;25340.873295633588;25473.61789573158;25607;26205.60691589628;2346.0967527501866;2500.4249022724694;2675.8795854186415;2845.672747634028;3186.426443240216;15285.235738213156;15352;15467.303971321206;15834.683306333924;16232.944858584155;1614.555997100795;1895.8862468251948;1907.2657027044688;1959.8726082804571;1968.4488343263909;78057.35688173011;120663;121583;127453.36273318574;129500.7049929498;11216.046636035866;11298.265612735146;11753.118516111326;11757.412129393668;11970.982466490446;14020.298985050911;14209.076410033314;14237.074590151815;14247.78628320576;14275.643108922151;8466.703665371806;8598.699045533502;8634.78413039679;8711.651533938571;8813.91186088131;8748.8687669685;8810.559472026373;8855.822883542292;9012.143846334588;9046.457212567268;6718.496112791462;6801.829954251183;6819.079424277758;6835.487338449636;6850.965745171242;1692.3230707717012;1936.8644194906221;1953.765548134233;1980.0792631426168;1982.3755517012096;898.2768871058861;911.8905731312152;1009.7021378693303;1015.8577799108131;1029.2332212501585;10810.891891081146;11722.593288805398;11795.823062654066;11870;12320.938395308145;435.4948260018364;439.2313451459945;447.37144312241355;455.1874903297598;455.3533981745929;6187.597806142587;6233.065040244;6277.686115694289;6309.747610095545;6351.396617321347;536802;634029;642926.7853660702;643833;666476;79054;79446.6027669858;118175.40912295383;132911.02533848;136086;451.7583093045205;464.4937018649621;472.487351223919;475.4508542633264;476.53061734191795;11513.53945842157;11558.133140014701;11637.301761894314;11865.940670296593;11891.048716102863;43822.561774383124;55110.72444637751;55481.72259138679;57308.71345643245;57419.1387129185;76831;77382.71407431144;79988;84141;84253.57873210595;8180.222878826502;8500.72489126636;8510.74467765968;8542.530160841214;8558.828823423371;8911.598978046133;12607.936960315139;12720.6819829505;12764;13048.869511304765;10973.23187376876;11134.888651113386;11305.7738845221;11334.433278335886;11413.657590272305;9791.706248812547;10829.67510974672;10990.120790336914;11021.724456888642;11320.660380188607;11105.944470277596;11144.665660030212;11244.156688248579;11444.370559619301;11491.885081149081;7482.70069197226;8216.507009579444;8299;8354.955630546181;8532.933383327085;10208.591656333663;10486;10873.891261087289;10926.289791163548;11166.385848778395 09:41:56 INFO - PROCESS | 1643 | |3;mootools.html;1466.7139907717994;1512.357248169528;1708.906010169441;1834.642244762272;1844.944651660449;7710.92289077109;7974.641141148648;8161.510309381434;8572.442791218566;8613.827723445527;8114.553699546523;8373.455725377846;8382.161783821615;8480.872786908201;8495.872561911576;2382.059086660768;2420.770026847447;2428.0651948999584;2428.538577670237;2432.5499782540196;2413.9758602414013;2445.217530390276;2462.150558057467;2468.950620987579;2473.4063824682034;2379.5003049359657;2403.8798060096924;2414.987925060381;2424.3211900667843;2429.392651837041;2181.6691817991014;2323.5352929414016;2386.1767690146958;2386.451116243265;2414.3964008997664;2390.605550084234;2395.47299594089;2412.0834083048503;2415.1426243683413;2434.7078350597862;8444.831103377897;8493.915060849375;8514.361422893307;8562.957185214096;8565.48607083577;5170.793168273264;5301.363836339626;5325.707086110262;5327.360716713981;5337.3061502004875;3488.0930957951114;3488.180277634754;3548.6983606393487;3549.183687751806;3586.677199052098;1030.2273295028729;1070.6252811515985;1095.9726006849892;1101.3502033800096;1111.9499622517005;10877.619283325124;10888.61889833852;10932.672019839418;10955.780884382348;10971.615993440191;3868.9806550967064;4534.455865296186;4725.9527404726205;4726.503717109654;4846.660733748674;9308.976012638605;9572.186364159124;9627.855582166274;9637.855432168522;9644.565994530263;4136.896577585584;5037.974810125926;5288.709120998382;5290.127129023668;5299.33758280215;4311.525732169459;4517.81928722854;4719.339292499053;4830.29960655703;4884.7069175849565;3425.845836937344;3917.5298964124486;3937.4881265435884;4036.8788936331957;4056.8782936511952;1302.2121616421928;1309.5351150341492;1313.7044165062784;1314.0210543145417;1316.0655934286763;938.216589148069;940.4874343482818;942.1002942190192;950.9334346595672;981.0728861226073;5686.9715651421475;5769;5927.347991720907;6008.497875531118;6014.308354539196;383.411463403674;463.5711966431038;467.2547287077107;469.0712389468871;475.6385147288017;3961.3265744823448;3977.125032492847;4013.4381186633896;4014.3175660137845;4065.10567675111;9777;10082.24383171265;10161.441120738282;10312.071913527818;10331.845022324671;10084.394936303845;10170.796584068277;10186.08325250731;10189.98100189975;10274.897251027394;7827.882581761278;7901.881471777928;7924.801879953047;8094.433389662667;8113.350931925432;5874.559408044499;5923.881522369615;6002.6398416093425;6009.399060094131;6096.055111457731;9308.906910930804;9414.293927955294;9427.811443770948;9433.622655093719;9565.326067938222;9583.760405989906;9709.417434953926;9751.171150452008;9759.219262459128;9790.46152461593;6742.8651426972165;6939.722411103499;7084.397826184626;7261.745838895613;7386.852262954818;7884.487508311941;7985.800354991172;7997.320227780471;8149.551774652214;8158.224968627971;7203.963980180066;7287.708491660486;7344.669489872862;7436.479446438698;7454.627268636438;5903.468687818117;6078.5441091919165;7167.928320716935;7176.96411517939;7229.240929702516;6671.399574038166;6716.764913228014;6726.8990965136545;6731.32686731323;6806.625635589892;4950.430700469348;4982.327385802871;4991.975040124777;4997.300377947163;5032.874178145576;4993.575546078624;5023.422306434807;5035.7985680572365;5036.722980236123;5067.5185857343495 09:41:56 INFO - PROCESS | 1643 | |4;prototype.html;308.4709722825353;317.77120473259254;324.9317643294909;327.73944713952403;329.26409474823765;4433.8669839904815;4815;4886.218205087187;5343.664083979005;5682.545396368291;4395.780210989454;5408.891822163554;5545.972270138654;5568;5588.469095435938;3747.494088298086;3818.06457417933;4277.657787377002;4279.957200427987;4288.228118938589;3332.9000129996016;3542.8937131885955;4255.510616279136;4266.957330426702;4307.892302692442;3898.966773804944;4131.29767939451;4188.178142508019;4198.958010419902;4219.915601687964;3649.288388764192;4179.122384299286;4181.351890456999;4196.51740049892;4208.516020657602;3442.896713098586;3620.5655321361605;3634.9636503635156;3652.3425783358985;3655.574326012857;4472.373867658531;4552.112338094073;5048.974755126201;5054.216596427523;5176.275321454999;4025.81883815229;4120.96975756061;4437.334399840018;4800.231962885957;4810.903781924377;4787.4494433139935;5055.51972562606;5842.444967728059;5846.590738648296;5869.85325366865;3648.087978005499;3736.327461057009;3977.960220397788;3988.8205030773684;4005.439238506611;4824.975875120602;5597.132444471114;5687.800926967537;6140.416660417253;6262.561620686553;3269.9673003269663;3411.9317613647586;4034.435179074932;4076.490438695163;4103.425520427142;3448.6034106078123;3804.524434445694;4124.886280704193;4139.234241665302;4144.896377590584;4828.46886842447;4841.2496063110275;4841.8547443576745;4842.757862106951;4857.732824694675;4997.30037794709;5180.948190518122;5915.467607915309;5943.167956486095;5943.732532036069;1112.6328311657135;1146.9025132863633;1164.945724119673;1256.604169686546;1361.4826365980864;798.7364169823944;908.246155690775;911.352939413011;919.4161707315769;922.294444749767;4658.58072773452;4810.542998415145;5296.867578310496;5320.388155362183;5387.568994480511;5123.538881500608;5799.927013502515;5802.767889284381;5844.464099219996;5855.560832937546;5029.195328747456;5039.470855560114;5180.611454140954;5217.634765566374;5325.254464374991;2776.681076488652;3042.9239269018008;3046.558249053875;3081.922951926175;3099.628044634658;5689.886202275931;5842.094475356326;6152.692365381713;6163.291221509494;6190.969045154836;4709.811607535728;5051.444341122474;5207.661502002357;5943.255748878266;6170.876582468325;4912.778924948386;5785.598976424737;5841.824745257649;6062.969685151635;6229.5327850410495;4921.530321123671;5089.931114466003;5204.7587495920525;5639.6334238274385;5699.686517241592;5101.7194054327365;5204.713740744295;5438.945610543844;5478.835634930959;5485.369182543899;5215;5489.910017037333;5740.560261888764;6160.507159427325;6207.206756959296;3347.6484969077896;4041.656459201001;4057.2291264660803;4067.4102255172243;4096.487939007624;5579.553635709215;6025.397460253939;6041.2750469943885;6067.939320606737;6104.405627608337;4876.65920358223;5510.586705996986;5865.912011319919;5876.588638795414;5876.823695289148;5875.471207591167;5895.970520147372;5901.29184497863;5911.261092363455;5936.465718085393;4750.952490475189;4822.059698358927;5269.314989051399;5410.59420543453;5766.740496677576;5307.601929855199;5719.885602287848;5902.970485147547;5952;5967.910481342697;5039.521245481674;5774.307083150049;5841.737121829443;5846.503047241032;5858.794942177004 09:41:56 INFO - PROCESS | 1643 | |5;yui.html;703.2756261051119;735.7093947890584;787.488132713736;818.2104269380047;839.2446797881904;14630.122192668434;15314.0811551307;15380.307886145123;15423.537293881187;15478.69042619147;13418.396172172252;13591.456341746343;13759.449622015107;13826.930865345685;13830.515931942384;798.3014861995754;832.8667413213872;842.2546046748641;851.3444647621359;855.8159995600945;804.2761514636816;831;847.174005344787;848.6053984897021;850.740524140137;873.5763154869915;876.6581033396981;888.7555922121404;889.9688510902088;896.5920506169709;859.7291853066264;867.2281669314315;868.4702331577732;880.1594477274252;880.467317273047;651.3226244705501;658.6180015590994;660.5310229736892;662.7514681994252;663.0352836622702;12517.37274154354;12580.182288151242;12615.684607884785;12648.873511264954;12675.239485630891;10153.441560714158;11736.178467507278;12102;12102.092343074217;12337.69155771104;10515.579376824919;10590.576376944913;10772.35365878042;10883.83674244887;11071.667849964513;3655.6892664123316;3656.3784156693428;3681.9447708284397;3685.4840322354894;3745.3445647011663;91545;96600;97054;97738.51130744301;98245.5087724564;8890.199882010651;9105.817883642423;9121.407108537924;9209.861852072225;9436.528173591292;18587.853847113107;18673.719894201597;18825.246990120515;18939.053047347854;19081.332153374566;7615.200403957616;7726.4591478597085;7795.259450352208;7920.801979950547;8077.192280771876;4302.913941721211;4398.186335527937;4487.237169681162;4488.349189367524;4612.7001744886475;3434.793912365266;3563.3407819553468;3579.713622910161;3696.2977034363385;3869.7678139311734;627.6140173793084;705.5978092487336;706.7384718277953;713.6859781696037;727.756201672435;614.4869034356274;640.3788325324427;646.7768619826153;673.8989151627295;678.3250665587772;9258.861117083248;9273;9277.304202184863;9366.953165234267;9470.242380609534;555.0315785315404;647.1651569475324;667.4960404894274;680.5678394219607;682.2563405887507;4214.967333003373;4286.635635970977;4488.12481566098;4636.86089417318;4687.789049492781;82206.35587288221;95665.56501652481;100429.49785251026;104652;114358.85641143649;108595.45702271438;108777.45611271892;110586;113416;115317;60915.39084609186;79510.21714239974;92600;99246.50376748215;99723;7737.767866964;9612.279079068958;9812.165965892978;9832.705018849447;9899.406035637885;54191.72904135454;54767.987760673546;55860;56382.43617563772;57474.13788793255;51404.742976286376;54700.726496367264;81619.5919020401;84155;85631.8592035204;12188.817167742314;12676.873231267939;12767.297798621164;12968.221906685629;13430.19418834873;14216.857831421554;14385.496507622183;14449.85550144485;14455;14460.566183014527;13280.468781248643;13292.468301268227;13313.33433328349;13316.33418329099;13450.715108250852;13678;13762.862371376157;13781.89744820432;13985.93007034999;14586.708265834835;13024.674383140118;13051.980037914047;13141.408636611184;13564.321783910567;13785.103968242433;11231.38227397501;11440.542378304775;11450.026747726204;11674.591389301333;11730.589429369931;9584.66453674118;11092.889071109508;11269.605563805228;11688.357140357362;11813.881861181279 09:41:56 INFO - PROCESS | 1643 | __end_tp_report 09:41:56 INFO - PROCESS | 1643 | __start_cc_report 09:41:56 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,359 09:41:56 INFO - PROCESS | 1643 | __end_cc_report 09:41:56 INFO - PROCESS | 1643 | __startTimestamp1485366116148__endTimestamp 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | ------- Summary: start ------- 09:41:56 INFO - PROCESS | 1643 | Number of tests: 6 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:6007.94 Median:6232.94 stddev:668.04 (10.7%) stddev-sans-first:643.73 09:41:56 INFO - PROCESS | 1643 | Values: 3639.4 3657.5 3678.8 3688.8 3711.2 6028.3 6105.9 6115.5 6145.4 6153.2 6103.9 6170.9 6182.1 6198.7 6236.5 5441.5 5493.9 5506.8 5510.6 5562.3 4779.0 5105.6 5565.9 5572.7 5597.9 5340.8 5348.6 5534.1 5545.4 5548.6 5412.6 5476.3 5518.3 5522.7 5579.6 5369.8 5463.9 5485.8 5493.0 5516.4 5946.9 6168.8 6181.8 6197.0 6198.7 4973.5 6270.3 6345.1 6347.5 6370.4 6476.0 6508.4 6519.3 6539.8 6550.2 5600.7 6218.0 6226.5 6231.1 6260.4 6562.5 6585.2 6633.3 6682.8 6693.8 6371.7 6387.3 6389.7 6391.8 6395.6 6307.4 6328.9 6536.3 6540.0 6540.6 6307.8 6354.9 6364.4 6453.4 6465.4 5375.1 6412.8 6436.3 6453.0 6457.8 5092.9 5210.8 5216.3 5232.7 5253.5 3831.8 4635.0 4645.4 4655.0 4748.5 4861.0 5968.8 6198.8 6228.7 6243.0 6267.5 6509.1 6534.5 6561.2 6568.4 6114.0 6131.7 6137.9 6162.5 6232.6 5741.3 5741.5 5742.8 5743.3 5764.7 6724.5 6738.9 6764.6 6764.7 6774.2 6342.6 6393.6 6443.1 6484.2 6497.8 6660.4 6743.8 6755.1 6761.1 6766.9 6433.4 6448.4 6448.5 6458.1 6536.5 6143.7 6301.4 6401.3 6429.7 6524.5 6309.9 6325.6 6328.0 6342.4 6348.0 6317.4 6348.7 6363.5 6377.1 6441.5 6127.9 6153.0 6177.8 6193.7 6195.8 6185.8 6289.0 6294.2 6299.5 6299.8 6231.4 6232.9 6242.1 6245.3 6257.1 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:75924.67 Median:41741.29 stddev:167988.27 (402.5%) stddev-sans-first:168364.02 09:41:56 INFO - PROCESS | 1643 | Values: 479.9 494.4 512.3 517.6 565.0 31034.8 38965.8 40438.4 40485.8 40681.4 41900.0 42459.8 42484.4 42556.8 42645.4 9356.6 9539.0 9583.0 9605.3 9729.9 9381.8 9436.8 9477.5 9566.5 9567.0 8180.7 9577.2 9613.3 9620.4 9652.2 9333.1 9489.2 9493.2 9511.6 9526.6 9565.9 9654.4 9669.1 9734.7 9751.6 39820.0 40621.8 40990.8 41092.0 41222.4 3276.7 3453.6 3485.7 3662.9 4148.4 30916.0 31127.5 31497.7 32115.0 32144.8 3199.2 3286.3 3310.9 3348.2 3371.1 511162.0 514169.4 517744.0 517800.0 518203.8 65107.0 65317.7 65387.7 65583.0 65697.0 57554.7 57590.4 69011.3 69171.0 69321.7 14516.5 16399.8 17030.2 17280.5 17323.1 12863.9 12968.4 12985.9 12989.7 13037.5 19322.8 19967.3 19981.0 19985.0 19999.4 8018.8 8023.9 8063.9 8115.6 8135.8 891.6 893.5 1005.9 1056.7 1084.3 29557.4 31087.2 31300.2 31390.4 31670.0 988.8 1084.2 1097.6 1118.9 1128.2 15984.0 16025.0 16253.6 16255.5 16470.9 698021.0 699490.0 699521.0 699566.0 707277.0 45985.0 58380.7 59246.7 59768.4 60031.0 1297.7 1321.6 1405.1 1408.9 1417.5 29822.4 30423.4 31926.0 33025.5 33244.5 46505.8 58193.1 59318.0 59325.0 59391.4 643870.0 645733.0 656118.7 656251.7 657965.0 43861.8 43923.8 44637.6 44803.8 44932.0 48005.0 48144.5 48492.0 48821.3 48971.3 36301.5 39449.2 39966.8 40747.8 42006.6 39965.2 40237.6 40887.0 41436.8 41582.6 38559.6 39402.0 39465.2 39480.8 40570.6 30305.8 33366.8 34615.0 36269.8 36414.1 26319.6 26692.9 26752.0 26812.2 26886.3 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:34919.52 Median:395.44 stddev:104073.65 (26318.4%) stddev-sans-first:104333.31 09:41:56 INFO - PROCESS | 1643 | Values: 388.6 389.9 391.3 399.5 417.3 19625.6 20441.0 20862.1 20885.9 21111.5 20509.4 20635.3 20866.9 20969.4 21056.4 5720.3 6134.4 6180.2 6182.5 6196.4 5874.8 6032.3 6070.0 6102.6 6140.5 6033.2 6049.0 6053.5 6061.8 6066.8 5730.4 6026.6 6055.6 6069.1 6085.8 5247.7 5303.4 5321.4 5333.9 5342.0 25231.7 25340.9 25473.6 25607.0 26205.6 2346.1 2500.4 2675.9 2845.7 3186.4 15285.2 15352.0 15467.3 15834.7 16232.9 1614.6 1895.9 1907.3 1959.9 1968.4 78057.4 120663.0 121583.0 127453.4 129500.7 11216.0 11298.3 11753.1 11757.4 11971.0 14020.3 14209.1 14237.1 14247.8 14275.6 8466.7 8598.7 8634.8 8711.7 8813.9 8748.9 8810.6 8855.8 9012.1 9046.5 6718.5 6801.8 6819.1 6835.5 6851.0 1692.3 1936.9 1953.8 1980.1 1982.4 898.3 911.9 1009.7 1015.9 1029.2 10810.9 11722.6 11795.8 11870.0 12320.9 435.5 439.2 447.4 455.2 455.4 6187.6 6233.1 6277.7 6309.7 6351.4 536802.0 634029.0 642926.8 643833.0 666476.0 79054.0 79446.6 118175.4 132911.0 136086.0 451.8 464.5 472.5 475.5 476.5 11513.5 11558.1 11637.3 11865.9 11891.0 43822.6 55110.7 55481.7 57308.7 57419.1 76831.0 77382.7 79988.0 84141.0 84253.6 8180.2 8500.7 8510.7 8542.5 8558.8 8911.6 12607.9 12720.7 12764.0 13048.9 10973.2 11134.9 11305.8 11334.4 11413.7 9791.7 10829.7 10990.1 11021.7 11320.7 11105.9 11144.7 11244.2 11444.4 11491.9 7482.7 8216.5 8299.0 8355.0 8532.9 10208.6 10486.0 10873.9 10926.3 11166.4 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5533.61 Median:5028.15 stddev:3022.25 (60.1%) stddev-sans-first:3015.27 09:41:56 INFO - PROCESS | 1643 | Values: 1466.7 1512.4 1708.9 1834.6 1844.9 7710.9 7974.6 8161.5 8572.4 8613.8 8114.6 8373.5 8382.2 8480.9 8495.9 2382.1 2420.8 2428.1 2428.5 2432.5 2414.0 2445.2 2462.2 2469.0 2473.4 2379.5 2403.9 2415.0 2424.3 2429.4 2181.7 2323.5 2386.2 2386.5 2414.4 2390.6 2395.5 2412.1 2415.1 2434.7 8444.8 8493.9 8514.4 8563.0 8565.5 5170.8 5301.4 5325.7 5327.4 5337.3 3488.1 3488.2 3548.7 3549.2 3586.7 1030.2 1070.6 1096.0 1101.4 1111.9 10877.6 10888.6 10932.7 10955.8 10971.6 3869.0 4534.5 4726.0 4726.5 4846.7 9309.0 9572.2 9627.9 9637.9 9644.6 4136.9 5038.0 5288.7 5290.1 5299.3 4311.5 4517.8 4719.3 4830.3 4884.7 3425.8 3917.5 3937.5 4036.9 4056.9 1302.2 1309.5 1313.7 1314.0 1316.1 938.2 940.5 942.1 950.9 981.1 5687.0 5769.0 5927.3 6008.5 6014.3 383.4 463.6 467.3 469.1 475.6 3961.3 3977.1 4013.4 4014.3 4065.1 9777.0 10082.2 10161.4 10312.1 10331.8 10084.4 10170.8 10186.1 10190.0 10274.9 7827.9 7901.9 7924.8 8094.4 8113.4 5874.6 5923.9 6002.6 6009.4 6096.1 9308.9 9414.3 9427.8 9433.6 9565.3 9583.8 9709.4 9751.2 9759.2 9790.5 6742.9 6939.7 7084.4 7261.7 7386.9 7884.5 7985.8 7997.3 8149.6 8158.2 7204.0 7287.7 7344.7 7436.5 7454.6 5903.5 6078.5 7167.9 7177.0 7229.2 6671.4 6716.8 6726.9 6731.3 6806.6 4950.4 4982.3 4992.0 4997.3 5032.9 4993.6 5023.4 5035.8 5036.7 5067.5 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4602.43 Median:5050.21 stddev:1429.60 (28.3%) stddev-sans-first:1396.81 09:41:56 INFO - PROCESS | 1643 | Values: 308.5 317.8 324.9 327.7 329.3 4433.9 4815.0 4886.2 5343.7 5682.5 4395.8 5408.9 5546.0 5568.0 5588.5 3747.5 3818.1 4277.7 4280.0 4288.2 3332.9 3542.9 4255.5 4267.0 4307.9 3899.0 4131.3 4188.2 4199.0 4219.9 3649.3 4179.1 4181.4 4196.5 4208.5 3442.9 3620.6 3635.0 3652.3 3655.6 4472.4 4552.1 5049.0 5054.2 5176.3 4025.8 4121.0 4437.3 4800.2 4810.9 4787.4 5055.5 5842.4 5846.6 5869.9 3648.1 3736.3 3978.0 3988.8 4005.4 4825.0 5597.1 5687.8 6140.4 6262.6 3270.0 3411.9 4034.4 4076.5 4103.4 3448.6 3804.5 4124.9 4139.2 4144.9 4828.5 4841.2 4841.9 4842.8 4857.7 4997.3 5180.9 5915.5 5943.2 5943.7 1112.6 1146.9 1164.9 1256.6 1361.5 798.7 908.2 911.4 919.4 922.3 4658.6 4810.5 5296.9 5320.4 5387.6 5123.5 5799.9 5802.8 5844.5 5855.6 5029.2 5039.5 5180.6 5217.6 5325.3 2776.7 3042.9 3046.6 3081.9 3099.6 5689.9 5842.1 6152.7 6163.3 6191.0 4709.8 5051.4 5207.7 5943.3 6170.9 4912.8 5785.6 5841.8 6063.0 6229.5 4921.5 5089.9 5204.8 5639.6 5699.7 5101.7 5204.7 5438.9 5478.8 5485.4 5215.0 5489.9 5740.6 6160.5 6207.2 3347.6 4041.7 4057.2 4067.4 4096.5 5579.6 6025.4 6041.3 6067.9 6104.4 4876.7 5510.6 5865.9 5876.6 5876.8 5875.5 5896.0 5901.3 5911.3 5936.5 4751.0 4822.1 5269.3 5410.6 5766.7 5307.6 5719.9 5903.0 5952.0 5967.9 5039.5 5774.3 5841.7 5846.5 5858.8 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20907.39 Median:4662.32 stddev:31189.68 (669.0%) stddev-sans-first:31240.28 09:41:56 INFO - PROCESS | 1643 | Values: 703.3 735.7 787.5 818.2 839.2 14630.1 15314.1 15380.3 15423.5 15478.7 13418.4 13591.5 13759.4 13826.9 13830.5 798.3 832.9 842.3 851.3 855.8 804.3 831.0 847.2 848.6 850.7 873.6 876.7 888.8 890.0 896.6 859.7 867.2 868.5 880.2 880.5 651.3 658.6 660.5 662.8 663.0 12517.4 12580.2 12615.7 12648.9 12675.2 10153.4 11736.2 12102.0 12102.1 12337.7 10515.6 10590.6 10772.4 10883.8 11071.7 3655.7 3656.4 3681.9 3685.5 3745.3 91545.0 96600.0 97054.0 97738.5 98245.5 8890.2 9105.8 9121.4 9209.9 9436.5 18587.9 18673.7 18825.2 18939.1 19081.3 7615.2 7726.5 7795.3 7920.8 8077.2 4302.9 4398.2 4487.2 4488.3 4612.7 3434.8 3563.3 3579.7 3696.3 3869.8 627.6 705.6 706.7 713.7 727.8 614.5 640.4 646.8 673.9 678.3 9258.9 9273.0 9277.3 9367.0 9470.2 555.0 647.2 667.5 680.6 682.3 4215.0 4286.6 4488.1 4636.9 4687.8 82206.4 95665.6 100429.5 104652.0 114358.9 108595.5 108777.5 110586.0 113416.0 115317.0 60915.4 79510.2 92600.0 99246.5 99723.0 7737.8 9612.3 9812.2 9832.7 9899.4 54191.7 54768.0 55860.0 56382.4 57474.1 51404.7 54700.7 81619.6 84155.0 85631.9 12188.8 12676.9 12767.3 12968.2 13430.2 14216.9 14385.5 14449.9 14455.0 14460.6 13280.5 13292.5 13313.3 13316.3 13450.7 13678.0 13762.9 13781.9 13985.9 14586.7 13024.7 13052.0 13141.4 13564.3 13785.1 11231.4 11440.5 11450.0 11674.6 11730.6 9584.7 11092.9 11269.6 11688.4 11813.9 09:41:56 INFO - PROCESS | 1643 | -------- Summary: end -------- 09:41:56 INFO - PROCESS | 1643 | 09:41:56 INFO - TEST-INFO | 1643: exit 0 09:41:56 INFO - TEST-OK | dromaeo_css | took 1115070ms 09:41:56 INFO - TEST-START | kraken 09:41:56 INFO - operating with platform_type : mac_ 09:41:56 INFO - Initialising browser for kraken test... 09:41:56 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp6U40Bu/profile http://localhost:49241/getInfo.html) 09:41:59 INFO - PROCESS | 1656 | 2017-01-25 09:41:59.599 plugin-container[1658:9885] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 09:41:59 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:59 INFO - PROCESS | 1656 | 2017-01-25 09:41:59.599 plugin-container[1658:9885] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:41:59 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:59 INFO - PROCESS | 1656 | 2017-01-25 09:41:59.599 plugin-container[1658:9885] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:41:59 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 09:41:59 INFO - PROCESS | 1656 | colorDepth:24 09:41:59 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 09:41:59 INFO - PROCESS | 1656 | __metrics 09:41:59 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:42:00 INFO - TEST-INFO | 1656: exit 0 09:42:00 INFO - Browser initialized. 09:42:00 INFO - Running cycle 1/1 for kraken test... 09:42:00 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp6U40Bu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:42:02 INFO - PROCESS | 1659 | 2017-01-25 09:42:02.812 plugin-container[1660:10009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 09:42:02 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:42:02 INFO - PROCESS | 1659 | 2017-01-25 09:42:02.812 plugin-container[1660:10009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 09:42:02 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:42:02 INFO - PROCESS | 1659 | 2017-01-25 09:42:02.812 plugin-container[1660:10009] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:42:12 INFO - PROCESS | 1659 | RSS: Main: 177164288 09:42:12 INFO - PROCESS | 1659 | 09:42:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:42:16 INFO - PROCESS | 1659 | RSS: Main: 190570496 09:42:16 INFO - PROCESS | 1659 | 09:42:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:42:19 INFO - PROCESS | 1659 | RSS: Main: 198062080 09:42:19 INFO - PROCESS | 1659 | 09:42:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:42:23 INFO - PROCESS | 1659 | RSS: Main: 201834496 09:42:23 INFO - PROCESS | 1659 | 09:42:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:42:25 INFO - PROCESS | 1659 | RSS: Main: 202145792 09:42:25 INFO - PROCESS | 1659 | 09:42:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:42:28 INFO - PROCESS | 1659 | RSS: Main: 203476992 09:42:28 INFO - PROCESS | 1659 | 09:42:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:42:32 INFO - PROCESS | 1659 | RSS: Main: 206921728 09:42:32 INFO - PROCESS | 1659 | 09:42:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:42:35 INFO - PROCESS | 1659 | RSS: Main: 207654912 09:42:35 INFO - PROCESS | 1659 | 09:42:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:42:38 INFO - PROCESS | 1659 | RSS: Main: 207507456 09:42:38 INFO - PROCESS | 1659 | 09:42:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:42:40 INFO - PROCESS | 1659 | RSS: Main: 206741504 09:42:40 INFO - PROCESS | 1659 | 09:42:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:42:43 INFO - PROCESS | 1659 | RSS: Main: 206086144 09:42:43 INFO - PROCESS | 1659 | 09:42:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:42:46 INFO - PROCESS | 1659 | RSS: Main: 206000128 09:42:46 INFO - PROCESS | 1659 | 09:42:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:42:49 INFO - PROCESS | 1659 | RSS: Main: 206589952 09:42:49 INFO - PROCESS | 1659 | 09:42:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:42:53 INFO - PROCESS | 1659 | RSS: Main: 206352384 09:42:53 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:42:55 INFO - PROCESS | 1659 | RSS: Main: 208494592 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | __start_tp_report 09:42:55 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 09:42:55 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 09:42:55 INFO - PROCESS | 1659 | |i|pagename|runs| 09:42:55 INFO - PROCESS | 1659 | |0;ai-astar;104;115;116;116;116;114;116;117;116;117 09:42:55 INFO - PROCESS | 1659 | |1;audio-beat-detection;140;111;111;143;127;171;149;100;173;141 09:42:55 INFO - PROCESS | 1659 | |2;audio-dft;189;198;196;192;196;192;194;195;196;192 09:42:55 INFO - PROCESS | 1659 | |3;audio-fft;83;64;60;75;75;84;83;53;94;87 09:42:55 INFO - PROCESS | 1659 | |4;audio-oscillator;86;70;111;102;95;71;84;68;71;87 09:42:55 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;89;85;88;86;88;85;111;105;103;132 09:42:55 INFO - PROCESS | 1659 | |6;imaging-darkroom;94;95;95;94;94;93;96;94;95;96 09:42:55 INFO - PROCESS | 1659 | |7;imaging-desaturate;89;88;104;110;90;96;88;94;85;87 09:42:55 INFO - PROCESS | 1659 | |8;json-parse-financial;55;55;54;57;58;58;59;60;57;57 09:42:55 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;66;70;66;73;72;72;67;68;69;71 09:42:55 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;83;83;83;82;84;82;83;82;87;83 09:42:55 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;166;147;158;152;154;139;143;142;203;183 09:42:55 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;168;166;163;164;210;236;168;162;161;163 09:42:55 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;66;66;65;67;66;66;66;66;66;67 09:42:55 INFO - PROCESS | 1659 | __end_tp_report 09:42:55 INFO - PROCESS | 1659 | __start_cc_report 09:42:55 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,905 09:42:55 INFO - PROCESS | 1659 | __end_cc_report 09:42:55 INFO - PROCESS | 1659 | __startTimestamp1485366175277__endTimestamp 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | ------- Summary: start ------- 09:42:55 INFO - PROCESS | 1659 | Number of tests: 14 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.00 stddev:3.86 (3.3%) stddev-sans-first:0.93 09:42:55 INFO - PROCESS | 1659 | Values: 104.0 115.0 116.0 116.0 116.0 114.0 116.0 117.0 116.0 117.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:142.00 stddev:24.67 (17.4%) stddev-sans-first:26.13 09:42:55 INFO - PROCESS | 1659 | Values: 140.0 111.0 111.0 143.0 127.0 171.0 149.0 100.0 173.0 141.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:194.00 Median:195.50 stddev:2.71 (1.4%) stddev-sans-first:2.19 09:42:55 INFO - PROCESS | 1659 | Values: 189.0 198.0 196.0 192.0 196.0 192.0 194.0 195.0 196.0 192.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:75.80 Median:83.00 stddev:13.07 (15.7%) stddev-sans-first:13.60 09:42:55 INFO - PROCESS | 1659 | Values: 83.0 64.0 60.0 75.0 75.0 84.0 83.0 53.0 94.0 87.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:77.50 stddev:14.81 (19.1%) stddev-sans-first:15.70 09:42:55 INFO - PROCESS | 1659 | Values: 86.0 70.0 111.0 102.0 95.0 71.0 84.0 68.0 71.0 87.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:85.50 stddev:15.48 (18.1%) stddev-sans-first:16.13 09:42:55 INFO - PROCESS | 1659 | Values: 89.0 85.0 88.0 86.0 88.0 85.0 111.0 105.0 103.0 132.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:1.00 09:42:55 INFO - PROCESS | 1659 | Values: 94.0 95.0 95.0 94.0 94.0 93.0 96.0 94.0 95.0 96.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:88.50 stddev:8.13 (9.2%) stddev-sans-first:8.49 09:42:55 INFO - PROCESS | 1659 | Values: 89.0 88.0 104.0 110.0 90.0 96.0 88.0 94.0 85.0 87.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.50 stddev:1.89 (3.3%) stddev-sans-first:1.86 09:42:55 INFO - PROCESS | 1659 | Values: 55.0 55.0 54.0 57.0 58.0 58.0 59.0 60.0 57.0 57.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.50 stddev:2.59 (3.7%) stddev-sans-first:2.44 09:42:55 INFO - PROCESS | 1659 | Values: 66.0 70.0 66.0 73.0 72.0 72.0 67.0 68.0 69.0 71.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:1.48 (1.8%) stddev-sans-first:1.56 09:42:55 INFO - PROCESS | 1659 | Values: 83.0 83.0 83.0 82.0 84.0 82.0 83.0 82.0 87.0 83.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:156.00 stddev:20.34 (13.0%) stddev-sans-first:21.40 09:42:55 INFO - PROCESS | 1659 | Values: 166.0 147.0 158.0 152.0 154.0 139.0 143.0 142.0 203.0 183.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:167.00 stddev:25.58 (15.3%) stddev-sans-first:26.96 09:42:55 INFO - PROCESS | 1659 | Values: 168.0 166.0 163.0 164.0 210.0 236.0 168.0 162.0 161.0 163.0 09:42:55 INFO - PROCESS | 1659 | 09:42:55 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 09:42:55 INFO - PROCESS | 1659 | Values: 66.0 66.0 65.0 67.0 66.0 66.0 66.0 66.0 66.0 67.0 09:42:55 INFO - PROCESS | 1659 | -------- Summary: end -------- 09:42:55 INFO - PROCESS | 1659 | 09:42:56 INFO - TEST-INFO | 1659: exit 0 09:42:56 INFO - TEST-OK | kraken | took 59572ms 09:42:56 INFO - SUITE-END | took 1174s 09:42:56 INFO - Completed test suite (00:19:34) 09:42:56 INFO - javascript benchmark 09:42:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7316.57965914237, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3639.3994990826513, 3657.4513822926556, 3678.8344524496397, 3688.760230585012, 3711.164987877728, 6028.306744724353, 6105.877882442349, 6115.480184184344, 6145.446909778119, 6153.16932214151, 6103.938960610392, 6170.938290617092, 6182.072689096638, 6198.659073750942, 6236.501079913607, 5441.455854414567, 5493.890122197554, 5506.834794956158, 5510.559155267589, 5562.332520097574, 4778.952210477885, 5105.593408018513, 5565.916511252334, 5572.721363931808, 5597.8600534986745, 5340.813071542497, 5348.625596208267, 5534.0592099343, 5545.3622833374075, 5548.5838562108, 5412.648177868446, 5476.315460567449, 5518.255035570187, 5522.696251706155, 5579.637323573955, 5369.7583608737705, 5463.945360546383, 5485.807996720096, 5493.011257973563, 5516.393196748354, 5946.940530594682, 6168.845778855564, 6181.81454556364, 6197.03945888388, 6198.721057552421, 4973.52751488608, 6270.3416141305415, 6345.111684364147, 6347.460465860408, 6370.394812492806, 6475.967620161916, 6508.414242718178, 6519.3154718754795, 6539.803805885831, 6550.181227346582, 5600.691961942132, 6217.96891015542, 6226.533010024266, 6231.065340198916, 6260.436560709512, 6562.5406221564035, 6585.242697089896, 6633.2703402625675, 6682.766103186365, 6693.765718199839, 6371.745130194833, 6387.265464471552, 6389.712462939179, 6391.808245752635, 6395.616263024233, 6307.369263073656, 6328.854600236852, 6536.313687062885, 6540.0, 6540.574862633914, 6307.842303942346, 6354.936450635434, 6364.363563643691, 6453.419192272718, 6465.353464653497, 5375.086235339923, 6412.775552855627, 6436.259830119502, 6452.967735161388, 6457.806265812033, 5092.898142037138, 5210.76551555181, 5216.347956505437, 5232.738363081907, 5253.50091741284, 3831.7700937943814, 4634.9536504634525, 4645.372874661877, 4654.953450465452, 4748.525147485224, 4860.975695121572, 5968.761249550056, 6198.81403557894, 6228.719707613168, 6243.0, 6267.4986001119805, 6509.121268628674, 6534.477241820643, 6561.212654481493, 6568.441682456949, 6113.962834745899, 6131.682521312785, 6137.8673257933415, 6162.476189523851, 6232.56372053961, 5741.339745929188, 5741.45456181662, 5742.799002034993, 5743.310802703619, 5764.7117644117625, 6724.495662825308, 6738.898916516354, 6764.627945463047, 6764.729410823709, 6774.187097548227, 6342.556021078664, 6393.552451328363, 6443.130177426175, 6484.157059582224, 6497.837554061186, 6660.433863121689, 6743.797686069426, 6755.088063111417, 6761.0534525167495, 6766.86466270662, 6433.388828061326, 6448.355164483514, 6448.5486015980305, 6458.095866578588, 6536.509761767981, 6143.665407145256, 6301.369863013662, 6401.263854656774, 6429.7428102875365, 6524.478041756743, 6309.873802524015, 6325.620462772248, 6327.96836015817, 6342.365763423805, 6347.9525878231425, 6317.399847014526, 6348.746050158126, 6363.522735794926, 6377.075324078076, 6441.484681225397, 6127.877442451037, 6152.969235153795, 6177.84555386119, 6193.659348735863, 6195.845103872439, 6185.84535386619, 6289.0, 6294.213223347082, 6299.527535434952, 6299.811005669837, 6231.376862313732, 6232.937670623236, 6242.126102345766, 6245.281792593728, 6257.061440783918], "value": 5967.834997394693, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.85364463838533, 494.3869601693901, 512.3365242011596, 517.6376536424499, 565.0337922153116, 31034.84482577587, 38965.83102506926, 40438.393424098664, 40485.797571012175, 40681.38977915334, 41900.0, 42459.78770106153, 42484.36273455901, 42556.78721606388, 42645.36031959523, 9356.625734970594, 9538.952305238465, 9583.0, 9605.327627066074, 9729.902700973005, 9381.76545586359, 9436.764080897963, 9477.47873866937, 9566.521673916277, 9567.043295670446, 8180.713675021376, 9577.233821294314, 9613.327067105305, 9620.374675646095, 9652.17956473697, 9333.113354231336, 9489.193418559431, 9493.240540756722, 9511.571979260882, 9526.618935242583, 9565.904340956571, 9654.420734755937, 9669.081437263449, 9734.659286924994, 9751.561179746857, 39820.0, 40621.79689101536, 40990.795046024876, 41091.972700682425, 41222.38166427506, 3276.705096541299, 3453.5510383649967, 3485.686288234071, 3662.8901132966053, 4148.377743338493, 30916.0, 31127.533087003714, 31497.685023149934, 32115.0, 32144.839275803704, 3199.2001999500126, 3286.3263031078573, 3310.867565297385, 3348.2466445049668, 3371.089805752458, 511162.0, 514169.4291528556, 517744.0, 517800.0, 518203.81796181935, 65107.0, 65317.67341163264, 65387.673061634385, 65583.0, 65697.01454478186, 57554.712226439435, 57590.424095758506, 69011.30988690049, 69171.0, 69321.65339173272, 14516.491922782652, 16399.83600164007, 17030.233639486254, 17280.481585552454, 17323.133843307784, 12863.871361286454, 12968.35158242084, 12985.935070324587, 12989.675258118623, 13037.543685970946, 19322.80677193238, 19967.301144459874, 19981.0, 19985.000749962444, 19999.400017999484, 8018.759437216776, 8023.879641805378, 8063.879041814377, 8115.553644549606, 8135.837283254302, 891.5765011619429, 893.4817805672694, 1005.9245556583286, 1056.6513050693252, 1084.3006260961793, 29557.408851823275, 31087.222819429244, 31300.247180213406, 31390.37219255602, 31670.0, 988.7577543501889, 1084.224779282801, 1097.550004498152, 1118.9440527973568, 1128.1820680006929, 15984.0, 16025.038497690175, 16253.59366015859, 16255.51233462998, 16470.929389589877, 698021.0, 699490.0, 699521.0, 699566.0, 707277.0, 45985.0, 58380.708096459246, 59246.70376648089, 59768.40231597628, 60031.0, 1297.740451909622, 1321.5506727712625, 1405.0726520496423, 1408.9295535223198, 1417.4755340524073, 29822.403551928404, 30423.391532169677, 31926.0, 33025.50461743124, 33244.50133248052, 46505.76747116243, 58193.127103092644, 59318.0, 59325.0, 59391.40608594032, 643870.0, 645733.0, 656118.7194063999, 656251.7187414032, 657965.0, 43861.78069109634, 43923.78038109917, 44637.55362446334, 44803.775981119885, 44931.97806098668, 48005.0, 48144.51855481401, 48492.03015939732, 48821.26768098411, 48971.26543101785, 36301.45547816838, 39449.2110157801, 39966.80099597017, 40747.777566673045, 42006.579934200265, 39965.200695986496, 40237.59762402456, 40887.0, 41436.792816035726, 41582.58417415787, 38559.6144038556, 39402.0, 39465.210695786496, 39480.80259598684, 40570.59429405668, 30305.78776848901, 33366.83316583402, 34614.96155115351, 36269.81865090658, 36414.08964775902, 26319.605205921544, 26692.8665356672, 26752.0, 26812.195634131007, 26886.32784180411], "value": 20872.58397126505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.58809661758556, 389.9044734040161, 391.3444979659071, 399.5365376163651, 417.3301850529899, 19625.607487850233, 20440.999999999964, 20862.06120724567, 20885.895570522163, 21111.472213194676, 20509.38471845847, 20635.277765278217, 20866.89566552169, 20969.37091887242, 21056.368308950718, 5720.342160651537, 6134.355892631276, 6180.165677633507, 6182.505399568046, 6196.442320191182, 5874.823755287348, 6032.336442991267, 6070.028795392738, 6102.572819902609, 6140.53945954053, 6033.245844269466, 6048.96975515124, 6053.51571874249, 6061.787837425691, 6066.787662431816, 5730.398308177623, 6026.60827046245, 6055.576109672303, 6069.1199776032445, 6085.786997455112, 5247.685138891678, 5303.416624171338, 5321.4412486688725, 5333.919991200135, 5342.038433082043, 25231.738413079274, 25340.873295633588, 25473.61789573158, 25607.0, 26205.60691589628, 2346.0967527501866, 2500.4249022724694, 2675.8795854186415, 2845.672747634028, 3186.426443240216, 15285.235738213156, 15352.0, 15467.303971321206, 15834.683306333924, 16232.944858584155, 1614.555997100795, 1895.8862468251948, 1907.2657027044688, 1959.8726082804571, 1968.4488343263909, 78057.35688173011, 120663.0, 121583.0, 127453.36273318574, 129500.7049929498, 11216.046636035866, 11298.265612735146, 11753.118516111326, 11757.412129393668, 11970.982466490446, 14020.298985050911, 14209.076410033314, 14237.074590151815, 14247.78628320576, 14275.643108922151, 8466.703665371806, 8598.699045533502, 8634.78413039679, 8711.651533938571, 8813.91186088131, 8748.8687669685, 8810.559472026373, 8855.822883542292, 9012.143846334588, 9046.457212567268, 6718.496112791462, 6801.829954251183, 6819.079424277758, 6835.487338449636, 6850.965745171242, 1692.3230707717012, 1936.8644194906221, 1953.765548134233, 1980.0792631426168, 1982.3755517012096, 898.2768871058861, 911.8905731312152, 1009.7021378693303, 1015.8577799108131, 1029.2332212501585, 10810.891891081146, 11722.593288805398, 11795.823062654066, 11870.0, 12320.938395308145, 435.4948260018364, 439.2313451459945, 447.37144312241355, 455.1874903297598, 455.3533981745929, 6187.597806142587, 6233.065040244, 6277.686115694289, 6309.747610095545, 6351.396617321347, 536802.0, 634029.0, 642926.7853660702, 643833.0, 666476.0, 79054.0, 79446.6027669858, 118175.40912295383, 132911.02533848, 136086.0, 451.7583093045205, 464.4937018649621, 472.487351223919, 475.4508542633264, 476.53061734191795, 11513.53945842157, 11558.133140014701, 11637.301761894314, 11865.940670296593, 11891.048716102863, 43822.561774383124, 55110.72444637751, 55481.72259138679, 57308.71345643245, 57419.1387129185, 76831.0, 77382.71407431144, 79988.0, 84141.0, 84253.57873210595, 8180.222878826502, 8500.72489126636, 8510.74467765968, 8542.530160841214, 8558.828823423371, 8911.598978046133, 12607.936960315139, 12720.6819829505, 12764.0, 13048.869511304765, 10973.23187376876, 11134.888651113386, 11305.7738845221, 11334.433278335886, 11413.657590272305, 9791.706248812547, 10829.67510974672, 10990.120790336914, 11021.724456888642, 11320.660380188607, 11105.944470277596, 11144.665660030212, 11244.156688248579, 11444.370559619301, 11491.885081149081, 7482.70069197226, 8216.507009579444, 8299.0, 8354.955630546181, 8532.933383327085, 10208.591656333663, 10486.0, 10873.891261087289, 10926.289791163548, 11166.385848778395], "value": 9066.983493588139, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.7139907717994, 1512.357248169528, 1708.906010169441, 1834.642244762272, 1844.944651660449, 7710.92289077109, 7974.641141148648, 8161.510309381434, 8572.442791218566, 8613.827723445527, 8114.553699546523, 8373.455725377846, 8382.161783821615, 8480.872786908201, 8495.872561911576, 2382.059086660768, 2420.770026847447, 2428.0651948999584, 2428.538577670237, 2432.5499782540196, 2413.9758602414013, 2445.217530390276, 2462.150558057467, 2468.950620987579, 2473.4063824682034, 2379.5003049359657, 2403.8798060096924, 2414.987925060381, 2424.3211900667843, 2429.392651837041, 2181.6691817991014, 2323.5352929414016, 2386.1767690146958, 2386.451116243265, 2414.3964008997664, 2390.605550084234, 2395.47299594089, 2412.0834083048503, 2415.1426243683413, 2434.7078350597862, 8444.831103377897, 8493.915060849375, 8514.361422893307, 8562.957185214096, 8565.48607083577, 5170.793168273264, 5301.363836339626, 5325.707086110262, 5327.360716713981, 5337.3061502004875, 3488.0930957951114, 3488.180277634754, 3548.6983606393487, 3549.183687751806, 3586.677199052098, 1030.2273295028729, 1070.6252811515985, 1095.9726006849892, 1101.3502033800096, 1111.9499622517005, 10877.619283325124, 10888.61889833852, 10932.672019839418, 10955.780884382348, 10971.615993440191, 3868.9806550967064, 4534.455865296186, 4725.9527404726205, 4726.503717109654, 4846.660733748674, 9308.976012638605, 9572.186364159124, 9627.855582166274, 9637.855432168522, 9644.565994530263, 4136.896577585584, 5037.974810125926, 5288.709120998382, 5290.127129023668, 5299.33758280215, 4311.525732169459, 4517.81928722854, 4719.339292499053, 4830.29960655703, 4884.7069175849565, 3425.845836937344, 3917.5298964124486, 3937.4881265435884, 4036.8788936331957, 4056.8782936511952, 1302.2121616421928, 1309.5351150341492, 1313.7044165062784, 1314.0210543145417, 1316.0655934286763, 938.216589148069, 940.4874343482818, 942.1002942190192, 950.9334346595672, 981.0728861226073, 5686.9715651421475, 5769.0, 5927.347991720907, 6008.497875531118, 6014.308354539196, 383.411463403674, 463.5711966431038, 467.2547287077107, 469.0712389468871, 475.6385147288017, 3961.3265744823448, 3977.125032492847, 4013.4381186633896, 4014.3175660137845, 4065.10567675111, 9777.0, 10082.24383171265, 10161.441120738282, 10312.071913527818, 10331.845022324671, 10084.394936303845, 10170.796584068277, 10186.08325250731, 10189.98100189975, 10274.897251027394, 7827.882581761278, 7901.881471777928, 7924.801879953047, 8094.433389662667, 8113.350931925432, 5874.559408044499, 5923.881522369615, 6002.6398416093425, 6009.399060094131, 6096.055111457731, 9308.906910930804, 9414.293927955294, 9427.811443770948, 9433.622655093719, 9565.326067938222, 9583.760405989906, 9709.417434953926, 9751.171150452008, 9759.219262459128, 9790.46152461593, 6742.8651426972165, 6939.722411103499, 7084.397826184626, 7261.745838895613, 7386.852262954818, 7884.487508311941, 7985.800354991172, 7997.320227780471, 8149.551774652214, 8158.224968627971, 7203.963980180066, 7287.708491660486, 7344.669489872862, 7436.479446438698, 7454.627268636438, 5903.468687818117, 6078.5441091919165, 7167.928320716935, 7176.96411517939, 7229.240929702516, 6671.399574038166, 6716.764913228014, 6726.8990965136545, 6731.32686731323, 6806.625635589892, 4950.430700469348, 4982.327385802871, 4991.975040124777, 4997.300377947163, 5032.874178145576, 4993.575546078624, 5023.422306434807, 5035.7985680572365, 5036.722980236123, 5067.5185857343495], "value": 4435.224907838271, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.4709722825353, 317.77120473259254, 324.9317643294909, 327.73944713952403, 329.26409474823765, 4433.8669839904815, 4815.0, 4886.218205087187, 5343.664083979005, 5682.545396368291, 4395.780210989454, 5408.891822163554, 5545.972270138654, 5568.0, 5588.469095435938, 3747.494088298086, 3818.06457417933, 4277.657787377002, 4279.957200427987, 4288.228118938589, 3332.9000129996016, 3542.8937131885955, 4255.510616279136, 4266.957330426702, 4307.892302692442, 3898.966773804944, 4131.29767939451, 4188.178142508019, 4198.958010419902, 4219.915601687964, 3649.288388764192, 4179.122384299286, 4181.351890456999, 4196.51740049892, 4208.516020657602, 3442.896713098586, 3620.5655321361605, 3634.9636503635156, 3652.3425783358985, 3655.574326012857, 4472.373867658531, 4552.112338094073, 5048.974755126201, 5054.216596427523, 5176.275321454999, 4025.81883815229, 4120.96975756061, 4437.334399840018, 4800.231962885957, 4810.903781924377, 4787.4494433139935, 5055.51972562606, 5842.444967728059, 5846.590738648296, 5869.85325366865, 3648.087978005499, 3736.327461057009, 3977.960220397788, 3988.8205030773684, 4005.439238506611, 4824.975875120602, 5597.132444471114, 5687.800926967537, 6140.416660417253, 6262.561620686553, 3269.9673003269663, 3411.9317613647586, 4034.435179074932, 4076.490438695163, 4103.425520427142, 3448.6034106078123, 3804.524434445694, 4124.886280704193, 4139.234241665302, 4144.896377590584, 4828.46886842447, 4841.2496063110275, 4841.8547443576745, 4842.757862106951, 4857.732824694675, 4997.30037794709, 5180.948190518122, 5915.467607915309, 5943.167956486095, 5943.732532036069, 1112.6328311657135, 1146.9025132863633, 1164.945724119673, 1256.604169686546, 1361.4826365980864, 798.7364169823944, 908.246155690775, 911.352939413011, 919.4161707315769, 922.294444749767, 4658.58072773452, 4810.542998415145, 5296.867578310496, 5320.388155362183, 5387.568994480511, 5123.538881500608, 5799.927013502515, 5802.767889284381, 5844.464099219996, 5855.560832937546, 5029.195328747456, 5039.470855560114, 5180.611454140954, 5217.634765566374, 5325.254464374991, 2776.681076488652, 3042.9239269018008, 3046.558249053875, 3081.922951926175, 3099.628044634658, 5689.886202275931, 5842.094475356326, 6152.692365381713, 6163.291221509494, 6190.969045154836, 4709.811607535728, 5051.444341122474, 5207.661502002357, 5943.255748878266, 6170.876582468325, 4912.778924948386, 5785.598976424737, 5841.824745257649, 6062.969685151635, 6229.5327850410495, 4921.530321123671, 5089.931114466003, 5204.7587495920525, 5639.6334238274385, 5699.686517241592, 5101.7194054327365, 5204.713740744295, 5438.945610543844, 5478.835634930959, 5485.369182543899, 5215.0, 5489.910017037333, 5740.560261888764, 6160.507159427325, 6207.206756959296, 3347.6484969077896, 4041.656459201001, 4057.2291264660803, 4067.4102255172243, 4096.487939007624, 5579.553635709215, 6025.397460253939, 6041.2750469943885, 6067.939320606737, 6104.405627608337, 4876.65920358223, 5510.586705996986, 5865.912011319919, 5876.588638795414, 5876.823695289148, 5875.471207591167, 5895.970520147372, 5901.29184497863, 5911.261092363455, 5936.465718085393, 4750.952490475189, 4822.059698358927, 5269.314989051399, 5410.59420543453, 5766.740496677576, 5307.601929855199, 5719.885602287848, 5902.970485147547, 5952.0, 5967.910481342697, 5039.521245481674, 5774.307083150049, 5841.737121829443, 5846.503047241032, 5858.794942177004], "value": 4147.007077645963, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.2756261051119, 735.7093947890584, 787.488132713736, 818.2104269380047, 839.2446797881904, 14630.122192668434, 15314.0811551307, 15380.307886145123, 15423.537293881187, 15478.69042619147, 13418.396172172252, 13591.456341746343, 13759.449622015107, 13826.930865345685, 13830.515931942384, 798.3014861995754, 832.8667413213872, 842.2546046748641, 851.3444647621359, 855.8159995600945, 804.2761514636816, 831.0, 847.174005344787, 848.6053984897021, 850.740524140137, 873.5763154869915, 876.6581033396981, 888.7555922121404, 889.9688510902088, 896.5920506169709, 859.7291853066264, 867.2281669314315, 868.4702331577732, 880.1594477274252, 880.467317273047, 651.3226244705501, 658.6180015590994, 660.5310229736892, 662.7514681994252, 663.0352836622702, 12517.37274154354, 12580.182288151242, 12615.684607884785, 12648.873511264954, 12675.239485630891, 10153.441560714158, 11736.178467507278, 12102.0, 12102.092343074217, 12337.69155771104, 10515.579376824919, 10590.576376944913, 10772.35365878042, 10883.83674244887, 11071.667849964513, 3655.6892664123316, 3656.3784156693428, 3681.9447708284397, 3685.4840322354894, 3745.3445647011663, 91545.0, 96600.0, 97054.0, 97738.51130744301, 98245.5087724564, 8890.199882010651, 9105.817883642423, 9121.407108537924, 9209.861852072225, 9436.528173591292, 18587.853847113107, 18673.719894201597, 18825.246990120515, 18939.053047347854, 19081.332153374566, 7615.200403957616, 7726.4591478597085, 7795.259450352208, 7920.801979950547, 8077.192280771876, 4302.913941721211, 4398.186335527937, 4487.237169681162, 4488.349189367524, 4612.7001744886475, 3434.793912365266, 3563.3407819553468, 3579.713622910161, 3696.2977034363385, 3869.7678139311734, 627.6140173793084, 705.5978092487336, 706.7384718277953, 713.6859781696037, 727.756201672435, 614.4869034356274, 640.3788325324427, 646.7768619826153, 673.8989151627295, 678.3250665587772, 9258.861117083248, 9273.0, 9277.304202184863, 9366.953165234267, 9470.242380609534, 555.0315785315404, 647.1651569475324, 667.4960404894274, 680.5678394219607, 682.2563405887507, 4214.967333003373, 4286.635635970977, 4488.12481566098, 4636.86089417318, 4687.789049492781, 82206.35587288221, 95665.56501652481, 100429.49785251026, 104652.0, 114358.85641143649, 108595.45702271438, 108777.45611271892, 110586.0, 113416.0, 115317.0, 60915.39084609186, 79510.21714239974, 92600.0, 99246.50376748215, 99723.0, 7737.767866964, 9612.279079068958, 9812.165965892978, 9832.705018849447, 9899.406035637885, 54191.72904135454, 54767.987760673546, 55860.0, 56382.43617563772, 57474.13788793255, 51404.742976286376, 54700.726496367264, 81619.5919020401, 84155.0, 85631.8592035204, 12188.817167742314, 12676.873231267939, 12767.297798621164, 12968.221906685629, 13430.19418834873, 14216.857831421554, 14385.496507622183, 14449.85550144485, 14455.0, 14460.566183014527, 13280.468781248643, 13292.468301268227, 13313.33433328349, 13316.33418329099, 13450.715108250852, 13678.0, 13762.862371376157, 13781.89744820432, 13985.93007034999, 14586.708265834835, 13024.674383140118, 13051.980037914047, 13141.408636611184, 13564.321783910567, 13785.103968242433, 11231.38227397501, 11440.542378304775, 11450.026747726204, 11674.591389301333, 11730.589429369931, 9584.66453674118, 11092.889071109508, 11269.605563805228, 11688.357140357362, 11813.881861181279], "value": 7384.066168318947, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 116.0, 116.0, 116.0, 114.0, 116.0, 117.0, 116.0, 117.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 111.0, 143.0, 127.0, 171.0, 149.0, 100.0, 173.0, 141.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 198.0, 196.0, 192.0, 196.0, 192.0, 194.0, 195.0, 196.0, 192.0], "value": 194.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 64.0, 60.0, 75.0, 75.0, 84.0, 83.0, 53.0, 94.0, 87.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 111.0, 102.0, 95.0, 71.0, 84.0, 68.0, 71.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 86.0, 88.0, 85.0, 111.0, 105.0, 103.0, 132.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 93.0, 96.0, 94.0, 95.0, 96.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 104.0, 110.0, 90.0, 96.0, 88.0, 94.0, 85.0, 87.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 57.0, 58.0, 58.0, 59.0, 60.0, 57.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 66.0, 73.0, 72.0, 72.0, 67.0, 68.0, 69.0, 71.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 82.0, 84.0, 82.0, 83.0, 82.0, 87.0, 83.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 147.0, 158.0, 152.0, 154.0, 139.0, 143.0, 142.0, 203.0, 183.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 163.0, 164.0, 210.0, 236.0, 168.0, 162.0, 161.0, 163.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 66.1, "unit": "score"}]}]} 09:42:56 INFO - Return code: 0 09:42:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:42:56 ERROR - # TBPL SUCCESS # 09:42:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:42:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:42:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:42:56 INFO - 2017-01-25 09:42:56.581 screenresolution[1664:10398] starting screenresolution argv=screenresolution get 09:42:56 INFO - 2017-01-25 09:42:56.586 screenresolution[1664:10398] Display 0: 1600x1200x32@60 09:42:56 INFO - 2017-01-25 09:42:56.592 screenresolution[1665:10401] starting screenresolution argv=screenresolution list 09:42:56 INFO - Available Modes on Display 0 09:42:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:42:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:42:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:42:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:42:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:42:56 INFO - Intel Iris: 09:42:56 INFO - Chipset Model: Intel Iris 09:42:56 INFO - Type: GPU 09:42:56 INFO - Bus: Built-In 09:42:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:42:56 INFO - Vendor: Intel (0x8086) 09:42:56 INFO - Device ID: 0x0a2e 09:42:56 INFO - Revision ID: 0x0009 09:42:56 INFO - Displays: 09:42:56 INFO - Display: 09:42:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:42:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:42:56 INFO - Display Serial Number: 1600x1200 60 09:42:56 INFO - Main Display: Yes 09:42:56 INFO - Mirror: Off 09:42:56 INFO - Online: Yes 09:42:56 INFO - Rotation: Supported 09:42:56 INFO - Return code: 0 09:42:56 INFO - Running post-action listener: _resource_record_post_action 09:42:56 INFO - [mozharness: 2017-01-25 17:42:56.833488Z] Finished run-tests step (success) 09:42:56 INFO - Running post-run listener: _resource_record_post_run 09:42:56 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39479296; Write bytes: 499690496; Read time: 251; Write time: 1607 09:42:56 INFO - TinderboxPrint: CPU usage
54.1% 09:42:56 INFO - TinderboxPrint: I/O read bytes / time
39,479,296 / 251 09:42:56 INFO - TinderboxPrint: I/O write bytes / time
499,690,496 / 1,607 09:42:56 INFO - TinderboxPrint: CPU idle
2,198.7 (45.9%) 09:42:56 INFO - TinderboxPrint: CPU system
965.8 (20.2%) 09:42:56 INFO - TinderboxPrint: CPU user
1,621.1 (33.9%) 09:42:56 INFO - TinderboxPrint: Swap in / out
230,764,544 / 0 09:42:56 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 186716672; Write bytes: 188979200; Read time: 16785; Write time: 422 09:42:56 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 37677056; Write bytes: 303809536; Read time: 235; Write time: 1157 09:42:57 INFO - Running post-run listener: _upload_blobber_files 09:42:57 INFO - Blob upload gear active. 09:42:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:42:57 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:42:57 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:42:57 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:42:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:42:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:42:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:42:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:58 INFO - (blobuploader) - INFO - Done attempting. 09:42:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:42:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:42:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:59 INFO - (blobuploader) - INFO - Done attempting. 09:42:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:42:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:43:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:43:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:43:01 INFO - (blobuploader) - INFO - Done attempting. 09:43:01 INFO - (blobuploader) - INFO - Iteration through files over. 09:43:01 INFO - Return code: 0 09:43:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:43:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:43:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9d566a2740b09d60dac6b2933493eb56c6173436687c404d9e0afea6a56b8c0b29a842c06ac67499ea94e1d316d675e5dd1f91bc1410f54450261f01549d15b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1efb5cc9684628778902d84e3bad8647ee1f224895a1644deb07415922c07268a1900e16618baf8ac7bc228880187c00e21d02d006db39b7a1ab9787cd3905d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1003351ae4e6a81151a588ee2ee518fbcd1f67c4ac7d7b1264fa8111d1479995994acec79879534ab010f4f01f4c76ae1c9399ed0df12ab82f726b18be7a86f7"} 09:43:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:43:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:43:01 INFO - Contents: 09:43:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9d566a2740b09d60dac6b2933493eb56c6173436687c404d9e0afea6a56b8c0b29a842c06ac67499ea94e1d316d675e5dd1f91bc1410f54450261f01549d15b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1efb5cc9684628778902d84e3bad8647ee1f224895a1644deb07415922c07268a1900e16618baf8ac7bc228880187c00e21d02d006db39b7a1ab9787cd3905d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1003351ae4e6a81151a588ee2ee518fbcd1f67c4ac7d7b1264fa8111d1479995994acec79879534ab010f4f01f4c76ae1c9399ed0df12ab82f726b18be7a86f7"} 09:43:01 INFO - Running post-run listener: copy_logs_to_upload_dir 09:43:01 INFO - Copying logs to upload dir... 09:43:01 INFO - mkdir: /builds/slave/test/build/upload/logs 09:43:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.233302 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-25 09:43:01.898168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 09:43:01.901706) ========= 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.iZRHUknhOL/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.myHw0WCc4R/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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/Mozilla-Beta/sha512/9d566a2740b09d60dac6b2933493eb56c6173436687c404d9e0afea6a56b8c0b29a842c06ac67499ea94e1d316d675e5dd1f91bc1410f54450261f01549d15b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1efb5cc9684628778902d84e3bad8647ee1f224895a1644deb07415922c07268a1900e16618baf8ac7bc228880187c00e21d02d006db39b7a1ab9787cd3905d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1003351ae4e6a81151a588ee2ee518fbcd1f67c4ac7d7b1264fa8111d1479995994acec79879534ab010f4f01f4c76ae1c9399ed0df12ab82f726b18be7a86f7"} build_url:https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011006 build_url: 'https://queue.taskcluster.net/v1/task/HD_9zaQySNaDlcOeQq84Jg/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9d566a2740b09d60dac6b2933493eb56c6173436687c404d9e0afea6a56b8c0b29a842c06ac67499ea94e1d316d675e5dd1f91bc1410f54450261f01549d15b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1efb5cc9684628778902d84e3bad8647ee1f224895a1644deb07415922c07268a1900e16618baf8ac7bc228880187c00e21d02d006db39b7a1ab9787cd3905d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1003351ae4e6a81151a588ee2ee518fbcd1f67c4ac7d7b1264fa8111d1479995994acec79879534ab010f4f01f4c76ae1c9399ed0df12ab82f726b18be7a86f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 09:43:01.935803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:43:01.936172) ========= 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.iZRHUknhOL/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.myHw0WCc4R/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.005022 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:43:01.985230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 09:43:01.985559) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 09:43:01.985894) ========= ========= Total master_lag: 0.18 =========