builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0243 starttime: 1485887466.98 results: success (0) buildid: 20170131075309 builduid: 298267ab9f6b4f61838e112a9b71d987 revision: 147b55311d5350e6d7022d031f725934d6c717a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:06.975695) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:06.976208) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:06.976520) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:07.004954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:07.005375) ========= 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.Rpocs4wEHl/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.BDbUVY81yL/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-31 10:31:07-- 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% 18.9M=0.001s 2017-01-31 10:31:07 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080381 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:07.110123) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:07.110528) ========= 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.Rpocs4wEHl/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.BDbUVY81yL/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.024529 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:07.224959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-31 10:31:07.225351) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 147b55311d5350e6d7022d031f725934d6c717a0 --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 147b55311d5350e6d7022d031f725934d6c717a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Rpocs4wEHl/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.BDbUVY81yL/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-31 10:31:07,334 truncating revision to first 12 chars 2017-01-31 10:31:07,334 Setting DEBUG logging. 2017-01-31 10:31:07,335 attempt 1/10 2017-01-31 10:31:07,335 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/147b55311d53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 10:31:08,596 unpacking tar archive at: mozilla-beta-147b55311d53/testing/mozharness/ program finished with exit code 0 elapsedTime=1.523324 ========= master_lag: 1.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-31 10:31:10.187600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:10.187997) ========= script_repo_revision: 147b55311d5350e6d7022d031f725934d6c717a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:10.188428) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:10.188694) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 10:31:10.215384) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-01-31 10:31:10.215810) ========= /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.Rpocs4wEHl/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.BDbUVY81yL/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:10 INFO - MultiFileLogger online at 20170131 10:31:10 in /builds/slave/test 10:31:10 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 10:31:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:31:10 INFO - {'append_to_log': False, 10:31:10 INFO - 'base_work_dir': '/builds/slave/test', 10:31:10 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:31:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:31:10 INFO - 'branch': 'Mozilla-Beta', 10:31:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:31:10 INFO - 'config_files': ('talos/mac_config.py',), 10:31:10 INFO - 'default_actions': ('clobber', 10:31:10 INFO - 'read-buildbot-config', 10:31:10 INFO - 'download-and-extract', 10:31:10 INFO - 'populate-webroot', 10:31:10 INFO - 'create-virtualenv', 10:31:10 INFO - 'install', 10:31:10 INFO - 'run-tests'), 10:31:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:31:10 INFO - 'download_minidump_stackwalk': True, 10:31:10 INFO - 'download_symbols': 'ondemand', 10:31:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:31:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:31:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:31:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:31:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:31:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:31:10 INFO - 'installer_path': 'installer.exe', 10:31:10 INFO - 'log_level': 'info', 10:31:10 INFO - 'log_name': 'talos', 10:31:10 INFO - 'log_to_console': True, 10:31:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:31:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:31:10 INFO - 'opt_config_files': (), 10:31:10 INFO - 'pip_index': False, 10:31:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:10 INFO - 'cmd': ('bash', 10:31:10 INFO - '-c', 10:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:10 INFO - 'enabled': True, 10:31:10 INFO - 'halt_on_failure': False, 10:31:10 INFO - 'name': 'check_screen_resolution'},), 10:31:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:10 INFO - 'cmd': ('bash', 10:31:10 INFO - '-c', 10:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:10 INFO - 'enabled': True, 10:31:10 INFO - 'halt_on_failure': False, 10:31:10 INFO - 'name': 'check_screen_resolution'},), 10:31:10 INFO - 'run_cmd_checks_enabled': True, 10:31:10 INFO - 'sps_profile': False, 10:31:10 INFO - 'sps_profile_interval': 0, 10:31:10 INFO - 'suite': 'dromaeojs-e10s', 10:31:10 INFO - 'system_bits': '32', 10:31:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:31:10 INFO - 'title': 't-yosemite-r7-0243', 10:31:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:31:10 INFO - 'use_talos_json': True, 10:31:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:31:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:31:10 INFO - 'work_dir': 'build'} 10:31:10 INFO - [mozharness: 2017-01-31 18:31:10.428831Z] Running clobber step. 10:31:10 INFO - Running pre-action listener: _resource_record_pre_action 10:31:10 INFO - Running main action method: clobber 10:31:10 INFO - rmtree: /builds/slave/test/build 10:31:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:31:13 INFO - Running post-action listener: _resource_record_post_action 10:31:13 INFO - [mozharness: 2017-01-31 18:31:13.317125Z] Finished clobber step (success) 10:31:13 INFO - [mozharness: 2017-01-31 18:31:13.317233Z] Running read-buildbot-config step. 10:31:13 INFO - Running pre-action listener: _resource_record_pre_action 10:31:13 INFO - Running main action method: read_buildbot_config 10:31:13 INFO - Using buildbot properties: 10:31:13 INFO - { 10:31:13 INFO - "project": "", 10:31:13 INFO - "product": "firefox", 10:31:13 INFO - "script_repo_revision": "production", 10:31:13 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:31:13 INFO - "repository": "", 10:31:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:31:13 INFO - "buildid": "20170131075309", 10:31:13 INFO - "pgo_build": "False", 10:31:13 INFO - "basedir": "/builds/slave/test", 10:31:13 INFO - "buildnumber": 0, 10:31:13 INFO - "slavename": "t-yosemite-r7-0243", 10:31:13 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:31:13 INFO - "platform": "yosemite_r7", 10:31:13 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:31:13 INFO - "branch": "mozilla-beta", 10:31:13 INFO - "repo_path": "releases/mozilla-beta", 10:31:13 INFO - "revision": "147b55311d5350e6d7022d031f725934d6c717a0", 10:31:13 INFO - "stage_platform": "macosx64", 10:31:13 INFO - "builduid": "298267ab9f6b4f61838e112a9b71d987", 10:31:13 INFO - "slavebuilddir": "test" 10:31:13 INFO - } 10:31:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 10:31:13 INFO - Running post-action listener: _resource_record_post_action 10:31:13 INFO - [mozharness: 2017-01-31 18:31:13.320250Z] Finished read-buildbot-config step (success) 10:31:13 INFO - [mozharness: 2017-01-31 18:31:13.320352Z] Running download-and-extract step. 10:31:13 INFO - Running pre-action listener: _resource_record_pre_action 10:31:13 INFO - Running main action method: download_and_extract 10:31:13 INFO - mkdir: /builds/slave/test/build/tests 10:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:13 INFO - trying https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 10:31:13 INFO - Downloading https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/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 10:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/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 10:31:14 INFO - Downloaded 1165 bytes. 10:31:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 10:31:14 INFO - Using the following test package requirements: 10:31:14 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 10:31:14 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 10:31:14 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 10:31:14 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:31:14 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 10:31:14 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 10:31:14 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 10:31:14 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 10:31:14 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 10:31:14 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 10:31:14 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 10:31:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 10:31:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 10:31:14 INFO - Fetch https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 10:31:15 INFO - Http code: 200 10:31:15 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 10:31:15 INFO - x-amz-cf-id: HizPXvH1eJ5ssOxKKdZXpVQr3K05sS0eyvjViDchwb_2NNrtL2R4Cw== 10:31:15 INFO - x-amz-version-id: _2PJAPhrNgWpg5mirvaVsmiqr3vQPVCr 10:31:15 INFO - Expected file size: 16089089 10:31:15 INFO - Obtained file size: 16089089 10:31:22 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 10:31:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 10:31:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 10:31:22 INFO - Fetch https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 10:31:22 INFO - Http code: 200 10:31:22 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 10:31:22 INFO - x-amz-cf-id: Pdx1PlTN8O1vM4r5EKJhtzjGkI88RmzifryvPzCCtWhQYkSiflGWXg== 10:31:22 INFO - x-amz-version-id: _2PJAPhrNgWpg5mirvaVsmiqr3vQPVCr 10:31:23 INFO - Expected file size: 16089089 10:31:23 INFO - Obtained file size: 16089089 10:31:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 10:31:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 10:31:29 INFO - Fetch https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 10:31:30 INFO - Http code: 200 10:31:30 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 10:31:30 INFO - x-amz-cf-id: WjQr0uj76gMDPfchGoNwbk9WjTg_9syigCfjzHSrWr6TgbezhQ3vdQ== 10:31:30 INFO - x-amz-version-id: fVfOJkGUp3TFsPUp0usPhGWQIrBAr4t1 10:31:30 INFO - Expected file size: 14402992 10:31:30 INFO - Obtained file size: 14402992 10:31: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')]} 10:31:30 INFO - trying https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.dmg 10:31:30 INFO - Downloading https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 10:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/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 10:31:32 INFO - Downloaded 82145321 bytes. 10:31:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.dmg 10:31:32 INFO - mkdir: /builds/slave/test/properties 10:31:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:31:32 INFO - Writing to file /builds/slave/test/properties/build_url 10:31:32 INFO - Contents: 10:31:32 INFO - build_url:https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.dmg 10:31:33 INFO - Running post-action listener: _resource_record_post_action 10:31:33 INFO - Running post-action listener: set_extra_try_arguments 10:31:33 INFO - [mozharness: 2017-01-31 18:31:33.697948Z] Finished download-and-extract step (success) 10:31:33 INFO - [mozharness: 2017-01-31 18:31:33.698085Z] Running populate-webroot step. 10:31:33 INFO - Running pre-action listener: _resource_record_pre_action 10:31:33 INFO - Running main action method: populate_webroot 10:31:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:31:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:31:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:31:33 INFO - '--tppagecycles', 10:31:33 INFO - '7'], 10:31:33 INFO - 'tests': ['tsvgm']}}, 10:31:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:31:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:31:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:31:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:31:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['damp', 'tps']}, 10:31:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'tests': ['damp', 'tps']}, 10:31:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['dromaeo_dom']}, 10:31:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:31:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['basic_compositor_video']}, 10:31:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:31:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['a11yr', 10:31:33 INFO - 'ts_paint', 10:31:33 INFO - 'tpaint', 10:31:33 INFO - 'sessionrestore', 10:31:33 INFO - 'sessionrestore_no_auto_restore', 10:31:33 INFO - 'tabpaint']}, 10:31:33 INFO - 'other-e10s': {'tests': ['a11yr', 10:31:33 INFO - 'ts_paint', 10:31:33 INFO - 'tpaint', 10:31:33 INFO - 'sessionrestore', 10:31:33 INFO - 'sessionrestore_no_auto_restore', 10:31:33 INFO - 'tabpaint']}, 10:31:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:31:33 INFO - 'ts_paint', 10:31:33 INFO - 'tpaint', 10:31:33 INFO - 'sessionrestore', 10:31:33 INFO - 'sessionrestore_no_auto_restore', 10:31:33 INFO - 'tabpaint']}, 10:31:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:31:33 INFO - 'ts_paint', 10:31:33 INFO - 'tpaint', 10:31:33 INFO - 'sessionrestore', 10:31:33 INFO - 'sessionrestore_no_auto_restore', 10:31:33 INFO - 'tabpaint']}, 10:31:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['a11yr', 10:31:33 INFO - 'ts_paint', 10:31:33 INFO - 'tpaint', 10:31:33 INFO - 'sessionrestore', 10:31:33 INFO - 'sessionrestore_no_auto_restore', 10:31:33 INFO - 'tabpaint']}, 10:31:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['a11yr', 10:31:33 INFO - 'ts_paint', 10:31:33 INFO - 'tpaint', 10:31:33 INFO - 'sessionrestore', 10:31:33 INFO - 'sessionrestore_no_auto_restore', 10:31:33 INFO - 'tabpaint']}, 10:31:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['tsvgx', 10:31:33 INFO - 'tsvgr_opacity', 10:31:33 INFO - 'tart', 10:31:33 INFO - 'tscrollx', 10:31:33 INFO - 'cart']}, 10:31:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:31:33 INFO - 'tsvgr_opacity', 10:31:33 INFO - 'tart', 10:31:33 INFO - 'tscrollx', 10:31:33 INFO - 'cart']}, 10:31:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'talos_options': ['--disable-e10s'], 10:31:33 INFO - 'tests': ['tp5o']}, 10:31:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'tests': ['tp5o']}, 10:31:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'talos_options': ['--disable-e10s', 10:31:33 INFO - '--xperf_path', 10:31:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:33 INFO - 'tests': ['tp5n']}, 10:31:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:33 INFO - 'talos_options': ['--xperf_path', 10:31:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:33 INFO - 'tests': ['tp5n']}}, 10:31:33 INFO - 'talos.zip': {'path': '', 10:31:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:31:33 INFO - Running post-action listener: _resource_record_post_action 10:31:33 INFO - [mozharness: 2017-01-31 18:31:33.716848Z] Finished populate-webroot step (success) 10:31:33 INFO - [mozharness: 2017-01-31 18:31:33.716973Z] Running create-virtualenv step. 10:31:33 INFO - Running pre-action listener: _resource_record_pre_action 10:31:33 INFO - Running main action method: create_virtualenv 10:31:33 INFO - Creating virtualenv /builds/slave/test/build/venv 10:31:33 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 10:31:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:31:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:31:33 INFO - Using real prefix '/tools/python27' 10:31:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:31:34 INFO - Installing distribute.............................................................................................................................................................................................done. 10:31:37 INFO - Installing pip.................done. 10:31:37 INFO - Return code: 0 10:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:31:37 INFO - Reading from file tmpfile_stdout 10:31:37 INFO - Output received: 10:31:37 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) 10:31:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:37 INFO - 'HOME': '/Users/cltbld', 10:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:37 INFO - 'LOGNAME': 'cltbld', 10:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:37 INFO - 'MOZ_NO_REMOTE': '1', 10:31:37 INFO - 'NO_EM_RESTART': '1', 10:31:37 INFO - 'PAGER': '/bin/cat', 10:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:37 INFO - 'PWD': '/builds/slave/test', 10:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:37 INFO - 'SHELL': '/bin/bash', 10:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:37 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:37 INFO - 'USER': 'cltbld', 10:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:37 INFO - 'XPC_FLAGS': '0x0', 10:31:37 INFO - 'XPC_SERVICE_NAME': '0', 10:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:37 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 10:31:37 INFO - Cleaning up... 10:31:37 INFO - Return code: 0 10:31:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:37 INFO - 'HOME': '/Users/cltbld', 10:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:37 INFO - 'LOGNAME': 'cltbld', 10:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:37 INFO - 'MOZ_NO_REMOTE': '1', 10:31:37 INFO - 'NO_EM_RESTART': '1', 10:31:37 INFO - 'PAGER': '/bin/cat', 10:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:37 INFO - 'PWD': '/builds/slave/test', 10:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:37 INFO - 'SHELL': '/bin/bash', 10:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:37 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:37 INFO - 'USER': 'cltbld', 10:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:37 INFO - 'XPC_FLAGS': '0x0', 10:31:37 INFO - 'XPC_SERVICE_NAME': '0', 10:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:38 INFO - Downloading/unpacking psutil>=3.1.1 10:31: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 10:31: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 10:31: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 10:31: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 10:31: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 10:31: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 10:31:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:31:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:31:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:31:41 INFO - Installing collected packages: psutil 10:31:41 INFO - Running setup.py install for psutil 10:31:41 INFO - building 'psutil._psutil_osx' extension 10:31:41 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 10:31:42 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 10:31:42 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 10:31:42 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 10:31:42 INFO - building 'psutil._psutil_posix' extension 10:31:42 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 10:31:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:31:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:31:42 INFO - ^ 10:31:42 INFO - 1 warning generated. 10:31:42 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 10:31:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:31:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:31:42 INFO - Successfully installed psutil 10:31:42 INFO - Cleaning up... 10:31:42 INFO - Return code: 0 10:31:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:31: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')]} 10:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31: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')]} 10:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:31: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 mozsystemmonitor==0.3 10:31:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:42 INFO - 'HOME': '/Users/cltbld', 10:31:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:42 INFO - 'LOGNAME': 'cltbld', 10:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:42 INFO - 'MOZ_NO_REMOTE': '1', 10:31:42 INFO - 'NO_EM_RESTART': '1', 10:31:42 INFO - 'PAGER': '/bin/cat', 10:31:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:42 INFO - 'PWD': '/builds/slave/test', 10:31:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:42 INFO - 'SHELL': '/bin/bash', 10:31:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:42 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:42 INFO - 'USER': 'cltbld', 10:31:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:42 INFO - 'XPC_FLAGS': '0x0', 10:31:42 INFO - 'XPC_SERVICE_NAME': '0', 10:31:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:31:43 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 10:31:43 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 10:31:43 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 10:31:43 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 10:31:43 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 10:31:43 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 10:31:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:31:45 INFO - Installing collected packages: mozsystemmonitor 10:31:45 INFO - Running setup.py install for mozsystemmonitor 10:31:46 INFO - Successfully installed mozsystemmonitor 10:31:46 INFO - Cleaning up... 10:31:46 INFO - Return code: 0 10:31:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:31:46 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')]} 10:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:46 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')]} 10:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:46 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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:46 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 10:31:46 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 10:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:46 INFO - 'HOME': '/Users/cltbld', 10:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:46 INFO - 'LOGNAME': 'cltbld', 10:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:46 INFO - 'MOZ_NO_REMOTE': '1', 10:31:46 INFO - 'NO_EM_RESTART': '1', 10:31:46 INFO - 'PAGER': '/bin/cat', 10:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:46 INFO - 'PWD': '/builds/slave/test', 10:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:46 INFO - 'SHELL': '/bin/bash', 10:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:46 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:46 INFO - 'USER': 'cltbld', 10:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:46 INFO - 'XPC_FLAGS': '0x0', 10:31:46 INFO - 'XPC_SERVICE_NAME': '0', 10:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:46 INFO - Downloading/unpacking jsonschema==2.5.1 10:31:46 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 10:31:46 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 10:31:46 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 10:31:46 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 10:31:46 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 10:31:46 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 10:31:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:31:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:31:49 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 10:31:49 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 10:31:49 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 10:31:49 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 10:31:49 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 10:31:49 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 10:31:49 INFO - Downloading functools32-3.2.3-2.tar.gz 10:31:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:31:49 INFO - warning: no files found matching '*.txt' 10:31:49 INFO - no previously-included directories found matching 'build' 10:31:49 INFO - no previously-included directories found matching 'dist' 10:31:49 INFO - no previously-included directories found matching '.git*' 10:31:49 INFO - Installing collected packages: jsonschema, functools32 10:31:49 INFO - Running setup.py install for functools32 10:31:49 INFO - warning: no files found matching '*.txt' 10:31:49 INFO - no previously-included directories found matching 'build' 10:31:49 INFO - no previously-included directories found matching 'dist' 10:31:49 INFO - no previously-included directories found matching '.git*' 10:31:49 INFO - Successfully installed jsonschema functools32 10:31:49 INFO - Cleaning up... 10:31:49 INFO - Return code: 0 10:31:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:31:49 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')]} 10:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:49 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')]} 10:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:49 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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:49 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 10:31:49 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 10:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:49 INFO - 'HOME': '/Users/cltbld', 10:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:49 INFO - 'LOGNAME': 'cltbld', 10:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:49 INFO - 'MOZ_NO_REMOTE': '1', 10:31:49 INFO - 'NO_EM_RESTART': '1', 10:31:49 INFO - 'PAGER': '/bin/cat', 10:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:49 INFO - 'PWD': '/builds/slave/test', 10:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:49 INFO - 'SHELL': '/bin/bash', 10:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:49 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:49 INFO - 'USER': 'cltbld', 10:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:49 INFO - 'XPC_FLAGS': '0x0', 10:31:49 INFO - 'XPC_SERVICE_NAME': '0', 10:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:31:50 INFO - Cleaning up... 10:31:50 INFO - Return code: 0 10:31:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:50 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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:50 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 10:31:50 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 10:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:50 INFO - 'HOME': '/Users/cltbld', 10:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:50 INFO - 'LOGNAME': 'cltbld', 10:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:50 INFO - 'MOZ_NO_REMOTE': '1', 10:31:50 INFO - 'NO_EM_RESTART': '1', 10:31:50 INFO - 'PAGER': '/bin/cat', 10:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:50 INFO - 'PWD': '/builds/slave/test', 10:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:50 INFO - 'SHELL': '/bin/bash', 10:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:50 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:50 INFO - 'USER': 'cltbld', 10:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:50 INFO - 'XPC_FLAGS': '0x0', 10:31:50 INFO - 'XPC_SERVICE_NAME': '0', 10:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:50 INFO - Downloading/unpacking blobuploader==1.2.4 10:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:52 INFO - Downloading blobuploader-1.2.4.tar.gz 10:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:31:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:31:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:54 INFO - Downloading docopt-0.6.1.tar.gz 10:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:31:54 INFO - Installing collected packages: blobuploader, requests, docopt 10:31:54 INFO - Running setup.py install for blobuploader 10:31:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:31:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:31:54 INFO - Running setup.py install for requests 10:31:54 INFO - Running setup.py install for docopt 10:31:55 INFO - Successfully installed blobuploader requests docopt 10:31:55 INFO - Cleaning up... 10:31:55 INFO - Return code: 0 10:31:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:55 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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:55 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 10:31:55 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 10:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:55 INFO - 'HOME': '/Users/cltbld', 10:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:55 INFO - 'LOGNAME': 'cltbld', 10:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:55 INFO - 'MOZ_NO_REMOTE': '1', 10:31:55 INFO - 'NO_EM_RESTART': '1', 10:31:55 INFO - 'PAGER': '/bin/cat', 10:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:55 INFO - 'PWD': '/builds/slave/test', 10:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:55 INFO - 'SHELL': '/bin/bash', 10:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:55 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:55 INFO - 'USER': 'cltbld', 10:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:55 INFO - 'XPC_FLAGS': '0x0', 10:31:55 INFO - 'XPC_SERVICE_NAME': '0', 10:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:31:55 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-RCYXLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:31:55 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-SVdkcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:31:55 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-i6vDRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:31:55 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-fj3r08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:31:55 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-awQs5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:31:55 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-3vckAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hPXnSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-4_5hb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Vsx0xq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ETwBAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-XPNNVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-JVca81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-1G1mPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-I9cm16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:56 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-xkcWPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:31:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-TsRBP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:31:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-cGRkHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:31:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:31:57 INFO - Running setup.py install for manifestparser 10:31:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:31:57 INFO - Running setup.py install for mozcrash 10:31:57 INFO - Running setup.py install for mozdebug 10:31:57 INFO - Running setup.py install for mozdevice 10:31:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:31:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:31:57 INFO - Running setup.py install for mozfile 10:31:57 INFO - Running setup.py install for mozhttpd 10:31:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozinfo 10:31:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozInstall 10:31:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozleak 10:31:58 INFO - Running setup.py install for mozlog 10:31:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for moznetwork 10:31:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozprocess 10:31:59 INFO - Running setup.py install for mozprofile 10:31:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Running setup.py install for mozrunner 10:31:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Running setup.py install for mozscreenshot 10:31:59 INFO - Running setup.py install for moztest 10:31:59 INFO - Running setup.py install for mozversion 10:31:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:31:59 INFO - Cleaning up... 10:31:59 INFO - Return code: 0 10:31:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:31:59 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')]} 10:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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')]} 10:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:31:59 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 10:31:59 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 10:31:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:31:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:59 INFO - 'HOME': '/Users/cltbld', 10:31:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:59 INFO - 'LOGNAME': 'cltbld', 10:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:59 INFO - 'MOZ_NO_REMOTE': '1', 10:31:59 INFO - 'NO_EM_RESTART': '1', 10:31:59 INFO - 'PAGER': '/bin/cat', 10:31:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:59 INFO - 'PWD': '/builds/slave/test', 10:31:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:59 INFO - 'SHELL': '/bin/bash', 10:31:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:31:59 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:31:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:59 INFO - 'USER': 'cltbld', 10:31:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:59 INFO - 'XPC_FLAGS': '0x0', 10:31:59 INFO - 'XPC_SERVICE_NAME': '0', 10:31:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-eZBKIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-J42BKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-rsHXpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-0uTyd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-br4eVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-dKiVzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-MHhVa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-KGlxAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:32:00 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-nZd2Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-KoDOEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-iGQd7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-o4h0m5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-GLg97l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-J3k1ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-3iQsy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-DZwgaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:32:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-RhujGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:32:01 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 10:32:01 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 10:32:01 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 10:32:01 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 10:32:01 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 10:32:01 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 10:32:04 INFO - Downloading blessings-1.6.tar.gz 10:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:32:04 INFO - Installing collected packages: blessings 10:32:04 INFO - Running setup.py install for blessings 10:32:04 INFO - Successfully installed blessings 10:32:04 INFO - Cleaning up... 10:32:04 INFO - Return code: 0 10:32:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:05 INFO - Reading from file tmpfile_stdout 10:32:05 INFO - Current package versions: 10:32:05 INFO - blessings == 1.6 10:32:05 INFO - blobuploader == 1.2.4 10:32:05 INFO - docopt == 0.6.1 10:32:05 INFO - functools32 == 3.2.3-2 10:32:05 INFO - jsonschema == 2.5.1 10:32:05 INFO - manifestparser == 1.1 10:32:05 INFO - mozInstall == 1.12 10:32:05 INFO - mozcrash == 1.0 10:32:05 INFO - mozdebug == 0.1 10:32:05 INFO - mozdevice == 0.48 10:32:05 INFO - mozfile == 1.2 10:32:05 INFO - mozhttpd == 0.7 10:32:05 INFO - mozinfo == 0.9 10:32:05 INFO - mozleak == 0.1 10:32:05 INFO - mozlog == 3.4 10:32:05 INFO - moznetwork == 0.27 10:32:05 INFO - mozprocess == 0.23 10:32:05 INFO - mozprofile == 0.28 10:32:05 INFO - mozrunner == 6.13 10:32:05 INFO - mozscreenshot == 0.1 10:32:05 INFO - mozsystemmonitor == 0.3 10:32:05 INFO - moztest == 0.8 10:32:05 INFO - mozversion == 1.4 10:32:05 INFO - psutil == 3.1.1 10:32:05 INFO - requests == 1.2.3 10:32:05 INFO - wsgiref == 0.1.2 10:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32: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')]} 10:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32: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')]} 10:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:05 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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:32:05 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 10:32:05 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 10:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:05 INFO - 'HOME': '/Users/cltbld', 10:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:05 INFO - 'LOGNAME': 'cltbld', 10:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:05 INFO - 'MOZ_NO_REMOTE': '1', 10:32:05 INFO - 'NO_EM_RESTART': '1', 10:32:05 INFO - 'PAGER': '/bin/cat', 10:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:05 INFO - 'PWD': '/builds/slave/test', 10:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:05 INFO - 'SHELL': '/bin/bash', 10:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:32:05 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:05 INFO - 'USER': 'cltbld', 10:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:05 INFO - 'XPC_FLAGS': '0x0', 10:32:05 INFO - 'XPC_SERVICE_NAME': '0', 10:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 INFO - Cleaning up... 10:32:06 INFO - Return code: 0 10:32:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:06 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 0x10150a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101480570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8a2d8c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, '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.Rpocs4wEHl/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.BDbUVY81yL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 10:32:06 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 10:32:06 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 10:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:06 INFO - 'HOME': '/Users/cltbld', 10:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:06 INFO - 'LOGNAME': 'cltbld', 10:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:06 INFO - 'MOZ_NO_REMOTE': '1', 10:32:06 INFO - 'NO_EM_RESTART': '1', 10:32:06 INFO - 'PAGER': '/bin/cat', 10:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:06 INFO - 'PWD': '/builds/slave/test', 10:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:06 INFO - 'SHELL': '/bin/bash', 10:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:32:06 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:06 INFO - 'USER': 'cltbld', 10:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:06 INFO - 'XPC_FLAGS': '0x0', 10:32:06 INFO - 'XPC_SERVICE_NAME': '0', 10:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:32:06 INFO - Cleaning up... 10:32:06 INFO - Return code: 0 10:32:06 INFO - Running post-action listener: _resource_record_post_action 10:32:06 INFO - Running post-action listener: _start_resource_monitoring 10:32:06 INFO - Starting resource monitoring. 10:32:06 INFO - [mozharness: 2017-01-31 18:32:06.758528Z] Finished create-virtualenv step (success) 10:32:06 INFO - [mozharness: 2017-01-31 18:32:06.759545Z] Running install step. 10:32:06 INFO - Running pre-action listener: _resource_record_pre_action 10:32:06 INFO - Running main action method: install 10:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:07 INFO - Reading from file tmpfile_stdout 10:32:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:32:07 INFO - Reading from file tmpfile_stdout 10:32:07 INFO - Output received: 10:32:07 INFO - Usage: mozinstall [options] installer 10:32:07 INFO - Options: 10:32:07 INFO - -h, --help show this help message and exit 10:32:07 INFO - -d DEST, --destination=DEST 10:32:07 INFO - Directory to install application into. [default: 10:32:07 INFO - "/builds/slave/test"] 10:32:07 INFO - --app=APP Application being installed. [default: firefox] 10:32:07 INFO - mkdir: /builds/slave/test/build/application 10:32:07 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'] 10:32:07 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 10:32:28 INFO - Reading from file tmpfile_stdout 10:32:28 INFO - Output received: 10:32:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:32:28 INFO - Running post-action listener: _resource_record_post_action 10:32:28 INFO - [mozharness: 2017-01-31 18:32:28.692206Z] Finished install step (success) 10:32:28 INFO - [mozharness: 2017-01-31 18:32:28.692336Z] Running run-tests step. 10:32:28 INFO - Running pre-action listener: _resource_record_pre_action 10:32:28 INFO - Running main action method: run_tests 10:32:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:32:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:32:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:32:28 INFO - 2017-01-31 10:32:28.706 screenresolution[1627:7276] starting screenresolution argv=screenresolution get 10:32:28 INFO - 2017-01-31 10:32:28.709 screenresolution[1627:7276] Display 0: 1600x1200x32@60 10:32:28 INFO - 2017-01-31 10:32:28.714 screenresolution[1628:7279] starting screenresolution argv=screenresolution list 10:32:28 INFO - Available Modes on Display 0 10:32:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:32:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:32:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:32:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:32:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:32:28 INFO - Intel Iris: 10:32:28 INFO - Chipset Model: Intel Iris 10:32:28 INFO - Type: GPU 10:32:28 INFO - Bus: Built-In 10:32:28 INFO - VRAM (Dynamic, Max): 1536 MB 10:32:28 INFO - Vendor: Intel (0x8086) 10:32:28 INFO - Device ID: 0x0a2e 10:32:28 INFO - Revision ID: 0x0009 10:32:28 INFO - Displays: 10:32:28 INFO - Display: 10:32:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:32:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:32:28 INFO - Display Serial Number: 1600x1200 60 10:32:28 INFO - Main Display: Yes 10:32:28 INFO - Mirror: Off 10:32:28 INFO - Online: Yes 10:32:28 INFO - Rotation: Supported 10:32:28 INFO - Return code: 0 10:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:32:28 INFO - Python 2.7.3 10:32:28 INFO - Return code: 0 10:32:28 INFO - grabbing minidump binary from tooltool 10:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:28 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 0x10131ed30>, '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 0x101184db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101316b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:32:28 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 10:32:28 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 10:32:28 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 10:32:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:32:29 INFO - Return code: 0 10:32:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:32:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:32:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:32:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:32:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:32:29 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/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 10:32:29 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/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --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 10:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rpocs4wEHl/Render', 10:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:29 INFO - 'HOME': '/Users/cltbld', 10:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:29 INFO - 'LOGNAME': 'cltbld', 10:32:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:32:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:29 INFO - 'MOZ_NO_REMOTE': '1', 10:32:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:32:29 INFO - 'NO_EM_RESTART': '1', 10:32:29 INFO - 'PAGER': '/bin/cat', 10:32:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:29 INFO - 'PWD': '/builds/slave/test', 10:32:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:29 INFO - 'SHELL': '/bin/bash', 10:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BDbUVY81yL/Listeners', 10:32:29 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 10:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:29 INFO - 'USER': 'cltbld', 10:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:29 INFO - 'XPC_FLAGS': '0x0', 10:32:29 INFO - 'XPC_SERVICE_NAME': '0', 10:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:29 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/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 10:32:29 INFO - mozversion application_buildid: 20170131075309 10:32:29 INFO - mozversion application_changeset: 147b55311d5350e6d7022d031f725934d6c717a0 10:32:29 INFO - mozversion application_display_name: Firefox 10:32:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:32:29 INFO - mozversion application_name: Firefox 10:32:29 INFO - mozversion application_remotingname: firefox 10:32:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:32:29 INFO - mozversion application_vendor: Mozilla 10:32:29 INFO - mozversion application_version: 52.0 10:32:29 INFO - mozversion platform_buildid: 20170131075309 10:32:29 INFO - mozversion platform_changeset: 147b55311d5350e6d7022d031f725934d6c717a0 10:32:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:32:29 INFO - mozversion platform_version: 52.0 10:32:29 INFO - using testdate: 1485887549 10:32:29 INFO - actual date: 1485887549 10:32:29 INFO - starting webserver on 'localhost:49236' 10:32:29 INFO - SUITE-START | Running 2 tests 10:32:29 INFO - TEST-START | dromaeo_css 10:32:29 INFO - operating with platform_type : mac_ 10:32:29 INFO - Initialising browser for dromaeo_css test... 10:32:29 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpUjopHf/profile http://localhost:49236/getInfo.html) 10:32:32 INFO - PROCESS | 1638 | 2017-01-31 10:32:32.434 plugin-container[1641:7460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 10:32:32 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:32 INFO - PROCESS | 1638 | 2017-01-31 10:32:32.435 plugin-container[1641:7460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e13, name = 'com.apple.CFPasteboardClient' 10:32:32 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:32 INFO - PROCESS | 1638 | 2017-01-31 10:32:32.435 plugin-container[1641:7460] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:32:32 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 10:32:32 INFO - PROCESS | 1638 | colorDepth:24 10:32:32 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 10:32:32 INFO - PROCESS | 1638 | __metrics 10:32:32 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 10:32:33 INFO - TEST-INFO | 1638: exit 0 10:32:33 INFO - Browser initialized. 10:32:33 INFO - Running cycle 1/1 for dromaeo_css test... 10:32:33 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpUjopHf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:32:35 INFO - PROCESS | 1642 | 2017-01-31 10:32:35.668 plugin-container[1643:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 10:32:35 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:35 INFO - PROCESS | 1642 | 2017-01-31 10:32:35.668 plugin-container[1643:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 10:32:35 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:35 INFO - PROCESS | 1642 | 2017-01-31 10:32:35.668 plugin-container[1643:7590] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:32:45 INFO - PROCESS | 1642 | RSS: Main: 177131520 10:32:45 INFO - PROCESS | 1642 | 10:35:46 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 10:35:48 INFO - PROCESS | 1642 | RSS: Main: 183042048 10:35:48 INFO - PROCESS | 1642 | 10:38:50 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 10:38:51 INFO - PROCESS | 1642 | RSS: Main: 194371584 10:38:51 INFO - PROCESS | 1642 | 10:41:53 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 10:41:53 INFO - PROCESS | 1642 | RSS: Main: 197103616 10:41:53 INFO - PROCESS | 1642 | 10:44:55 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 10:44:56 INFO - PROCESS | 1642 | RSS: Main: 200151040 10:44:56 INFO - PROCESS | 1642 | 10:47:58 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 10:47:59 INFO - PROCESS | 1642 | RSS: Main: 205541376 10:47:59 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 10:51:02 INFO - PROCESS | 1642 | RSS: Main: 206360576 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | __start_tp_report 10:51:02 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 10:51:02 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 10:51:02 INFO - PROCESS | 1642 | |i|pagename|runs| 10:51:02 INFO - PROCESS | 1642 | |0;dojo.html;3544.4328907374816;3551.4850346699714;3570.5715314162303;3580.337637537055;3615.891523254303;5934.317553481351;6003.549733769963;6013.158157857898;6016.247969003869;6017.338092809799;5967.04527275636;6030.728617212225;6048.09278608209;6064.939350606493;6082.452579267865;5165.38015438148;5345.358556973159;5381.67709937403;5402.729863506828;5495.670259784426;5408.891822163554;5429;5449.618526703132;5459.727013649322;5463.289772329612;3927.8609203331;4158.293090174662;4822.601445580777;5448.482394172567;5462.399136095027;5276.208568714705;5354.785808567653;5417.160340147283;5420.430854760233;5473.644213126134;5259.3951695555115;5373.059714549938;5379.435159308256;5380.730963451851;5416;6003.849903752397;6016.127661489103;6076.696165191723;6113.083037544404;6140.385961403868;6156.168917196211;6170.136180934628;6188.721507532172;6202.658853763041;6209.627422354673;6363.204599425072;6365.204349456318;6366.0769188467875;6376.904346434807;6408.51936104794;6023.698815059273;6115.480184184395;6117.663528505975;6119.938800611982;6121.234845644294;6205.503559715212;6349.365063493614;6382.425581697669;6411.551191416649;6473.870522589522;6211.09939058834;6265.686715664289;6285.151504546919;6308.842278943063;6317.46301564363;6351.682415879188;6367.777127800505;6384.648844313608;6391.552591318656;6402.103705481236;6184.6598437086395;6193.287771906198;6233.189685340967;6258.780942666985;6292.716827742763;5144.382674079135;6240.719167637467;6252.718627661766;6262.405071518198;6446.677666116675;5058.797648094109;5072.239164125411;5083.669561478566;5086.23706444029;5102.719350435762;4484.2376795944765;4497.280435130363;4555.931661025087;4592.977035114803;4602.125596136723;4548.727076375428;5969.432903874125;5995.61028533144;6029.065494848305;6050.364711705296;5676.403977582377;5919.052951527735;6202.379762023852;6274.529410294246;6281.3404592518045;5937.287525496968;6003.609765365237;6048;6049.425304596057;6096;5603.915941260884;5636.239107720406;5642.830715078554;5661.830145095653;5663.405342439067;6548.639824809681;6565.704543295467;6573.244076931119;6602.471802255712;6626.204855417381;6129.664444256008;6234.325686678318;6249.1563638908165;6376.202974628171;6389.424951754178;6463.57986730861;6576.243731970884;6594.175728033996;6615;6616.801495955129;6302.495800335871;6302.747890084345;6304.117423560613;6304.873902521832;6323.988161894074;6177.320494745484;6250.468710159687;6296.874062518632;6371.331010243857;6380.457661098859;6054.515638748801;6222.875542489215;6282.748690052346;6283.842903927439;6308.27454842699;6224.502039836892;6265.530085243534;6275.090111933835;6275.152854364603;6281;6006.729697163551;6083.239595050619;6142.477889379274;6165.167702360303;6248.968755156195;6194.876102477835;6199.876002480015;6223.408776166257;6224.253089629274;6288.528360373082;6218.657973811484;6222.16000839899;6230.283517395376;6246.094316324198;6267.749290028348 10:51:02 INFO - PROCESS | 1642 | |1;ext.html;459.3110334498253;470.67758585369006;506.13703635301835;512.7538781384934;571.331542095748;33145;33511.994640160774;38628.841134765935;40233.597664023386;41758.37362439558;34754.65245347546;35936.74221402252;41304.58695413045;42535.78732106343;42546.93632659185;9623.085806848338;9627.22982161429;9718.465484398355;9732.416055036685;9817.920028796827;9688.515574221261;9791.706248812547;9794.510274486283;9820.21438284939;9833.115019648267;9719.902800972006;9769.364991275545;9779.266555008368;9795.06946840049;9831.115199632031;9720.951395243013;9723.805523889554;9725.95137024314;9817.852732209023;9828.017198280115;9594.13652771247;9637.662681806103;9691.982341854145;9706.805863882682;9713.36863103896;39991;40690.59309406927;40798.38802417966;40892;40952.590474095174;3576.356255873942;3608.0438683748825;3914.256291304643;3984.442178095069;4133.607307305801;26732.33169170767;28855.85572072126;29060;29684.40631187364;30049.248768780955;3306.123877172551;3343.2143446290347;3345.7156141727983;3438.673326034023;3472;493651;494868;500393;500493.99999999633;502594;62657.373426266066;62671;62949.74100517964;63046;63159.6842015787;64554;64878.40486380587;65955;66172;66308.66845665741;16074;16549.25528351227;16802.15989200535;16824.411145609844;16857.904236224607;12422.503099876085;12502.374881256083;12888.613341599766;12963.870361296265;12987.675308117372;17403.47789566315;17762.5559361017;17788.46634600964;17834.28662853471;17836.19737111833;5896.380880007538;6099.634021958696;6543.443807276435;6556.557557337378;6558.573692709959;856.3063918226256;940.6079003075487;964.3827950111934;1060.9628662996913;1142.857142857143;26736.79684414206;30302.939397120994;30425.239369015508;30495.780168793444;30854.537181942287;991.6529214774771;999.4902599674218;1030.9639162629271;1045.3727763342085;1154.261272785418;12699.936500317439;15384.38462461489;15792.894497385303;15830.366785328688;15997.760033599505;662667;676200;677321;678592;682011;59204.70397647984;59566.404335956955;60024.69987650034;60109.69945150334;60172.69913650491;1198.5924785572947;1372.19726460022;1382.4815694114707;1405.8102156208781;1411.6541447345671;29514.409711805216;29984.95052673146;30169.69830301669;30405.39189216159;30451.847740761154;51452.48547514477;52301.7384913073;52331.95336093333;52386.21420678769;52468.737656311474;614128;621281;624396;624426;624850;27391.452170956072;27460.725392746615;27702.861485692443;27814.721852782022;27825.747841346783;42014.94962625836;47128.764356178;47623.52376476191;47729.28406073981;48556.2716559259;39211.2157756849;39244.41133383058;39305.803470982464;39383.01542461461;40817.1836563273;38893.80553097217;39377.60622393739;39705.61030363923;40261;40375.79812101038;37989;38561.22877542378;38910.832675019796;39253.80373098116;39413.408798868615;29730.554041689826;33696;34844.82577587096;35582.46626300556;35636.64363356333;21445.82047987313;26168.214953551425;26382.736172638797;26463.073792417174;26622.467550649268 10:51:02 INFO - PROCESS | 1642 | |2;jquery.html;310.7172473049524;381.54786577905185;386.1735885205659;394.74933417280016;414.9564295748946;19061.904690476546;20220.59558808823;20520.384388468352;20940.581188376244;21058.84176370299;20318.984050797473;20470.488237794063;20578.89710551445;20615.175392984263;21103.68344474834;5688.345840228385;5808.4191580842;5875.823725288248;5957.225560677106;5963.284405871302;4308.655307575386;4541.863744087666;5932.436418540254;5979.910301345461;5980.97009514954;6271.2788029376525;6538.378854008862;6579.638119903403;6632.204135503722;6645.900311495331;6449.6130232186215;6535.640539770311;6570.5729127606555;6609.867802643944;6625.271220165778;5652.491275785199;5784.971075144639;5805.8548536286935;5814.244148260741;5819.24349834523;24659;24874.626880596807;25238.116665916605;25336;25874.6118808218;2313.687652166953;2333.754955729641;2379.988100059506;2528.102523604131;2592.2093761402766;8386.371022173362;9760.755981100458;10180.287379883412;10381.79236415275;10566.418846963414;1876.8398559495618;1954.9315773947844;1957.9902100489549;1990.3531352310556;2026.250287393659;90719;93421.06578934191;100264.49603255957;126354.10468842975;127821;11495.655130346104;11621.663508696609;11824.822627660593;11875.643730688093;11885.96621883294;14952.626184345478;14964.625884352978;14986.775198372034;15035.173065481285;15040.623984400478;8671.523066231417;8736.78158046054;8829.072947340564;8838.204561589488;8891.19979201875;7535.794272916307;8642.870356944652;8667.479951202948;8766.079561646064;8860.601272942733;3849.711271654637;4554.339620754972;4720.763961801897;4733.290006499053;4773.9761301193275;1683.5620944349819;1819.7543677564383;1857.8699491035768;1929.218666440094;1986.8609197356336;814.9918500815035;935.5228833295068;951.46242373059;977.8679878216492;1016.8068067067234;20845.374638760863;21431.57136857285;21555.892220538797;21590.352289431343;21606.567868642538;873.6472797351336;938.8075444533854;949.924006079512;967.4485543240426;1027.342500799489;11099.44502774858;11362.715932101764;11385.861413858547;11400.315981041163;11436.94281528587;608558;620481;620988;632904;633690;106875.93124068815;121371.39314303373;140762;146873;151857;985.4038306824411;990.2869933647762;1028.8096702110133;1031.2832581356035;1032.9793404131876;19321.806781932002;19684.90157549203;19695;19980.600387991868;20081;55836.6040848982;56788.86422271449;57339.27982160542;57359;58193.70903145627;79676.60161699154;81087.59456202682;81640.18359816563;81652.18347816446;84122.31755364736;7902.130765615662;8120.025596928406;8185.181481851767;8318.45930014547;8340.41617086798;9115.407498512575;12306.75386492283;12832.935835321137;12857.485700572246;12876.613701588967;9580.856287155837;11076.5569377224;11103.111751060058;11106.722331941766;11442.48508817122;9029.142231487998;10586.258961872594;10888.67333979982;11193.552257909592;11555.191136620357;11424.428778560872;11444.828327574927;11547.4803633835;11748.765024699629;11876.287422754662;8103.351731861319;8160.83678326442;8312.667493300441;8351.33189344863;8520.275776558814;8373.916260837314;9300.39547429415;10990.010899019127;11131.554737810397;11206 10:51:02 INFO - PROCESS | 1642 | |3;mootools.html;1511.9924400378;1537.9000364976275;1733.921973511192;1831.5054935167514;1887.2168050259143;7714.074311082664;7957.442978991473;8228.876566851503;8708.172723591257;8712;8304.91695083049;8594.484330940148;8604.182602652754;8606.56967151643;8609.698660546883;2039.4391542325832;2353.9176128835493;2394.6048901931163;2411.5418070566625;2413.2036427978815;2381.535600557892;2418.7823095921362;2426.733059363469;2430.720467146274;2441.121196369306;2359.386559494535;2387.092904696213;2404.3388068281274;2407.6990376202975;2409.4819613783034;2358.705161854768;2373.1338061607476;2375.7386687464364;2384.6780684607616;2391.7966972807244;2398.8965076065033;2399.6160614301803;2423.466837295792;2424.478737071538;2431.234161239204;8428.115047920002;8433.367497437717;8455.323574114056;8562.443441176303;8599.968003839516;5181.948180518184;5343.438938911436;5346.866328341784;5353.464653534655;5365.812196573102;3540.504329393861;3590.8025058621765;3590.9281814363567;3615.5480564929385;3703.203811180609;1063.888291729365;1072.3673032910542;1097.5170924793144;1106.4191299567792;1109.2789686703627;11121.944390277997;11136.053435457996;11178;11199.832002519968;11251.268667536584;4544.977275113603;4666.60333871618;4741.051789642016;4756.9762151189025;4826.638002149783;9501.477418741897;9593.520323983912;9610.51947402627;9613.90386096144;9629.470379129076;5283.313169288045;5296.523312901781;5334.546563542065;5341.172118321666;5366.221897824794;4062.410950412233;4403.251447253974;4407.955920440819;4889.168841296988;4891.3396691446915;3913.099987003005;3946.1910308386714;3995.800209989489;4037.7779222143063;4055.3105971984833;1309.3453273363318;1321.8017297405465;1322.3718733601654;1328.2561765411401;1337.0640551613715;744.3524134003452;863.9092895246034;867.2021739999216;918.3387960668308;926.7405126564573;5524.3370795504;5655.293088363955;5688.8738046277695;5694.145878118233;5888.205092312569;840.974011705718;885.7475619448426;886.8847049883474;965.4496936746128;995.437577768573;3796.8481260749813;3807.6192380762254;3830.042489377656;3855.7879316637855;3861.613838616116;7963.641636126388;10140.645077422254;10188.184945204366;10490.73773155662;10495.737606559744;9117.452952822852;10402.375857448576;10426.478676066166;10486.37081775096;10686.51910664022;7998.960005200053;8111.594420279081;8120.6345714443;8126.75619731409;8164.428490005761;4664.253719404879;4869.878253043702;5084.720340381315;5558;5740.225069615549;9361.017093205115;9380.906190938276;9434.622615095594;9536.761580960532;9567.712968610675;9216.124468175512;9575.569099390683;9646.517674116125;9734.221262299141;9734.36726612768;6723.731050757915;6906.309369063254;7044.823879402851;7067.116610423697;7356.117265928123;7911.643976020978;8109.270165685117;8113.432059755761;8114.107448180577;8125;7191.964040179766;7296.160941491797;7385.9630701846145;7399.630018498946;7435.256474352522;7123.465740069412;7243.348098671145;7251.093613298338;7253.854922901618;7262.709491620276;6686.364795344434;6746.763863264762;6805.0132730752775;6832.487563432862;6834.1457317835275;4815.927761083657;4844.249141383091;4869.805207791649;4915.090708218991;4975.203967365204;4858.781354839111;4907.386576677915;4918.975405122951;4959.429665588504;4999.375078115236 10:51:02 INFO - PROCESS | 1642 | |4;prototype.html;308.8918878392561;313.9372125574885;320.8652366006277;323.7733586489458;325.99837000814995;4690.976545117278;5250.422453530114;5293.517815011798;5419.512243898049;5560.527355174805;4779.808807647699;4839.637027222963;4945.579625731808;5453.291072160623;5495.752691128899;3697.408414653656;4031.6976226782963;4237.957620423787;4300.279888044792;4332.393464914914;3589.85640574378;3590.7486475946694;4170.8540201092965;4328.004558951443;4346.652537713303;4145.937810932838;4227.435848735972;4237.809298581571;4238.364245363189;4258.744475331475;4234.724742891703;4242.512111107231;4256.6807489438415;4265.7014009019385;4295.269804133305;2990.9700902991126;3728.45937339087;3732.4883422214048;3742.251549690073;3743.7753734776;4298.065870358351;4666.766661666937;5171.715555644476;5320.206891382017;5365.3293338332705;4359.531480232755;4694.413198350207;4928.28539861718;4935.062338155775;4973.4280557735965;4740.431148262196;5160.509751573594;5724.398938111522;5848;5897.705114744245;3222.6455089940087;3929.607039296076;3985.8405663773415;3994.520657521087;3997.020729921159;5306;5368.8389348319615;5628.296462942173;6134.66882584615;6147.47746441553;3440.4839274109086;3624.0939765058733;3721.888343349704;4029.4761680981283;4047.817848196838;3984.940225896614;4021.270853533009;4029.3955906613655;4083.876933843217;4084.428180054735;4767.73777442244;4782.736949467743;4783.378160839069;4792.736399497991;4823.421189457217;5244.587489754756;5896.882062358729;5919.8096704219715;5927.614705044159;5936.910946335808;1175.723704929341;1185.8102703567388;1189.4052973513244;1271.3071376100047;1323.5698398020488;890.2343984173606;895.5611750242465;900.9054049324726;901.7925877048316;910.8770316007386;4652.674312798072;4743.430788305356;4948.233023881304;5238.68567885928;5243.764030618631;5070.923936140961;5122.615803814729;5307.788787783413;5665.405132461115;5716.342620598602;4385;5051.419086804991;5138.768755406016;5238.92141617876;5254.97372513135;2439.670644462975;2509.962350564743;2823.7740980721906;2946.2634341414646;3011.9849400752855;5784.305883294031;6005.8559593880955;6017.759289628453;6027.819165425044;6049.667268300285;5013.724245166478;5308.9116731070035;5861.88276234473;5972.402759723993;5983.282006159202;5741.62679425836;5929.911051334234;5954.702264886825;6003.699815009232;6089.421504957022;5063.569596584184;5137.51193636604;5501.229827824028;5570.5265052470995;5882.764689412375;4807.95192048075;5383;5655.632383895034;5666.716664166693;5681.573881958787;5947.245562559142;5955.702214889325;5994.850128746816;6005.519558435402;6017.488513476228;3622.4385220290897;3779.640934111255;3835.808209589565;3848.807559621952;3848.8075596220638;5055.469175736642;5264.973330200574;5307.469253074816;5708.65748055118;5987.64074155552;5449.3460784706085;5946.37271264537;5947.613405128825;5973.402659733992;6012.8797424052145;4555;4774.40319960005;5905.962442957173;5917.881642367214;5998.850603433232;4576.610988065919;4975.328330675313;5476.561875050066;5833.133397312989;5848.035074212878;5420.043368341212;5583.022970980143;5915.970420148044;5984.982552966093;5995.220621319375;5167.61242906791;5661.179129026185;5852.4147585241135;5865.14955331466;5919.200907877382 10:51:02 INFO - PROCESS | 1642 | |5;yui.html;660.8777376185406;686.7596341280553;717.2683862460292;720.5784615999637;790.6205021589641;11904.22622529536;15293.617659558502;15362.923185384057;15404.768928466054;15481.767773483392;11810.7637847243;13882.930585347085;14038.508652197175;15585.064896106212;15650.921745391286;853.3685073045964;869.4131461263653;895.6462197432011;899.8155378147496;904.6969265296101;836.2640876029086;848.2365870716343;852.4885068958637;876.7983363826323;880.0451510111536;916.7066538707617;922.1746536849515;931.3759780946748;932.3333816321328;938.4979036215626;895.5656506594329;918.9494577798218;928.3872644054925;929.5212965322864;931.5016466190623;670.1723371636026;676.4960104721964;688.3735800421642;688.9793306200822;690.5166383531548;14216.573502794932;14590.343434545472;14610.123392596479;14640.70718585633;14654.120752754763;11314.208005439623;13759.312034398241;14083.85916140846;14239.00326977112;14261.34187528299;11536.192466527347;11604.361760103191;11868.821967670405;11984.70038249042;11994.22037567564;3799.5060642116623;3848.8966463034535;3859.556151042638;3862.8841134766003;3886.3393223152134;60926.47683807896;64856;66547.66726166339;66712.33287667109;84505.15494845096;7871.881921771063;10582;10888.564457421773;10952.452377381258;11292.887071129184;20553.48616284575;20709.68935465969;20740.896295518727;20814.167433302802;20899;7651.262363805428;7763.029621297338;7763.650635721406;7981.40139489529;8169.42814003026;3671.265746850587;4293.527711951683;4359.258925982591;4433.290673492225;4511.1654343946475;3407.778494397906;3508.3158784037378;3512.5609298837644;3527.5590551181103;3672.9632703673155;715.1096884378982;736.1681300130925;741.5724729894931;743.0822933676905;747.7270796159363;556.9053260945649;607.1014897951057;612.8284080457479;645.2740666749906;699.3076853914588;8082.232187942136;8434.114417986146;8948;9059.184673379428;9445.622175113056;546.2925511462665;603.303184821523;611.2298503885137;621.7409745265783;679.9558028728216;3887.5140607424073;4474.194644963938;4595.184901963706;4700.848292168441;4780.880477988008;105616.47191763992;110260.448697756;111062;111446.44276778564;112147;85606.57196713977;89822;95449.04550954401;98589;98933;87525;88043.1195688035;89285.55357223173;89392.5530372357;90263.54868225617;8973.37186396972;9027.819443611223;9069.863952040856;9078.409903356262;9374.718758437259;46272;57196;57399;57652;59223.111653326094;73083.80748577551;75261;80097.5995120044;81473;86953;11810.82283765727;11882.049436044923;11905.940470297593;11931.880681193425;12014.459349329476;12652.430695258055;12661.303628300164;12777.616671499869;12823.525294591242;13118.212907225598;11795.174337796616;11934.462949167482;11948.163628545919;11960;12342.074344424025;11684.279658487047;11761.88238117608;11991.22057066288;11999.1600587958;12010.939945300217;11527.250728702607;12000.63998080059;12214.694632634255;12247.326397048248;12353.505859765508;10127.544260508443;10184.847227291448;10212.897871021194;10555.208359372924;10623.681289561326;10174.440405777754;10453.26827122094;10456;10476.895231047592;10478.580856765644 10:51:02 INFO - PROCESS | 1642 | __end_tp_report 10:51:02 INFO - PROCESS | 1642 | __start_cc_report 10:51:02 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,366 10:51:02 INFO - PROCESS | 1642 | __end_cc_report 10:51:02 INFO - PROCESS | 1642 | __startTimestamp1485888662361__endTimestamp 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | ------- Summary: start ------- 10:51:02 INFO - PROCESS | 1642 | Number of tests: 6 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5884.38 Median:6142.48 stddev:665.77 (10.8%) stddev-sans-first:642.01 10:51:02 INFO - PROCESS | 1642 | Values: 3544.4 3551.5 3570.6 3580.3 3615.9 5934.3 6003.5 6013.2 6016.2 6017.3 5967.0 6030.7 6048.1 6064.9 6082.5 5165.4 5345.4 5381.7 5402.7 5495.7 5408.9 5429.0 5449.6 5459.7 5463.3 3927.9 4158.3 4822.6 5448.5 5462.4 5276.2 5354.8 5417.2 5420.4 5473.6 5259.4 5373.1 5379.4 5380.7 5416.0 6003.8 6016.1 6076.7 6113.1 6140.4 6156.2 6170.1 6188.7 6202.7 6209.6 6363.2 6365.2 6366.1 6376.9 6408.5 6023.7 6115.5 6117.7 6119.9 6121.2 6205.5 6349.4 6382.4 6411.6 6473.9 6211.1 6265.7 6285.2 6308.8 6317.5 6351.7 6367.8 6384.6 6391.6 6402.1 6184.7 6193.3 6233.2 6258.8 6292.7 5144.4 6240.7 6252.7 6262.4 6446.7 5058.8 5072.2 5083.7 5086.2 5102.7 4484.2 4497.3 4555.9 4593.0 4602.1 4548.7 5969.4 5995.6 6029.1 6050.4 5676.4 5919.1 6202.4 6274.5 6281.3 5937.3 6003.6 6048.0 6049.4 6096.0 5603.9 5636.2 5642.8 5661.8 5663.4 6548.6 6565.7 6573.2 6602.5 6626.2 6129.7 6234.3 6249.2 6376.2 6389.4 6463.6 6576.2 6594.2 6615.0 6616.8 6302.5 6302.7 6304.1 6304.9 6324.0 6177.3 6250.5 6296.9 6371.3 6380.5 6054.5 6222.9 6282.7 6283.8 6308.3 6224.5 6265.5 6275.1 6275.2 6281.0 6006.7 6083.2 6142.5 6165.2 6249.0 6194.9 6199.9 6223.4 6224.3 6288.5 6218.7 6222.2 6230.3 6246.1 6267.7 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:72845.18 Median:40744.49 stddev:161432.05 (396.2%) stddev-sans-first:161793.42 10:51:02 INFO - PROCESS | 1642 | Values: 459.3 470.7 506.1 512.8 571.3 33145.0 33512.0 38628.8 40233.6 41758.4 34754.7 35936.7 41304.6 42535.8 42546.9 9623.1 9627.2 9718.5 9732.4 9817.9 9688.5 9791.7 9794.5 9820.2 9833.1 9719.9 9769.4 9779.3 9795.1 9831.1 9721.0 9723.8 9726.0 9817.9 9828.0 9594.1 9637.7 9692.0 9706.8 9713.4 39991.0 40690.6 40798.4 40892.0 40952.6 3576.4 3608.0 3914.3 3984.4 4133.6 26732.3 28855.9 29060.0 29684.4 30049.2 3306.1 3343.2 3345.7 3438.7 3472.0 493651.0 494868.0 500393.0 500494.0 502594.0 62657.4 62671.0 62949.7 63046.0 63159.7 64554.0 64878.4 65955.0 66172.0 66308.7 16074.0 16549.3 16802.2 16824.4 16857.9 12422.5 12502.4 12888.6 12963.9 12987.7 17403.5 17762.6 17788.5 17834.3 17836.2 5896.4 6099.6 6543.4 6556.6 6558.6 856.3 940.6 964.4 1061.0 1142.9 26736.8 30302.9 30425.2 30495.8 30854.5 991.7 999.5 1031.0 1045.4 1154.3 12699.9 15384.4 15792.9 15830.4 15997.8 662667.0 676200.0 677321.0 678592.0 682011.0 59204.7 59566.4 60024.7 60109.7 60172.7 1198.6 1372.2 1382.5 1405.8 1411.7 29514.4 29985.0 30169.7 30405.4 30451.8 51452.5 52301.7 52332.0 52386.2 52468.7 614128.0 621281.0 624396.0 624426.0 624850.0 27391.5 27460.7 27702.9 27814.7 27825.7 42014.9 47128.8 47623.5 47729.3 48556.3 39211.2 39244.4 39305.8 39383.0 40817.2 38893.8 39377.6 39705.6 40261.0 40375.8 37989.0 38561.2 38910.8 39253.8 39413.4 29730.6 33696.0 34844.8 35582.5 35636.6 21445.8 26168.2 26382.7 26463.1 26622.5 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:35883.01 Median:1451.46 stddev:103865.63 (7155.9%) stddev-sans-first:104122.66 10:51:02 INFO - PROCESS | 1642 | Values: 310.7 381.5 386.2 394.7 415.0 19061.9 20220.6 20520.4 20940.6 21058.8 20319.0 20470.5 20578.9 20615.2 21103.7 5688.3 5808.4 5875.8 5957.2 5963.3 4308.7 4541.9 5932.4 5979.9 5981.0 6271.3 6538.4 6579.6 6632.2 6645.9 6449.6 6535.6 6570.6 6609.9 6625.3 5652.5 5785.0 5805.9 5814.2 5819.2 24659.0 24874.6 25238.1 25336.0 25874.6 2313.7 2333.8 2380.0 2528.1 2592.2 8386.4 9760.8 10180.3 10381.8 10566.4 1876.8 1954.9 1958.0 1990.4 2026.3 90719.0 93421.1 100264.5 126354.1 127821.0 11495.7 11621.7 11824.8 11875.6 11886.0 14952.6 14964.6 14986.8 15035.2 15040.6 8671.5 8736.8 8829.1 8838.2 8891.2 7535.8 8642.9 8667.5 8766.1 8860.6 3849.7 4554.3 4720.8 4733.3 4774.0 1683.6 1819.8 1857.9 1929.2 1986.9 815.0 935.5 951.5 977.9 1016.8 20845.4 21431.6 21555.9 21590.4 21606.6 873.6 938.8 949.9 967.4 1027.3 11099.4 11362.7 11385.9 11400.3 11436.9 608558.0 620481.0 620988.0 632904.0 633690.0 106875.9 121371.4 140762.0 146873.0 151857.0 985.4 990.3 1028.8 1031.3 1033.0 19321.8 19684.9 19695.0 19980.6 20081.0 55836.6 56788.9 57339.3 57359.0 58193.7 79676.6 81087.6 81640.2 81652.2 84122.3 7902.1 8120.0 8185.2 8318.5 8340.4 9115.4 12306.8 12832.9 12857.5 12876.6 9580.9 11076.6 11103.1 11106.7 11442.5 9029.1 10586.3 10888.7 11193.6 11555.2 11424.4 11444.8 11547.5 11748.8 11876.3 8103.4 8160.8 8312.7 8351.3 8520.3 8373.9 9300.4 10990.0 11131.6 11206.0 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5543.15 Median:5133.33 stddev:3024.38 (58.9%) stddev-sans-first:3017.69 10:51:02 INFO - PROCESS | 1642 | Values: 1512.0 1537.9 1733.9 1831.5 1887.2 7714.1 7957.4 8228.9 8708.2 8712.0 8304.9 8594.5 8604.2 8606.6 8609.7 2039.4 2353.9 2394.6 2411.5 2413.2 2381.5 2418.8 2426.7 2430.7 2441.1 2359.4 2387.1 2404.3 2407.7 2409.5 2358.7 2373.1 2375.7 2384.7 2391.8 2398.9 2399.6 2423.5 2424.5 2431.2 8428.1 8433.4 8455.3 8562.4 8600.0 5181.9 5343.4 5346.9 5353.5 5365.8 3540.5 3590.8 3590.9 3615.5 3703.2 1063.9 1072.4 1097.5 1106.4 1109.3 11121.9 11136.1 11178.0 11199.8 11251.3 4545.0 4666.6 4741.1 4757.0 4826.6 9501.5 9593.5 9610.5 9613.9 9629.5 5283.3 5296.5 5334.5 5341.2 5366.2 4062.4 4403.3 4408.0 4889.2 4891.3 3913.1 3946.2 3995.8 4037.8 4055.3 1309.3 1321.8 1322.4 1328.3 1337.1 744.4 863.9 867.2 918.3 926.7 5524.3 5655.3 5688.9 5694.1 5888.2 841.0 885.7 886.9 965.4 995.4 3796.8 3807.6 3830.0 3855.8 3861.6 7963.6 10140.6 10188.2 10490.7 10495.7 9117.5 10402.4 10426.5 10486.4 10686.5 7999.0 8111.6 8120.6 8126.8 8164.4 4664.3 4869.9 5084.7 5558.0 5740.2 9361.0 9380.9 9434.6 9536.8 9567.7 9216.1 9575.6 9646.5 9734.2 9734.4 6723.7 6906.3 7044.8 7067.1 7356.1 7911.6 8109.3 8113.4 8114.1 8125.0 7192.0 7296.2 7386.0 7399.6 7435.3 7123.5 7243.3 7251.1 7253.9 7262.7 6686.4 6746.8 6805.0 6832.5 6834.1 4815.9 4844.2 4869.8 4915.1 4975.2 4858.8 4907.4 4919.0 4959.4 4999.4 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4611.22 Median:5096.77 stddev:1430.17 (28.1%) stddev-sans-first:1397.25 10:51:02 INFO - PROCESS | 1642 | Values: 308.9 313.9 320.9 323.8 326.0 4691.0 5250.4 5293.5 5419.5 5560.5 4779.8 4839.6 4945.6 5453.3 5495.8 3697.4 4031.7 4238.0 4300.3 4332.4 3589.9 3590.7 4170.9 4328.0 4346.7 4145.9 4227.4 4237.8 4238.4 4258.7 4234.7 4242.5 4256.7 4265.7 4295.3 2991.0 3728.5 3732.5 3742.3 3743.8 4298.1 4666.8 5171.7 5320.2 5365.3 4359.5 4694.4 4928.3 4935.1 4973.4 4740.4 5160.5 5724.4 5848.0 5897.7 3222.6 3929.6 3985.8 3994.5 3997.0 5306.0 5368.8 5628.3 6134.7 6147.5 3440.5 3624.1 3721.9 4029.5 4047.8 3984.9 4021.3 4029.4 4083.9 4084.4 4767.7 4782.7 4783.4 4792.7 4823.4 5244.6 5896.9 5919.8 5927.6 5936.9 1175.7 1185.8 1189.4 1271.3 1323.6 890.2 895.6 900.9 901.8 910.9 4652.7 4743.4 4948.2 5238.7 5243.8 5070.9 5122.6 5307.8 5665.4 5716.3 4385.0 5051.4 5138.8 5238.9 5255.0 2439.7 2510.0 2823.8 2946.3 3012.0 5784.3 6005.9 6017.8 6027.8 6049.7 5013.7 5308.9 5861.9 5972.4 5983.3 5741.6 5929.9 5954.7 6003.7 6089.4 5063.6 5137.5 5501.2 5570.5 5882.8 4808.0 5383.0 5655.6 5666.7 5681.6 5947.2 5955.7 5994.9 6005.5 6017.5 3622.4 3779.6 3835.8 3848.8 3848.8 5055.5 5265.0 5307.5 5708.7 5987.6 5449.3 5946.4 5947.6 5973.4 6012.9 4555.0 4774.4 5906.0 5917.9 5998.9 4576.6 4975.3 5476.6 5833.1 5848.0 5420.0 5583.0 5916.0 5985.0 5995.2 5167.6 5661.2 5852.4 5865.1 5919.2 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20177.81 Median:4553.18 stddev:29492.36 (647.7%) stddev-sans-first:29538.68 10:51:02 INFO - PROCESS | 1642 | Values: 660.9 686.8 717.3 720.6 790.6 11904.2 15293.6 15362.9 15404.8 15481.8 11810.8 13882.9 14038.5 15585.1 15650.9 853.4 869.4 895.6 899.8 904.7 836.3 848.2 852.5 876.8 880.0 916.7 922.2 931.4 932.3 938.5 895.6 918.9 928.4 929.5 931.5 670.2 676.5 688.4 689.0 690.5 14216.6 14590.3 14610.1 14640.7 14654.1 11314.2 13759.3 14083.9 14239.0 14261.3 11536.2 11604.4 11868.8 11984.7 11994.2 3799.5 3848.9 3859.6 3862.9 3886.3 60926.5 64856.0 66547.7 66712.3 84505.2 7871.9 10582.0 10888.6 10952.5 11292.9 20553.5 20709.7 20740.9 20814.2 20899.0 7651.3 7763.0 7763.7 7981.4 8169.4 3671.3 4293.5 4359.3 4433.3 4511.2 3407.8 3508.3 3512.6 3527.6 3673.0 715.1 736.2 741.6 743.1 747.7 556.9 607.1 612.8 645.3 699.3 8082.2 8434.1 8948.0 9059.2 9445.6 546.3 603.3 611.2 621.7 680.0 3887.5 4474.2 4595.2 4700.8 4780.9 105616.5 110260.4 111062.0 111446.4 112147.0 85606.6 89822.0 95449.0 98589.0 98933.0 87525.0 88043.1 89285.6 89392.6 90263.5 8973.4 9027.8 9069.9 9078.4 9374.7 46272.0 57196.0 57399.0 57652.0 59223.1 73083.8 75261.0 80097.6 81473.0 86953.0 11810.8 11882.0 11905.9 11931.9 12014.5 12652.4 12661.3 12777.6 12823.5 13118.2 11795.2 11934.5 11948.2 11960.0 12342.1 11684.3 11761.9 11991.2 11999.2 12010.9 11527.3 12000.6 12214.7 12247.3 12353.5 10127.5 10184.8 10212.9 10555.2 10623.7 10174.4 10453.3 10456.0 10476.9 10478.6 10:51:02 INFO - PROCESS | 1642 | -------- Summary: end -------- 10:51:02 INFO - PROCESS | 1642 | 10:51:02 INFO - TEST-INFO | 1642: exit 0 10:51:02 INFO - TEST-OK | dromaeo_css | took 1113662ms 10:51:02 INFO - TEST-START | kraken 10:51:02 INFO - operating with platform_type : mac_ 10:51:02 INFO - Initialising browser for kraken test... 10:51:02 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpLCBaE3/profile http://localhost:49236/getInfo.html) 10:51:05 INFO - PROCESS | 1653 | 2017-01-31 10:51:05.757 plugin-container[1655:9796] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874b, name = 'com.apple.tsm.portname' 10:51:05 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:05 INFO - PROCESS | 1653 | 2017-01-31 10:51:05.757 plugin-container[1655:9796] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:51:05 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:05 INFO - PROCESS | 1653 | 2017-01-31 10:51:05.757 plugin-container[1655:9796] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:51:05 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 10:51:05 INFO - PROCESS | 1653 | colorDepth:24 10:51:05 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 10:51:05 INFO - PROCESS | 1653 | __metrics 10:51:05 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 10:51:06 INFO - TEST-INFO | 1653: exit 0 10:51:06 INFO - Browser initialized. 10:51:06 INFO - Running cycle 1/1 for kraken test... 10:51:06 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpLCBaE3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:51:09 INFO - PROCESS | 1656 | 2017-01-31 10:51:09.186 plugin-container[1657:9924] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:51:09 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:09 INFO - PROCESS | 1656 | 2017-01-31 10:51:09.187 plugin-container[1657:9924] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 10:51:09 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:09 INFO - PROCESS | 1656 | 2017-01-31 10:51:09.187 plugin-container[1657:9924] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:51:18 INFO - PROCESS | 1656 | RSS: Main: 177283072 10:51:18 INFO - PROCESS | 1656 | 10:51:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 10:51:22 INFO - PROCESS | 1656 | RSS: Main: 190128128 10:51:22 INFO - PROCESS | 1656 | 10:51:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 10:51:25 INFO - PROCESS | 1656 | RSS: Main: 199200768 10:51:25 INFO - PROCESS | 1656 | 10:51:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 10:51:29 INFO - PROCESS | 1656 | RSS: Main: 202063872 10:51:29 INFO - PROCESS | 1656 | 10:51:31 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 10:51:32 INFO - PROCESS | 1656 | RSS: Main: 202285056 10:51:32 INFO - PROCESS | 1656 | 10:51:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:51:34 INFO - PROCESS | 1656 | RSS: Main: 202162176 10:51:34 INFO - PROCESS | 1656 | 10:51:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 10:51:38 INFO - PROCESS | 1656 | RSS: Main: 206118912 10:51:38 INFO - PROCESS | 1656 | 10:51:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 10:51:41 INFO - PROCESS | 1656 | RSS: Main: 207355904 10:51:41 INFO - PROCESS | 1656 | 10:51:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 10:51:45 INFO - PROCESS | 1656 | RSS: Main: 206929920 10:51:45 INFO - PROCESS | 1656 | 10:51:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:51:47 INFO - PROCESS | 1656 | RSS: Main: 206606336 10:51:47 INFO - PROCESS | 1656 | 10:51:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:51:49 INFO - PROCESS | 1656 | RSS: Main: 206598144 10:51:49 INFO - PROCESS | 1656 | 10:51:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:51:52 INFO - PROCESS | 1656 | RSS: Main: 206606336 10:51:52 INFO - PROCESS | 1656 | 10:51:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:51:56 INFO - PROCESS | 1656 | RSS: Main: 206094336 10:51:56 INFO - PROCESS | 1656 | 10:51:59 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:51:59 INFO - PROCESS | 1656 | RSS: Main: 206409728 10:51:59 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 10:52:01 INFO - PROCESS | 1656 | RSS: Main: 208224256 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | __start_tp_report 10:52:01 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 10:52:01 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 10:52:01 INFO - PROCESS | 1656 | |i|pagename|runs| 10:52:01 INFO - PROCESS | 1656 | |0;ai-astar;105;121;117;124;116;116;147;175;160;117 10:52:01 INFO - PROCESS | 1656 | |1;audio-beat-detection;140;132;113;125;117;113;123;111;133;110 10:52:01 INFO - PROCESS | 1656 | |2;audio-dft;196;201;198;197;195;194;195;196;196;194 10:52:01 INFO - PROCESS | 1656 | |3;audio-fft;80;59;60;94;59;58;60;60;76;60 10:52:01 INFO - PROCESS | 1656 | |4;audio-oscillator;85;70;88;74;73;95;116;94;70;87 10:52:01 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;92;88;89;132;121;88;89;102;91;88 10:52:01 INFO - PROCESS | 1656 | |6;imaging-darkroom;97;97;96;98;96;96;96;96;153;142 10:52:01 INFO - PROCESS | 1656 | |7;imaging-desaturate;87;91;86;86;88;92;96;93;88;87 10:52:01 INFO - PROCESS | 1656 | |8;json-parse-financial;55;56;56;61;59;61;62;59;59;58 10:52:01 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;61;72;68;80;76;77;67;70;70;71 10:52:01 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;82;84;82;84;82;85;86;88;83;82 10:52:01 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;142;172;143;151;178;134;153;146;202;173 10:52:01 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;166;171;231;166;163;163;166;157;156;163 10:52:01 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;68;68;66;69;66;67;64;69;67 10:52:01 INFO - PROCESS | 1656 | __end_tp_report 10:52:01 INFO - PROCESS | 1656 | __start_cc_report 10:52:01 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,919 10:52:01 INFO - PROCESS | 1656 | __end_cc_report 10:52:01 INFO - PROCESS | 1656 | __startTimestamp1485888721820__endTimestamp 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | ------- Summary: start ------- 10:52:01 INFO - PROCESS | 1656 | Number of tests: 14 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:129.80 Median:122.50 stddev:22.82 (18.6%) stddev-sans-first:22.37 10:52:01 INFO - PROCESS | 1656 | Values: 105.0 121.0 117.0 124.0 116.0 116.0 147.0 175.0 160.0 117.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:121.70 Median:124.00 stddev:10.57 (8.5%) stddev-sans-first:8.90 10:52:01 INFO - PROCESS | 1656 | Values: 140.0 132.0 113.0 125.0 117.0 113.0 123.0 111.0 133.0 110.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:196.20 Median:196.00 stddev:2.10 (1.1%) stddev-sans-first:2.22 10:52:01 INFO - PROCESS | 1656 | Values: 196.0 201.0 198.0 197.0 195.0 194.0 195.0 196.0 196.0 194.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.00 stddev:12.39 (20.7%) stddev-sans-first:12.16 10:52:01 INFO - PROCESS | 1656 | Values: 80.0 59.0 60.0 94.0 59.0 58.0 60.0 60.0 76.0 60.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:86.00 stddev:14.41 (16.8%) stddev-sans-first:15.29 10:52:01 INFO - PROCESS | 1656 | Values: 85.0 70.0 88.0 74.0 73.0 95.0 116.0 94.0 70.0 87.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:98.00 Median:88.50 stddev:15.80 (17.9%) stddev-sans-first:16.61 10:52:01 INFO - PROCESS | 1656 | Values: 92.0 88.0 89.0 132.0 121.0 88.0 89.0 102.0 91.0 88.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:96.00 stddev:21.67 (22.6%) stddev-sans-first:22.70 10:52:01 INFO - PROCESS | 1656 | Values: 97.0 97.0 96.0 98.0 96.0 96.0 96.0 96.0 153.0 142.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:89.50 stddev:3.41 (3.8%) stddev-sans-first:3.50 10:52:01 INFO - PROCESS | 1656 | Values: 87.0 91.0 86.0 86.0 88.0 92.0 96.0 93.0 88.0 87.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:2.37 (4.0%) stddev-sans-first:2.12 10:52:01 INFO - PROCESS | 1656 | Values: 55.0 56.0 56.0 61.0 59.0 61.0 62.0 59.0 59.0 58.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.50 stddev:5.47 (7.7%) stddev-sans-first:4.39 10:52:01 INFO - PROCESS | 1656 | Values: 61.0 72.0 68.0 80.0 76.0 77.0 67.0 70.0 70.0 71.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:2.04 (2.4%) stddev-sans-first:2.06 10:52:01 INFO - PROCESS | 1656 | Values: 82.0 84.0 82.0 84.0 82.0 85.0 86.0 88.0 83.0 82.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:159.40 Median:162.50 stddev:21.11 (13.0%) stddev-sans-first:21.44 10:52:01 INFO - PROCESS | 1656 | Values: 142.0 172.0 143.0 151.0 178.0 134.0 153.0 146.0 202.0 173.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:166.00 stddev:21.81 (13.1%) stddev-sans-first:23.08 10:52:01 INFO - PROCESS | 1656 | Values: 166.0 171.0 231.0 166.0 163.0 163.0 166.0 157.0 156.0 163.0 10:52:01 INFO - PROCESS | 1656 | 10:52:01 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:68.00 stddev:1.55 (2.3%) stddev-sans-first:1.62 10:52:01 INFO - PROCESS | 1656 | Values: 68.0 68.0 68.0 66.0 69.0 66.0 67.0 64.0 69.0 67.0 10:52:01 INFO - PROCESS | 1656 | -------- Summary: end -------- 10:52:01 INFO - PROCESS | 1656 | 10:52:02 INFO - TEST-INFO | 1656: exit 0 10:52:02 INFO - TEST-OK | kraken | took 59862ms 10:52:02 INFO - SUITE-END | took 1173s 10:52:02 INFO - Completed test suite (00:19:33) 10:52:02 INFO - javascript benchmark 10:52:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7333.685706411164, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3544.4328907374816, 3551.4850346699714, 3570.5715314162303, 3580.337637537055, 3615.891523254303, 5934.317553481351, 6003.549733769963, 6013.158157857898, 6016.247969003869, 6017.338092809799, 5967.04527275636, 6030.728617212225, 6048.09278608209, 6064.939350606493, 6082.452579267865, 5165.38015438148, 5345.358556973159, 5381.67709937403, 5402.729863506828, 5495.670259784426, 5408.891822163554, 5429.0, 5449.618526703132, 5459.727013649322, 5463.289772329612, 3927.8609203331, 4158.293090174662, 4822.601445580777, 5448.482394172567, 5462.399136095027, 5276.208568714705, 5354.785808567653, 5417.160340147283, 5420.430854760233, 5473.644213126134, 5259.3951695555115, 5373.059714549938, 5379.435159308256, 5380.730963451851, 5416.0, 6003.849903752397, 6016.127661489103, 6076.696165191723, 6113.083037544404, 6140.385961403868, 6156.168917196211, 6170.136180934628, 6188.721507532172, 6202.658853763041, 6209.627422354673, 6363.204599425072, 6365.204349456318, 6366.0769188467875, 6376.904346434807, 6408.51936104794, 6023.698815059273, 6115.480184184395, 6117.663528505975, 6119.938800611982, 6121.234845644294, 6205.503559715212, 6349.365063493614, 6382.425581697669, 6411.551191416649, 6473.870522589522, 6211.09939058834, 6265.686715664289, 6285.151504546919, 6308.842278943063, 6317.46301564363, 6351.682415879188, 6367.777127800505, 6384.648844313608, 6391.552591318656, 6402.103705481236, 6184.6598437086395, 6193.287771906198, 6233.189685340967, 6258.780942666985, 6292.716827742763, 5144.382674079135, 6240.719167637467, 6252.718627661766, 6262.405071518198, 6446.677666116675, 5058.797648094109, 5072.239164125411, 5083.669561478566, 5086.23706444029, 5102.719350435762, 4484.2376795944765, 4497.280435130363, 4555.931661025087, 4592.977035114803, 4602.125596136723, 4548.727076375428, 5969.432903874125, 5995.61028533144, 6029.065494848305, 6050.364711705296, 5676.403977582377, 5919.052951527735, 6202.379762023852, 6274.529410294246, 6281.3404592518045, 5937.287525496968, 6003.609765365237, 6048.0, 6049.425304596057, 6096.0, 5603.915941260884, 5636.239107720406, 5642.830715078554, 5661.830145095653, 5663.405342439067, 6548.639824809681, 6565.704543295467, 6573.244076931119, 6602.471802255712, 6626.204855417381, 6129.664444256008, 6234.325686678318, 6249.1563638908165, 6376.202974628171, 6389.424951754178, 6463.57986730861, 6576.243731970884, 6594.175728033996, 6615.0, 6616.801495955129, 6302.495800335871, 6302.747890084345, 6304.117423560613, 6304.873902521832, 6323.988161894074, 6177.320494745484, 6250.468710159687, 6296.874062518632, 6371.331010243857, 6380.457661098859, 6054.515638748801, 6222.875542489215, 6282.748690052346, 6283.842903927439, 6308.27454842699, 6224.502039836892, 6265.530085243534, 6275.090111933835, 6275.152854364603, 6281.0, 6006.729697163551, 6083.239595050619, 6142.477889379274, 6165.167702360303, 6248.968755156195, 6194.876102477835, 6199.876002480015, 6223.408776166257, 6224.253089629274, 6288.528360373082, 6218.657973811484, 6222.16000839899, 6230.283517395376, 6246.094316324198, 6267.749290028348], "value": 5843.5590165051735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.3110334498253, 470.67758585369006, 506.13703635301835, 512.7538781384934, 571.331542095748, 33145.0, 33511.994640160774, 38628.841134765935, 40233.597664023386, 41758.37362439558, 34754.65245347546, 35936.74221402252, 41304.58695413045, 42535.78732106343, 42546.93632659185, 9623.085806848338, 9627.22982161429, 9718.465484398355, 9732.416055036685, 9817.920028796827, 9688.515574221261, 9791.706248812547, 9794.510274486283, 9820.21438284939, 9833.115019648267, 9719.902800972006, 9769.364991275545, 9779.266555008368, 9795.06946840049, 9831.115199632031, 9720.951395243013, 9723.805523889554, 9725.95137024314, 9817.852732209023, 9828.017198280115, 9594.13652771247, 9637.662681806103, 9691.982341854145, 9706.805863882682, 9713.36863103896, 39991.0, 40690.59309406927, 40798.38802417966, 40892.0, 40952.590474095174, 3576.356255873942, 3608.0438683748825, 3914.256291304643, 3984.442178095069, 4133.607307305801, 26732.33169170767, 28855.85572072126, 29060.0, 29684.40631187364, 30049.248768780955, 3306.123877172551, 3343.2143446290347, 3345.7156141727983, 3438.673326034023, 3472.0, 493651.0, 494868.0, 500393.0, 500493.99999999633, 502594.0, 62657.373426266066, 62671.0, 62949.74100517964, 63046.0, 63159.6842015787, 64554.0, 64878.40486380587, 65955.0, 66172.0, 66308.66845665741, 16074.0, 16549.25528351227, 16802.15989200535, 16824.411145609844, 16857.904236224607, 12422.503099876085, 12502.374881256083, 12888.613341599766, 12963.870361296265, 12987.675308117372, 17403.47789566315, 17762.5559361017, 17788.46634600964, 17834.28662853471, 17836.19737111833, 5896.380880007538, 6099.634021958696, 6543.443807276435, 6556.557557337378, 6558.573692709959, 856.3063918226256, 940.6079003075487, 964.3827950111934, 1060.9628662996913, 1142.857142857143, 26736.79684414206, 30302.939397120994, 30425.239369015508, 30495.780168793444, 30854.537181942287, 991.6529214774771, 999.4902599674218, 1030.9639162629271, 1045.3727763342085, 1154.261272785418, 12699.936500317439, 15384.38462461489, 15792.894497385303, 15830.366785328688, 15997.760033599505, 662667.0, 676200.0, 677321.0, 678592.0, 682011.0, 59204.70397647984, 59566.404335956955, 60024.69987650034, 60109.69945150334, 60172.69913650491, 1198.5924785572947, 1372.19726460022, 1382.4815694114707, 1405.8102156208781, 1411.6541447345671, 29514.409711805216, 29984.95052673146, 30169.69830301669, 30405.39189216159, 30451.847740761154, 51452.48547514477, 52301.7384913073, 52331.95336093333, 52386.21420678769, 52468.737656311474, 614128.0, 621281.0, 624396.0, 624426.0, 624850.0, 27391.452170956072, 27460.725392746615, 27702.861485692443, 27814.721852782022, 27825.747841346783, 42014.94962625836, 47128.764356178, 47623.52376476191, 47729.28406073981, 48556.2716559259, 39211.2157756849, 39244.41133383058, 39305.803470982464, 39383.01542461461, 40817.1836563273, 38893.80553097217, 39377.60622393739, 39705.61030363923, 40261.0, 40375.79812101038, 37989.0, 38561.22877542378, 38910.832675019796, 39253.80373098116, 39413.408798868615, 29730.554041689826, 33696.0, 34844.82577587096, 35582.46626300556, 35636.64363356333, 21445.82047987313, 26168.214953551425, 26382.736172638797, 26463.073792417174, 26622.467550649268], "value": 20103.719342922424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.7172473049524, 381.54786577905185, 386.1735885205659, 394.74933417280016, 414.9564295748946, 19061.904690476546, 20220.59558808823, 20520.384388468352, 20940.581188376244, 21058.84176370299, 20318.984050797473, 20470.488237794063, 20578.89710551445, 20615.175392984263, 21103.68344474834, 5688.345840228385, 5808.4191580842, 5875.823725288248, 5957.225560677106, 5963.284405871302, 4308.655307575386, 4541.863744087666, 5932.436418540254, 5979.910301345461, 5980.97009514954, 6271.2788029376525, 6538.378854008862, 6579.638119903403, 6632.204135503722, 6645.900311495331, 6449.6130232186215, 6535.640539770311, 6570.5729127606555, 6609.867802643944, 6625.271220165778, 5652.491275785199, 5784.971075144639, 5805.8548536286935, 5814.244148260741, 5819.24349834523, 24659.0, 24874.626880596807, 25238.116665916605, 25336.0, 25874.6118808218, 2313.687652166953, 2333.754955729641, 2379.988100059506, 2528.102523604131, 2592.2093761402766, 8386.371022173362, 9760.755981100458, 10180.287379883412, 10381.79236415275, 10566.418846963414, 1876.8398559495618, 1954.9315773947844, 1957.9902100489549, 1990.3531352310556, 2026.250287393659, 90719.0, 93421.06578934191, 100264.49603255957, 126354.10468842975, 127821.0, 11495.655130346104, 11621.663508696609, 11824.822627660593, 11875.643730688093, 11885.96621883294, 14952.626184345478, 14964.625884352978, 14986.775198372034, 15035.173065481285, 15040.623984400478, 8671.523066231417, 8736.78158046054, 8829.072947340564, 8838.204561589488, 8891.19979201875, 7535.794272916307, 8642.870356944652, 8667.479951202948, 8766.079561646064, 8860.601272942733, 3849.711271654637, 4554.339620754972, 4720.763961801897, 4733.290006499053, 4773.9761301193275, 1683.5620944349819, 1819.7543677564383, 1857.8699491035768, 1929.218666440094, 1986.8609197356336, 814.9918500815035, 935.5228833295068, 951.46242373059, 977.8679878216492, 1016.8068067067234, 20845.374638760863, 21431.57136857285, 21555.892220538797, 21590.352289431343, 21606.567868642538, 873.6472797351336, 938.8075444533854, 949.924006079512, 967.4485543240426, 1027.342500799489, 11099.44502774858, 11362.715932101764, 11385.861413858547, 11400.315981041163, 11436.94281528587, 608558.0, 620481.0, 620988.0, 632904.0, 633690.0, 106875.93124068815, 121371.39314303373, 140762.0, 146873.0, 151857.0, 985.4038306824411, 990.2869933647762, 1028.8096702110133, 1031.2832581356035, 1032.9793404131876, 19321.806781932002, 19684.90157549203, 19695.0, 19980.600387991868, 20081.0, 55836.6040848982, 56788.86422271449, 57339.27982160542, 57359.0, 58193.70903145627, 79676.60161699154, 81087.59456202682, 81640.18359816563, 81652.18347816446, 84122.31755364736, 7902.130765615662, 8120.025596928406, 8185.181481851767, 8318.45930014547, 8340.41617086798, 9115.407498512575, 12306.75386492283, 12832.935835321137, 12857.485700572246, 12876.613701588967, 9580.856287155837, 11076.5569377224, 11103.111751060058, 11106.722331941766, 11442.48508817122, 9029.142231487998, 10586.258961872594, 10888.67333979982, 11193.552257909592, 11555.191136620357, 11424.428778560872, 11444.828327574927, 11547.4803633835, 11748.765024699629, 11876.287422754662, 8103.351731861319, 8160.83678326442, 8312.667493300441, 8351.33189344863, 8520.275776558814, 8373.916260837314, 9300.39547429415, 10990.010899019127, 11131.554737810397, 11206.0], "value": 9694.054802900193, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1511.9924400378, 1537.9000364976275, 1733.921973511192, 1831.5054935167514, 1887.2168050259143, 7714.074311082664, 7957.442978991473, 8228.876566851503, 8708.172723591257, 8712.0, 8304.91695083049, 8594.484330940148, 8604.182602652754, 8606.56967151643, 8609.698660546883, 2039.4391542325832, 2353.9176128835493, 2394.6048901931163, 2411.5418070566625, 2413.2036427978815, 2381.535600557892, 2418.7823095921362, 2426.733059363469, 2430.720467146274, 2441.121196369306, 2359.386559494535, 2387.092904696213, 2404.3388068281274, 2407.6990376202975, 2409.4819613783034, 2358.705161854768, 2373.1338061607476, 2375.7386687464364, 2384.6780684607616, 2391.7966972807244, 2398.8965076065033, 2399.6160614301803, 2423.466837295792, 2424.478737071538, 2431.234161239204, 8428.115047920002, 8433.367497437717, 8455.323574114056, 8562.443441176303, 8599.968003839516, 5181.948180518184, 5343.438938911436, 5346.866328341784, 5353.464653534655, 5365.812196573102, 3540.504329393861, 3590.8025058621765, 3590.9281814363567, 3615.5480564929385, 3703.203811180609, 1063.888291729365, 1072.3673032910542, 1097.5170924793144, 1106.4191299567792, 1109.2789686703627, 11121.944390277997, 11136.053435457996, 11178.0, 11199.832002519968, 11251.268667536584, 4544.977275113603, 4666.60333871618, 4741.051789642016, 4756.9762151189025, 4826.638002149783, 9501.477418741897, 9593.520323983912, 9610.51947402627, 9613.90386096144, 9629.470379129076, 5283.313169288045, 5296.523312901781, 5334.546563542065, 5341.172118321666, 5366.221897824794, 4062.410950412233, 4403.251447253974, 4407.955920440819, 4889.168841296988, 4891.3396691446915, 3913.099987003005, 3946.1910308386714, 3995.800209989489, 4037.7779222143063, 4055.3105971984833, 1309.3453273363318, 1321.8017297405465, 1322.3718733601654, 1328.2561765411401, 1337.0640551613715, 744.3524134003452, 863.9092895246034, 867.2021739999216, 918.3387960668308, 926.7405126564573, 5524.3370795504, 5655.293088363955, 5688.8738046277695, 5694.145878118233, 5888.205092312569, 840.974011705718, 885.7475619448426, 886.8847049883474, 965.4496936746128, 995.437577768573, 3796.8481260749813, 3807.6192380762254, 3830.042489377656, 3855.7879316637855, 3861.613838616116, 7963.641636126388, 10140.645077422254, 10188.184945204366, 10490.73773155662, 10495.737606559744, 9117.452952822852, 10402.375857448576, 10426.478676066166, 10486.37081775096, 10686.51910664022, 7998.960005200053, 8111.594420279081, 8120.6345714443, 8126.75619731409, 8164.428490005761, 4664.253719404879, 4869.878253043702, 5084.720340381315, 5558.0, 5740.225069615549, 9361.017093205115, 9380.906190938276, 9434.622615095594, 9536.761580960532, 9567.712968610675, 9216.124468175512, 9575.569099390683, 9646.517674116125, 9734.221262299141, 9734.36726612768, 6723.731050757915, 6906.309369063254, 7044.823879402851, 7067.116610423697, 7356.117265928123, 7911.643976020978, 8109.270165685117, 8113.432059755761, 8114.107448180577, 8125.0, 7191.964040179766, 7296.160941491797, 7385.9630701846145, 7399.630018498946, 7435.256474352522, 7123.465740069412, 7243.348098671145, 7251.093613298338, 7253.854922901618, 7262.709491620276, 6686.364795344434, 6746.763863264762, 6805.0132730752775, 6832.487563432862, 6834.1457317835275, 4815.927761083657, 4844.249141383091, 4869.805207791649, 4915.090708218991, 4975.203967365204, 4858.781354839111, 4907.386576677915, 4918.975405122951, 4959.429665588504, 4999.375078115236], "value": 4506.235090961935, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.8918878392561, 313.9372125574885, 320.8652366006277, 323.7733586489458, 325.99837000814995, 4690.976545117278, 5250.422453530114, 5293.517815011798, 5419.512243898049, 5560.527355174805, 4779.808807647699, 4839.637027222963, 4945.579625731808, 5453.291072160623, 5495.752691128899, 3697.408414653656, 4031.6976226782963, 4237.957620423787, 4300.279888044792, 4332.393464914914, 3589.85640574378, 3590.7486475946694, 4170.8540201092965, 4328.004558951443, 4346.652537713303, 4145.937810932838, 4227.435848735972, 4237.809298581571, 4238.364245363189, 4258.744475331475, 4234.724742891703, 4242.512111107231, 4256.6807489438415, 4265.7014009019385, 4295.269804133305, 2990.9700902991126, 3728.45937339087, 3732.4883422214048, 3742.251549690073, 3743.7753734776, 4298.065870358351, 4666.766661666937, 5171.715555644476, 5320.206891382017, 5365.3293338332705, 4359.531480232755, 4694.413198350207, 4928.28539861718, 4935.062338155775, 4973.4280557735965, 4740.431148262196, 5160.509751573594, 5724.398938111522, 5848.0, 5897.705114744245, 3222.6455089940087, 3929.607039296076, 3985.8405663773415, 3994.520657521087, 3997.020729921159, 5306.0, 5368.8389348319615, 5628.296462942173, 6134.66882584615, 6147.47746441553, 3440.4839274109086, 3624.0939765058733, 3721.888343349704, 4029.4761680981283, 4047.817848196838, 3984.940225896614, 4021.270853533009, 4029.3955906613655, 4083.876933843217, 4084.428180054735, 4767.73777442244, 4782.736949467743, 4783.378160839069, 4792.736399497991, 4823.421189457217, 5244.587489754756, 5896.882062358729, 5919.8096704219715, 5927.614705044159, 5936.910946335808, 1175.723704929341, 1185.8102703567388, 1189.4052973513244, 1271.3071376100047, 1323.5698398020488, 890.2343984173606, 895.5611750242465, 900.9054049324726, 901.7925877048316, 910.8770316007386, 4652.674312798072, 4743.430788305356, 4948.233023881304, 5238.68567885928, 5243.764030618631, 5070.923936140961, 5122.615803814729, 5307.788787783413, 5665.405132461115, 5716.342620598602, 4385.0, 5051.419086804991, 5138.768755406016, 5238.92141617876, 5254.97372513135, 2439.670644462975, 2509.962350564743, 2823.7740980721906, 2946.2634341414646, 3011.9849400752855, 5784.305883294031, 6005.8559593880955, 6017.759289628453, 6027.819165425044, 6049.667268300285, 5013.724245166478, 5308.9116731070035, 5861.88276234473, 5972.402759723993, 5983.282006159202, 5741.62679425836, 5929.911051334234, 5954.702264886825, 6003.699815009232, 6089.421504957022, 5063.569596584184, 5137.51193636604, 5501.229827824028, 5570.5265052470995, 5882.764689412375, 4807.95192048075, 5383.0, 5655.632383895034, 5666.716664166693, 5681.573881958787, 5947.245562559142, 5955.702214889325, 5994.850128746816, 6005.519558435402, 6017.488513476228, 3622.4385220290897, 3779.640934111255, 3835.808209589565, 3848.807559621952, 3848.8075596220638, 5055.469175736642, 5264.973330200574, 5307.469253074816, 5708.65748055118, 5987.64074155552, 5449.3460784706085, 5946.37271264537, 5947.613405128825, 5973.402659733992, 6012.8797424052145, 4555.0, 4774.40319960005, 5905.962442957173, 5917.881642367214, 5998.850603433232, 4576.610988065919, 4975.328330675313, 5476.561875050066, 5833.133397312989, 5848.035074212878, 5420.043368341212, 5583.022970980143, 5915.970420148044, 5984.982552966093, 5995.220621319375, 5167.61242906791, 5661.179129026185, 5852.4147585241135, 5865.14955331466, 5919.200907877382], "value": 4152.711767988302, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.8777376185406, 686.7596341280553, 717.2683862460292, 720.5784615999637, 790.6205021589641, 11904.22622529536, 15293.617659558502, 15362.923185384057, 15404.768928466054, 15481.767773483392, 11810.7637847243, 13882.930585347085, 14038.508652197175, 15585.064896106212, 15650.921745391286, 853.3685073045964, 869.4131461263653, 895.6462197432011, 899.8155378147496, 904.6969265296101, 836.2640876029086, 848.2365870716343, 852.4885068958637, 876.7983363826323, 880.0451510111536, 916.7066538707617, 922.1746536849515, 931.3759780946748, 932.3333816321328, 938.4979036215626, 895.5656506594329, 918.9494577798218, 928.3872644054925, 929.5212965322864, 931.5016466190623, 670.1723371636026, 676.4960104721964, 688.3735800421642, 688.9793306200822, 690.5166383531548, 14216.573502794932, 14590.343434545472, 14610.123392596479, 14640.70718585633, 14654.120752754763, 11314.208005439623, 13759.312034398241, 14083.85916140846, 14239.00326977112, 14261.34187528299, 11536.192466527347, 11604.361760103191, 11868.821967670405, 11984.70038249042, 11994.22037567564, 3799.5060642116623, 3848.8966463034535, 3859.556151042638, 3862.8841134766003, 3886.3393223152134, 60926.47683807896, 64856.0, 66547.66726166339, 66712.33287667109, 84505.15494845096, 7871.881921771063, 10582.0, 10888.564457421773, 10952.452377381258, 11292.887071129184, 20553.48616284575, 20709.68935465969, 20740.896295518727, 20814.167433302802, 20899.0, 7651.262363805428, 7763.029621297338, 7763.650635721406, 7981.40139489529, 8169.42814003026, 3671.265746850587, 4293.527711951683, 4359.258925982591, 4433.290673492225, 4511.1654343946475, 3407.778494397906, 3508.3158784037378, 3512.5609298837644, 3527.5590551181103, 3672.9632703673155, 715.1096884378982, 736.1681300130925, 741.5724729894931, 743.0822933676905, 747.7270796159363, 556.9053260945649, 607.1014897951057, 612.8284080457479, 645.2740666749906, 699.3076853914588, 8082.232187942136, 8434.114417986146, 8948.0, 9059.184673379428, 9445.622175113056, 546.2925511462665, 603.303184821523, 611.2298503885137, 621.7409745265783, 679.9558028728216, 3887.5140607424073, 4474.194644963938, 4595.184901963706, 4700.848292168441, 4780.880477988008, 105616.47191763992, 110260.448697756, 111062.0, 111446.44276778564, 112147.0, 85606.57196713977, 89822.0, 95449.04550954401, 98589.0, 98933.0, 87525.0, 88043.1195688035, 89285.55357223173, 89392.5530372357, 90263.54868225617, 8973.37186396972, 9027.819443611223, 9069.863952040856, 9078.409903356262, 9374.718758437259, 46272.0, 57196.0, 57399.0, 57652.0, 59223.111653326094, 73083.80748577551, 75261.0, 80097.5995120044, 81473.0, 86953.0, 11810.82283765727, 11882.049436044923, 11905.940470297593, 11931.880681193425, 12014.459349329476, 12652.430695258055, 12661.303628300164, 12777.616671499869, 12823.525294591242, 13118.212907225598, 11795.174337796616, 11934.462949167482, 11948.163628545919, 11960.0, 12342.074344424025, 11684.279658487047, 11761.88238117608, 11991.22057066288, 11999.1600587958, 12010.939945300217, 11527.250728702607, 12000.63998080059, 12214.694632634255, 12247.326397048248, 12353.505859765508, 10127.544260508443, 10184.847227291448, 10212.897871021194, 10555.208359372924, 10623.681289561326, 10174.440405777754, 10453.26827122094, 10456.0, 10476.895231047592, 10478.580856765644], "value": 7299.329362280234, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1504.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 121.0, 117.0, 124.0, 116.0, 116.0, 147.0, 175.0, 160.0, 117.0], "value": 129.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 132.0, 113.0, 125.0, 117.0, 113.0, 123.0, 111.0, 133.0, 110.0], "value": 121.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 198.0, 197.0, 195.0, 194.0, 195.0, 196.0, 196.0, 194.0], "value": 196.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 94.0, 59.0, 58.0, 60.0, 60.0, 76.0, 60.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 88.0, 74.0, 73.0, 95.0, 116.0, 94.0, 70.0, 87.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 89.0, 132.0, 121.0, 88.0, 89.0, 102.0, 91.0, 88.0], "value": 98.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 98.0, 96.0, 96.0, 96.0, 96.0, 153.0, 142.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 86.0, 86.0, 88.0, 92.0, 96.0, 93.0, 88.0, 87.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 61.0, 59.0, 61.0, 62.0, 59.0, 59.0, 58.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 72.0, 68.0, 80.0, 76.0, 77.0, 67.0, 70.0, 70.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 82.0, 84.0, 82.0, 85.0, 86.0, 88.0, 83.0, 82.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 172.0, 143.0, 151.0, 178.0, 134.0, 153.0, 146.0, 202.0, 173.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 231.0, 166.0, 163.0, 163.0, 166.0, 157.0, 156.0, 163.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 69.0, 66.0, 67.0, 64.0, 69.0, 67.0], "value": 67.2, "unit": "score"}]}]} 10:52:03 INFO - Return code: 0 10:52:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:52:03 ERROR - # TBPL SUCCESS # 10:52:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:52:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:52:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:52:03 INFO - 2017-01-31 10:52:03.153 screenresolution[1661:10307] starting screenresolution argv=screenresolution get 10:52:03 INFO - 2017-01-31 10:52:03.156 screenresolution[1661:10307] Display 0: 1600x1200x32@60 10:52:03 INFO - 2017-01-31 10:52:03.162 screenresolution[1662:10310] starting screenresolution argv=screenresolution list 10:52:03 INFO - Available Modes on Display 0 10:52:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:52:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:52:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:52:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:52:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:52:03 INFO - Intel Iris: 10:52:03 INFO - Chipset Model: Intel Iris 10:52:03 INFO - Type: GPU 10:52:03 INFO - Bus: Built-In 10:52:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:52:03 INFO - Vendor: Intel (0x8086) 10:52:03 INFO - Device ID: 0x0a2e 10:52:03 INFO - Revision ID: 0x0009 10:52:03 INFO - Displays: 10:52:03 INFO - Display: 10:52:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:52:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:52:03 INFO - Display Serial Number: 1600x1200 60 10:52:03 INFO - Main Display: Yes 10:52:03 INFO - Mirror: Off 10:52:03 INFO - Online: Yes 10:52:03 INFO - Rotation: Supported 10:52:03 INFO - Return code: 0 10:52:03 INFO - Running post-action listener: _resource_record_post_action 10:52:03 INFO - [mozharness: 2017-01-31 18:52:03.400288Z] Finished run-tests step (success) 10:52:03 INFO - Running post-run listener: _resource_record_post_run 10:52:03 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39217152; Write bytes: 502173184; Read time: 250; Write time: 1605 10:52:03 INFO - TinderboxPrint: CPU usage
54.2% 10:52:03 INFO - TinderboxPrint: I/O read bytes / time
39,217,152 / 250 10:52:03 INFO - TinderboxPrint: I/O write bytes / time
502,173,184 / 1,605 10:52:03 INFO - TinderboxPrint: CPU idle
2,190.9 (45.8%) 10:52:03 INFO - TinderboxPrint: CPU system
969.1 (20.3%) 10:52:03 INFO - TinderboxPrint: CPU user
1,625.6 (34.0%) 10:52:03 INFO - TinderboxPrint: Swap in / out
230,907,904 / 0 10:52:03 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 176910848; Write bytes: 176203776; Read time: 16483; Write time: 387 10:52:03 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 38287360; Write bytes: 308237824; Read time: 238; Write time: 1163 10:52:03 INFO - Running post-run listener: _upload_blobber_files 10:52:03 INFO - Blob upload gear active. 10:52:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:52:03 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 10:52:03 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'] 10:52:03 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 10:52:04 INFO - (blobuploader) - INFO - Open directory for files ... 10:52:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:52:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:52:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:05 INFO - (blobuploader) - INFO - Done attempting. 10:52:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:52:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:52:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:06 INFO - (blobuploader) - INFO - Done attempting. 10:52:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:52:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:52:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:08 INFO - (blobuploader) - INFO - Done attempting. 10:52:08 INFO - (blobuploader) - INFO - Iteration through files over. 10:52:08 INFO - Return code: 0 10:52:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:52:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:52:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00a4b933305e2d5c9d5368b9f1471fee1c833679a9fd37e9b85618dfc07f6a394053a69f37a1fa5e6c056ce376d7aef65479d84e107cec57eb6e1e945c9980e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b916b2b25fd70cac53f17c0623c32153325e5b8743f835037af4d6314f31bceaf9d74a659ee0cff138f2b0b58e1c9b1563275f95b2d530d05d0c9b77412040f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef37aa8f04eb845548f0478d3e11ff4c5b675abeb13b7078b31e461a4bb9ba74e6fccf6fa0b788b8f50f2589ef9f938e4a9e34d1a1837ec3a3a42259ac06c44c"} 10:52:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:52:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:52:08 INFO - Contents: 10:52:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00a4b933305e2d5c9d5368b9f1471fee1c833679a9fd37e9b85618dfc07f6a394053a69f37a1fa5e6c056ce376d7aef65479d84e107cec57eb6e1e945c9980e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b916b2b25fd70cac53f17c0623c32153325e5b8743f835037af4d6314f31bceaf9d74a659ee0cff138f2b0b58e1c9b1563275f95b2d530d05d0c9b77412040f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef37aa8f04eb845548f0478d3e11ff4c5b675abeb13b7078b31e461a4bb9ba74e6fccf6fa0b788b8f50f2589ef9f938e4a9e34d1a1837ec3a3a42259ac06c44c"} 10:52:08 INFO - Running post-run listener: copy_logs_to_upload_dir 10:52:08 INFO - Copying logs to upload dir... 10:52:08 INFO - mkdir: /builds/slave/test/build/upload/logs 10:52:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.564223 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-01-31 10:52:08.823256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 10:52:08.827299) ========= 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.Rpocs4wEHl/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.BDbUVY81yL/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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/00a4b933305e2d5c9d5368b9f1471fee1c833679a9fd37e9b85618dfc07f6a394053a69f37a1fa5e6c056ce376d7aef65479d84e107cec57eb6e1e945c9980e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b916b2b25fd70cac53f17c0623c32153325e5b8743f835037af4d6314f31bceaf9d74a659ee0cff138f2b0b58e1c9b1563275f95b2d530d05d0c9b77412040f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef37aa8f04eb845548f0478d3e11ff4c5b675abeb13b7078b31e461a4bb9ba74e6fccf6fa0b788b8f50f2589ef9f938e4a9e34d1a1837ec3a3a42259ac06c44c"} build_url:https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010955 build_url: 'https://queue.taskcluster.net/v1/task/AdgtZ_xXS16Pe23huRHo9Q/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/00a4b933305e2d5c9d5368b9f1471fee1c833679a9fd37e9b85618dfc07f6a394053a69f37a1fa5e6c056ce376d7aef65479d84e107cec57eb6e1e945c9980e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b916b2b25fd70cac53f17c0623c32153325e5b8743f835037af4d6314f31bceaf9d74a659ee0cff138f2b0b58e1c9b1563275f95b2d530d05d0c9b77412040f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef37aa8f04eb845548f0478d3e11ff4c5b675abeb13b7078b31e461a4bb9ba74e6fccf6fa0b788b8f50f2589ef9f938e4a9e34d1a1837ec3a3a42259ac06c44c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 10:52:08.862680) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 10:52:08.863333) ========= 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.Rpocs4wEHl/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.BDbUVY81yL/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005139 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 10:52:08.912751) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 10:52:08.913157) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 10:52:08.913492) ========= ========= Total master_lag: 1.67 =========