builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0273 starttime: 1481645311.13 results: success (0) buildid: 20161213062001 builduid: fbecb809e826445c8cb9e4510e0f4898 revision: 71207be31a156103a0829f75b0bdef6540014c79 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.131579) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.132091) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.132401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.160033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.160455) ========= 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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-13 08:08:31-- 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% 17.9M=0.001s 2016-12-13 08:08:31 (17.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100050 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.284536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.284941) ========= 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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.024849 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.333780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:31.334145) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 71207be31a156103a0829f75b0bdef6540014c79 --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-aurora --rev 71207be31a156103a0829f75b0bdef6540014c79 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5wwI2UeBpV/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.9uduzQn1Z9/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-13 08:08:31,431 truncating revision to first 12 chars 2016-12-13 08:08:31,431 Setting DEBUG logging. 2016-12-13 08:08:31,431 attempt 1/10 2016-12-13 08:08:31,431 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/71207be31a15?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-13 08:08:32,090 unpacking tar archive at: mozilla-aurora-71207be31a15/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942463 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:32.299793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:32.300127) ========= script_repo_revision: 71207be31a156103a0829f75b0bdef6540014c79 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:32.300540) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:32.300808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 08:08:32.320912) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-13 08:08:32.321440) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5wwI2UeBpV/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.9uduzQn1Z9/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:08:32 INFO - MultiFileLogger online at 20161213 08:08:32 in /builds/slave/test 08:08:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:08:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:08:32 INFO - {'append_to_log': False, 08:08:32 INFO - 'base_work_dir': '/builds/slave/test', 08:08:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:08:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:08:32 INFO - 'branch': 'Mozilla-Aurora', 08:08:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:32 INFO - 'config_files': ('talos/mac_config.py',), 08:08:32 INFO - 'default_actions': ('clobber', 08:08:32 INFO - 'read-buildbot-config', 08:08:32 INFO - 'download-and-extract', 08:08:32 INFO - 'populate-webroot', 08:08:32 INFO - 'create-virtualenv', 08:08:32 INFO - 'install', 08:08:32 INFO - 'run-tests'), 08:08:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:32 INFO - 'download_minidump_stackwalk': True, 08:08:32 INFO - 'download_symbols': 'ondemand', 08:08:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:32 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:32 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:32 INFO - 'installer_path': 'installer.exe', 08:08:32 INFO - 'log_level': 'info', 08:08:32 INFO - 'log_name': 'talos', 08:08:32 INFO - 'log_to_console': True, 08:08:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:08:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:08:32 INFO - 'opt_config_files': (), 08:08:32 INFO - 'pip_index': False, 08:08:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:32 INFO - 'cmd': ('bash', 08:08:32 INFO - '-c', 08:08:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:32 INFO - 'enabled': True, 08:08:32 INFO - 'halt_on_failure': False, 08:08:32 INFO - 'name': 'check_screen_resolution'},), 08:08:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:32 INFO - 'cmd': ('bash', 08:08:32 INFO - '-c', 08:08:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:32 INFO - 'enabled': True, 08:08:32 INFO - 'halt_on_failure': False, 08:08:32 INFO - 'name': 'check_screen_resolution'},), 08:08:32 INFO - 'run_cmd_checks_enabled': True, 08:08:32 INFO - 'sps_profile': False, 08:08:32 INFO - 'sps_profile_interval': 0, 08:08:32 INFO - 'suite': 'dromaeojs', 08:08:32 INFO - 'system_bits': '32', 08:08:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:32 INFO - 'title': 't-yosemite-r7-0273', 08:08:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:32 INFO - 'use_talos_json': True, 08:08:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:08:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:32 INFO - 'work_dir': 'build'} 08:08:32 INFO - [mozharness: 2016-12-13 16:08:32.530062Z] Running clobber step. 08:08:32 INFO - Running pre-action listener: _resource_record_pre_action 08:08:32 INFO - Running main action method: clobber 08:08:32 INFO - rmtree: /builds/slave/test/build 08:08:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:08:34 INFO - Running post-action listener: _resource_record_post_action 08:08:34 INFO - [mozharness: 2016-12-13 16:08:34.905979Z] Finished clobber step (success) 08:08:34 INFO - [mozharness: 2016-12-13 16:08:34.906104Z] Running read-buildbot-config step. 08:08:34 INFO - Running pre-action listener: _resource_record_pre_action 08:08:34 INFO - Running main action method: read_buildbot_config 08:08:34 INFO - Using buildbot properties: 08:08:34 INFO - { 08:08:34 INFO - "project": "", 08:08:34 INFO - "product": "firefox", 08:08:34 INFO - "script_repo_revision": "production", 08:08:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:08:34 INFO - "repository": "", 08:08:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:08:34 INFO - "buildid": "20161213062001", 08:08:34 INFO - "pgo_build": "False", 08:08:34 INFO - "basedir": "/builds/slave/test", 08:08:34 INFO - "buildnumber": 10, 08:08:34 INFO - "slavename": "t-yosemite-r7-0273", 08:08:34 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:08:34 INFO - "platform": "yosemite_r7", 08:08:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:08:34 INFO - "branch": "mozilla-aurora", 08:08:34 INFO - "repo_path": "releases/mozilla-aurora", 08:08:34 INFO - "revision": "71207be31a156103a0829f75b0bdef6540014c79", 08:08:34 INFO - "stage_platform": "macosx64", 08:08:34 INFO - "builduid": "fbecb809e826445c8cb9e4510e0f4898", 08:08:34 INFO - "slavebuilddir": "test" 08:08:34 INFO - } 08:08:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:08:34 INFO - Running post-action listener: _resource_record_post_action 08:08:34 INFO - [mozharness: 2016-12-13 16:08:34.909380Z] Finished read-buildbot-config step (success) 08:08:34 INFO - [mozharness: 2016-12-13 16:08:34.909503Z] Running download-and-extract step. 08:08:34 INFO - Running pre-action listener: _resource_record_pre_action 08:08:34 INFO - Running main action method: download_and_extract 08:08:34 INFO - mkdir: /builds/slave/test/build/tests 08:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:34 INFO - trying https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:08:34 INFO - Downloading https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:08:36 INFO - Downloaded 1199 bytes. 08:08:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:08:36 INFO - Using the following test package requirements: 08:08:36 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:08:36 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:08:36 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:08:36 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'jsshell-mac.zip'], 08:08:36 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:08:36 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:08:36 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:08:36 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:08:36 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:08:36 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:36 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:08:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:08:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:08:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:08:36 INFO - Fetch https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:08:36 INFO - Http code: 200 08:08:36 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 08:08:36 INFO - x-amz-cf-id: TT9AVGu2xnAtcARUyMnH_wqdYTRfE2ttFGbUfvNYE-voEd8bEysMTg== 08:08:36 INFO - x-amz-version-id: rhzKCTTQkebvly0n72xg9ou0PbharjuY 08:08:37 INFO - Expected file size: 16280968 08:08:37 INFO - Obtained file size: 16280968 08:08:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:08:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:08:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:08:43 INFO - Fetch https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:08:44 INFO - Http code: 200 08:08:44 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 08:08:44 INFO - x-amz-cf-id: 4qSP1kk3l4qI5-3-NBcqmHCFVTHgwh8oevF1oD1pHbwfaHgDm135-w== 08:08:44 INFO - x-amz-version-id: rhzKCTTQkebvly0n72xg9ou0PbharjuY 08:08:44 INFO - Expected file size: 16280968 08:08:44 INFO - Obtained file size: 16280968 08:08:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:08:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:08:51 INFO - Fetch https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:08:51 INFO - Http code: 200 08:08:51 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 08:08:51 INFO - x-amz-cf-id: 5156VLsM1akm1MIS2l-wJ3q1eLcskY-lagTZPH1djC1nESKhp0X0MQ== 08:08:51 INFO - x-amz-version-id: hjEte1e5JwCJw6NJfhK0YGno6UDhXhZg 08:08:52 INFO - Expected file size: 14409038 08:08:52 INFO - Obtained file size: 14409038 08:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:53 INFO - trying https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:08:53 INFO - Downloading https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:08:56 INFO - Downloaded 109760314 bytes. 08:08:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:08:56 INFO - mkdir: /builds/slave/test/properties 08:08:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:08:56 INFO - Writing to file /builds/slave/test/properties/build_url 08:08:56 INFO - Contents: 08:08:56 INFO - build_url:https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:08:57 INFO - Running post-action listener: _resource_record_post_action 08:08:57 INFO - Running post-action listener: set_extra_try_arguments 08:08:57 INFO - [mozharness: 2016-12-13 16:08:57.328007Z] Finished download-and-extract step (success) 08:08:57 INFO - [mozharness: 2016-12-13 16:08:57.328173Z] Running populate-webroot step. 08:08:57 INFO - Running pre-action listener: _resource_record_pre_action 08:08:57 INFO - Running main action method: populate_webroot 08:08:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:57 INFO - '--tppagecycles', 08:08:57 INFO - '7'], 08:08:57 INFO - 'tests': ['tsvgm']}}, 08:08:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:08:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:08:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:08:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['damp', 'tps']}, 08:08:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'tests': ['damp', 'tps']}, 08:08:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['dromaeo_dom']}, 08:08:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['basic_compositor_video']}, 08:08:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:08:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other-e10s': {'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tsvgx', 08:08:57 INFO - 'tsvgr_opacity', 08:08:57 INFO - 'tart', 08:08:57 INFO - 'tscrollx', 08:08:57 INFO - 'cart']}, 08:08:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:08:57 INFO - 'tsvgr_opacity', 08:08:57 INFO - 'tart', 08:08:57 INFO - 'tscrollx', 08:08:57 INFO - 'cart']}, 08:08:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tp5o']}, 08:08:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'tests': ['tp5o']}, 08:08:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s', 08:08:57 INFO - '--xperf_path', 08:08:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:57 INFO - 'tests': ['tp5n']}, 08:08:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--xperf_path', 08:08:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:57 INFO - 'tests': ['tp5n']}}, 08:08:57 INFO - 'talos.zip': {'path': '', 08:08:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:57 INFO - Running post-action listener: _resource_record_post_action 08:08:57 INFO - [mozharness: 2016-12-13 16:08:57.347165Z] Finished populate-webroot step (success) 08:08:57 INFO - [mozharness: 2016-12-13 16:08:57.347250Z] Running create-virtualenv step. 08:08:57 INFO - Running pre-action listener: _resource_record_pre_action 08:08:57 INFO - Running main action method: create_virtualenv 08:08:57 INFO - Creating virtualenv /builds/slave/test/build/venv 08:08:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:08:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:08:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:57 INFO - Using real prefix '/tools/python27' 08:08:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:08:58 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:01 INFO - Installing pip.................done. 08:09:01 INFO - Return code: 0 08:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:09:01 INFO - Reading from file tmpfile_stdout 08:09:01 INFO - Output received: 08:09:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:09:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:01 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:01 INFO - 'HOME': '/Users/cltbld', 08:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:01 INFO - 'LOGNAME': 'cltbld', 08:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:01 INFO - 'MOZ_NO_REMOTE': '1', 08:09:01 INFO - 'NO_EM_RESTART': '1', 08:09:01 INFO - 'PAGER': '/bin/cat', 08:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:01 INFO - 'PWD': '/builds/slave/test', 08:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:01 INFO - 'SHELL': '/bin/bash', 08:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:01 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:01 INFO - 'USER': 'cltbld', 08:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:01 INFO - 'XPC_FLAGS': '0x0', 08:09:01 INFO - 'XPC_SERVICE_NAME': '0', 08:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:09:01 INFO - Cleaning up... 08:09:01 INFO - Return code: 0 08:09:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:01 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:01 INFO - 'HOME': '/Users/cltbld', 08:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:01 INFO - 'LOGNAME': 'cltbld', 08:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:01 INFO - 'MOZ_NO_REMOTE': '1', 08:09:01 INFO - 'NO_EM_RESTART': '1', 08:09:01 INFO - 'PAGER': '/bin/cat', 08:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:01 INFO - 'PWD': '/builds/slave/test', 08:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:01 INFO - 'SHELL': '/bin/bash', 08:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:01 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:01 INFO - 'USER': 'cltbld', 08:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:01 INFO - 'XPC_FLAGS': '0x0', 08:09:01 INFO - 'XPC_SERVICE_NAME': '0', 08:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:01 INFO - Downloading/unpacking psutil>=3.1.1 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:05 INFO - Installing collected packages: psutil 08:09:05 INFO - Running setup.py install for psutil 08:09:05 INFO - building 'psutil._psutil_osx' extension 08:09:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:09:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:09:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:09:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:09:06 INFO - building 'psutil._psutil_posix' extension 08:09:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:09:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:06 INFO - ^ 08:09:06 INFO - 1 warning generated. 08:09:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:09:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:06 INFO - Successfully installed psutil 08:09:06 INFO - Cleaning up... 08:09:06 INFO - Return code: 0 08:09:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:09: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 mozsystemmonitor==0.3 08:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:06 INFO - 'HOME': '/Users/cltbld', 08:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:06 INFO - 'LOGNAME': 'cltbld', 08:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:06 INFO - 'MOZ_NO_REMOTE': '1', 08:09:06 INFO - 'NO_EM_RESTART': '1', 08:09:06 INFO - 'PAGER': '/bin/cat', 08:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:06 INFO - 'PWD': '/builds/slave/test', 08:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:06 INFO - 'SHELL': '/bin/bash', 08:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:06 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:06 INFO - 'USER': 'cltbld', 08:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:06 INFO - 'XPC_FLAGS': '0x0', 08:09:06 INFO - 'XPC_SERVICE_NAME': '0', 08:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:09:09 INFO - Installing collected packages: mozsystemmonitor 08:09:09 INFO - Running setup.py install for mozsystemmonitor 08:09:09 INFO - Successfully installed mozsystemmonitor 08:09:09 INFO - Cleaning up... 08:09:09 INFO - Return code: 0 08:09:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:09 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:09:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:09 INFO - 'HOME': '/Users/cltbld', 08:09:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:09 INFO - 'LOGNAME': 'cltbld', 08:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:09 INFO - 'MOZ_NO_REMOTE': '1', 08:09:09 INFO - 'NO_EM_RESTART': '1', 08:09:09 INFO - 'PAGER': '/bin/cat', 08:09:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:09 INFO - 'PWD': '/builds/slave/test', 08:09:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:09 INFO - 'SHELL': '/bin/bash', 08:09:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:09 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:09 INFO - 'USER': 'cltbld', 08:09:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:09 INFO - 'XPC_FLAGS': '0x0', 08:09:09 INFO - 'XPC_SERVICE_NAME': '0', 08:09:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:09 INFO - Downloading/unpacking jsonschema==2.5.1 08:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:12 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:13 INFO - warning: no files found matching '*.txt' 08:09:13 INFO - no previously-included directories found matching 'build' 08:09:13 INFO - no previously-included directories found matching 'dist' 08:09:13 INFO - no previously-included directories found matching '.git*' 08:09:13 INFO - Installing collected packages: jsonschema, functools32 08:09:13 INFO - Running setup.py install for functools32 08:09:13 INFO - warning: no files found matching '*.txt' 08:09:13 INFO - no previously-included directories found matching 'build' 08:09:13 INFO - no previously-included directories found matching 'dist' 08:09:13 INFO - no previously-included directories found matching '.git*' 08:09:13 INFO - Successfully installed jsonschema functools32 08:09:13 INFO - Cleaning up... 08:09:13 INFO - Return code: 0 08:09:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:13 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:13 INFO - 'HOME': '/Users/cltbld', 08:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:13 INFO - 'LOGNAME': 'cltbld', 08:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:13 INFO - 'MOZ_NO_REMOTE': '1', 08:09:13 INFO - 'NO_EM_RESTART': '1', 08:09:13 INFO - 'PAGER': '/bin/cat', 08:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:13 INFO - 'PWD': '/builds/slave/test', 08:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:13 INFO - 'SHELL': '/bin/bash', 08:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:13 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:13 INFO - 'USER': 'cltbld', 08:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:13 INFO - 'XPC_FLAGS': '0x0', 08:09:13 INFO - 'XPC_SERVICE_NAME': '0', 08:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:09:13 INFO - Cleaning up... 08:09:13 INFO - Return code: 0 08:09:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:13 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:13 INFO - 'HOME': '/Users/cltbld', 08:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:13 INFO - 'LOGNAME': 'cltbld', 08:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:13 INFO - 'MOZ_NO_REMOTE': '1', 08:09:13 INFO - 'NO_EM_RESTART': '1', 08:09:13 INFO - 'PAGER': '/bin/cat', 08:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:13 INFO - 'PWD': '/builds/slave/test', 08:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:13 INFO - 'SHELL': '/bin/bash', 08:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:13 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:13 INFO - 'USER': 'cltbld', 08:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:13 INFO - 'XPC_FLAGS': '0x0', 08:09:13 INFO - 'XPC_SERVICE_NAME': '0', 08:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:13 INFO - Downloading/unpacking blobuploader==1.2.4 08:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:16 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:17 INFO - Downloading docopt-0.6.1.tar.gz 08:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:17 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:17 INFO - Running setup.py install for blobuploader 08:09:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:17 INFO - Running setup.py install for requests 08:09:18 INFO - Running setup.py install for docopt 08:09:18 INFO - Successfully installed blobuploader requests docopt 08:09:18 INFO - Cleaning up... 08:09:18 INFO - Return code: 0 08:09:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:18 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:18 INFO - 'HOME': '/Users/cltbld', 08:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:18 INFO - 'LOGNAME': 'cltbld', 08:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:18 INFO - 'MOZ_NO_REMOTE': '1', 08:09:18 INFO - 'NO_EM_RESTART': '1', 08:09:18 INFO - 'PAGER': '/bin/cat', 08:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:18 INFO - 'PWD': '/builds/slave/test', 08:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:18 INFO - 'SHELL': '/bin/bash', 08:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:18 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:18 INFO - 'USER': 'cltbld', 08:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:18 INFO - 'XPC_FLAGS': '0x0', 08:09:18 INFO - 'XPC_SERVICE_NAME': '0', 08:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-KMIBVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-SX7062-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-eXtVN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-tfJupf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-GArgjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-KqnEpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-CuwHSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ut_7Ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-aHOdFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:19 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Bv_NgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-GEp2_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-x7SKhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-5nxA4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-2tZHax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-u1u03e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-WDUibn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-yuFvD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:20 INFO - Running setup.py install for manifestparser 08:09:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:20 INFO - Running setup.py install for mozcrash 08:09:21 INFO - Running setup.py install for mozdebug 08:09:21 INFO - Running setup.py install for mozdevice 08:09:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Running setup.py install for mozfile 08:09:21 INFO - Running setup.py install for mozhttpd 08:09:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Running setup.py install for mozinfo 08:09:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Running setup.py install for mozInstall 08:09:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Running setup.py install for mozleak 08:09:22 INFO - Running setup.py install for mozlog 08:09:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:22 INFO - Running setup.py install for moznetwork 08:09:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:22 INFO - Running setup.py install for mozprocess 08:09:22 INFO - Running setup.py install for mozprofile 08:09:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:22 INFO - Running setup.py install for mozrunner 08:09:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:22 INFO - Running setup.py install for mozscreenshot 08:09:23 INFO - Running setup.py install for moztest 08:09:23 INFO - Running setup.py install for mozversion 08:09:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:23 INFO - Cleaning up... 08:09:23 INFO - Return code: 0 08:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:24 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:09:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:24 INFO - 'HOME': '/Users/cltbld', 08:09:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:24 INFO - 'LOGNAME': 'cltbld', 08:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:24 INFO - 'MOZ_NO_REMOTE': '1', 08:09:24 INFO - 'NO_EM_RESTART': '1', 08:09:24 INFO - 'PAGER': '/bin/cat', 08:09:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:24 INFO - 'PWD': '/builds/slave/test', 08:09:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:24 INFO - 'SHELL': '/bin/bash', 08:09:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:24 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:24 INFO - 'USER': 'cltbld', 08:09:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:24 INFO - 'XPC_FLAGS': '0x0', 08:09:24 INFO - 'XPC_SERVICE_NAME': '0', 08:09:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:24 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-vkyMnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:24 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-WToy5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:24 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)) 08:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:24 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-YxaXDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:24 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-GZGx3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:24 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-vep29t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:24 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-M5wsAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-1BVpfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-WbBy9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-nq_p0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-DwuD6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-zXCsxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-fHF31o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-0o30Xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-0s34kO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:25 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)) 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:25 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-fT2_o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:26 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-0mPBed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:26 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-umlmc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:09:26 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)) 08:09:26 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)) 08:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:09:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:29 INFO - Downloading blessings-1.6.tar.gz 08:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:09:29 INFO - Installing collected packages: blessings 08:09:29 INFO - Running setup.py install for blessings 08:09:29 INFO - Successfully installed blessings 08:09:29 INFO - Cleaning up... 08:09:29 INFO - Return code: 0 08:09:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:29 INFO - Reading from file tmpfile_stdout 08:09:29 INFO - Current package versions: 08:09:29 INFO - blessings == 1.6 08:09:29 INFO - blobuploader == 1.2.4 08:09:29 INFO - docopt == 0.6.1 08:09:29 INFO - functools32 == 3.2.3-2 08:09:29 INFO - jsonschema == 2.5.1 08:09:29 INFO - manifestparser == 1.1 08:09:29 INFO - mozInstall == 1.12 08:09:29 INFO - mozcrash == 1.0 08:09:29 INFO - mozdebug == 0.1 08:09:29 INFO - mozdevice == 0.48 08:09:29 INFO - mozfile == 1.2 08:09:29 INFO - mozhttpd == 0.7 08:09:29 INFO - mozinfo == 0.9 08:09:29 INFO - mozleak == 0.1 08:09:29 INFO - mozlog == 3.4 08:09:29 INFO - moznetwork == 0.27 08:09:29 INFO - mozprocess == 0.23 08:09:29 INFO - mozprofile == 0.28 08:09:29 INFO - mozrunner == 6.13 08:09:29 INFO - mozscreenshot == 0.1 08:09:29 INFO - mozsystemmonitor == 0.3 08:09:29 INFO - moztest == 0.7 08:09:29 INFO - mozversion == 1.4 08:09:29 INFO - psutil == 3.1.1 08:09:29 INFO - requests == 1.2.3 08:09:29 INFO - wsgiref == 0.1.2 08:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:29 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 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:29 INFO - 'HOME': '/Users/cltbld', 08:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:29 INFO - 'LOGNAME': 'cltbld', 08:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:29 INFO - 'MOZ_NO_REMOTE': '1', 08:09:29 INFO - 'NO_EM_RESTART': '1', 08:09:29 INFO - 'PAGER': '/bin/cat', 08:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:29 INFO - 'PWD': '/builds/slave/test', 08:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:29 INFO - 'SHELL': '/bin/bash', 08:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:29 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:29 INFO - 'USER': 'cltbld', 08:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:29 INFO - 'XPC_FLAGS': '0x0', 08:09:29 INFO - 'XPC_SERVICE_NAME': '0', 08:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:09:30 INFO - Cleaning up... 08:09:30 INFO - Return code: 0 08:09:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b1547060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, '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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:09:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:09:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:30 INFO - 'HOME': '/Users/cltbld', 08:09:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:30 INFO - 'LOGNAME': 'cltbld', 08:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:30 INFO - 'MOZ_NO_REMOTE': '1', 08:09:30 INFO - 'NO_EM_RESTART': '1', 08:09:30 INFO - 'PAGER': '/bin/cat', 08:09:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:30 INFO - 'PWD': '/builds/slave/test', 08:09:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:30 INFO - 'SHELL': '/bin/bash', 08:09:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:09:30 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:09:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:30 INFO - 'USER': 'cltbld', 08:09:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:30 INFO - 'XPC_FLAGS': '0x0', 08:09:30 INFO - 'XPC_SERVICE_NAME': '0', 08:09:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:09:30 INFO - Cleaning up... 08:09:30 INFO - Return code: 0 08:09:30 INFO - Running post-action listener: _resource_record_post_action 08:09:30 INFO - Running post-action listener: _start_resource_monitoring 08:09:30 INFO - Starting resource monitoring. 08:09:30 INFO - [mozharness: 2016-12-13 16:09:30.396810Z] Finished create-virtualenv step (success) 08:09:30 INFO - [mozharness: 2016-12-13 16:09:30.397883Z] Running install step. 08:09:30 INFO - Running pre-action listener: _resource_record_pre_action 08:09:30 INFO - Running main action method: install 08:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:30 INFO - Reading from file tmpfile_stdout 08:09:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:09:30 INFO - Reading from file tmpfile_stdout 08:09:30 INFO - Output received: 08:09:30 INFO - Usage: mozinstall [options] installer 08:09:30 INFO - Options: 08:09:30 INFO - -h, --help show this help message and exit 08:09:30 INFO - -d DEST, --destination=DEST 08:09:30 INFO - Directory to install application into. [default: 08:09:30 INFO - "/builds/slave/test"] 08:09:30 INFO - --app=APP Application being installed. [default: firefox] 08:09:30 INFO - mkdir: /builds/slave/test/build/application 08:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:10:01 INFO - Reading from file tmpfile_stdout 08:10:01 INFO - Output received: 08:10:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:10:01 INFO - Running post-action listener: _resource_record_post_action 08:10:01 INFO - [mozharness: 2016-12-13 16:10:01.479348Z] Finished install step (success) 08:10:01 INFO - [mozharness: 2016-12-13 16:10:01.479530Z] Running run-tests step. 08:10:01 INFO - Running pre-action listener: _resource_record_pre_action 08:10:01 INFO - Running main action method: run_tests 08:10:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:01 INFO - 2016-12-13 08:10:01.494 screenresolution[1627:7471] starting screenresolution argv=screenresolution get 08:10:01 INFO - 2016-12-13 08:10:01.496 screenresolution[1627:7471] Display 0: 1600x1200x32@60 08:10:01 INFO - 2016-12-13 08:10:01.502 screenresolution[1628:7473] starting screenresolution argv=screenresolution list 08:10:01 INFO - Available Modes on Display 0 08:10:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:01 INFO - Intel Iris: 08:10:01 INFO - Chipset Model: Intel Iris 08:10:01 INFO - Type: GPU 08:10:01 INFO - Bus: Built-In 08:10:01 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:01 INFO - Vendor: Intel (0x8086) 08:10:01 INFO - Device ID: 0x0a2e 08:10:01 INFO - Revision ID: 0x0009 08:10:01 INFO - Displays: 08:10:01 INFO - Display: 08:10:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:01 INFO - Display Serial Number: 1600x1200 60 08:10:01 INFO - Main Display: Yes 08:10:01 INFO - Mirror: Off 08:10:01 INFO - Online: Yes 08:10:01 INFO - Rotation: Supported 08:10:01 INFO - Return code: 0 08:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:01 INFO - Python 2.7.3 08:10:01 INFO - Return code: 0 08:10:01 INFO - grabbing minidump binary from tooltool 08:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:01 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 0x103d20d30>, '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 0x103b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d18b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:01 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 08:10:01 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 08:10:01 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 08:10:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:01 INFO - Return code: 0 08:10:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0273 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5wwI2UeBpV/Render', 08:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:01 INFO - 'HOME': '/Users/cltbld', 08:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:01 INFO - 'LOGNAME': 'cltbld', 08:10:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:01 INFO - 'MOZ_NO_REMOTE': '1', 08:10:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:01 INFO - 'NO_EM_RESTART': '1', 08:10:01 INFO - 'PAGER': '/bin/cat', 08:10:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:01 INFO - 'PWD': '/builds/slave/test', 08:10:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:01 INFO - 'SHELL': '/bin/bash', 08:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9uduzQn1Z9/Listeners', 08:10:01 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 08:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:01 INFO - 'USER': 'cltbld', 08:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:01 INFO - 'XPC_FLAGS': '0x0', 08:10:01 INFO - 'XPC_SERVICE_NAME': '0', 08:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:10:02 INFO - mozversion application_buildid: 20161213062001 08:10:02 INFO - mozversion application_changeset: 71207be31a156103a0829f75b0bdef6540014c79 08:10:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:10:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:02 INFO - mozversion application_name: Firefox 08:10:02 INFO - mozversion application_remotingname: firefox-dev 08:10:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:10:02 INFO - mozversion application_vendor: Mozilla 08:10:02 INFO - mozversion application_version: 52.0a2 08:10:02 INFO - mozversion platform_buildid: 20161213062001 08:10:02 INFO - mozversion platform_changeset: 71207be31a156103a0829f75b0bdef6540014c79 08:10:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:10:02 INFO - mozversion platform_version: 52.0a2 08:10:02 INFO - using testdate: 1481645402 08:10:02 INFO - actual date: 1481645402 08:10:02 INFO - starting webserver on 'localhost:49236' 08:10:02 INFO - SUITE-START | Running 2 tests 08:10:02 INFO - TEST-START | dromaeo_css 08:10:02 INFO - operating with platform_type : mac_ 08:10:02 INFO - Initialising browser for dromaeo_css test... 08:10:02 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpjErVU7/profile http://localhost:49236/getInfo.html) 08:10:04 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 08:10:04 INFO - PROCESS | 1638 | colorDepth:24 08:10:04 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 08:10:04 INFO - PROCESS | 1638 | __metrics 08:10:05 INFO - PROCESS | 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:10:05 INFO - PROCESS | 1638 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:10:05 INFO - TEST-INFO | 1638: exit 0 08:10:05 INFO - Browser initialized. 08:10:05 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:05 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpjErVU7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:18 INFO - PROCESS | 1641 | RSS: Main: 222314496 08:10:18 INFO - PROCESS | 1641 | 08:11:09 INFO - PROCESS | 1641 | 1481645469872 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:13:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 08:13:25 INFO - PROCESS | 1641 | RSS: Main: 372412416 08:13:25 INFO - PROCESS | 1641 | 08:16:27 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 08:16:28 INFO - PROCESS | 1641 | RSS: Main: 349519872 08:16:28 INFO - PROCESS | 1641 | 08:19:30 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 08:19:31 INFO - PROCESS | 1641 | RSS: Main: 318271488 08:19:31 INFO - PROCESS | 1641 | 08:22:33 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 08:22:34 INFO - PROCESS | 1641 | RSS: Main: 320303104 08:22:34 INFO - PROCESS | 1641 | 08:25:37 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 08:25:38 INFO - PROCESS | 1641 | RSS: Main: 307929088 08:25:38 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 08:28:41 INFO - PROCESS | 1641 | RSS: Main: 413175808 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | __start_tp_report 08:28:41 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 08:28:41 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 08:28:41 INFO - PROCESS | 1641 | |i|pagename|runs| 08:28:41 INFO - PROCESS | 1641 | |0;dojo.html;3174.9841250793743;3324.7007769300744;3335.199552107495;3359.378514974728;3381.070205693434;5731.65610063396;5805.593608447411;5805.983952808263;5842.737076831542;5854.824355269343;5660.405657405975;5741.052726300166;5757.309122905247;5787.21872547206;5792.449717276862;5193.87015324618;5213.2701421800975;5227.398849132342;5231.136862417716;5282;4127.669786417079;5203.86990325243;5206.817761378353;5296.523312901838;5305.336832895888;5226.163813789794;5258.316418865561;5260.763265653055;5272.472752724735;5273.789048438058;5253.763580638862;5264.736763161826;5288.788448462057;5303.098473259516;5306.628536002481;5197.22041693749;5225.2162175673575;5253.553447956928;5276.050310944029;5319.840404787863;5614.214010038598;5625.606207565472;5663.433656634304;5671.602987790856;5671.922334756383;5143.8456846294675;6202.627842329474;6205.7517699291975;6241.7191226394925;6242.157308763304;5983.730732117066;6280.403361680633;6294.3705629437145;6308.022256550242;6308.369163083701;5716.542676585863;5735.541156707453;5753.942460575425;5757.88484230313;5821.097729851837;5307.9734601326745;6245.469135123474;6250.6874656267;6260.843478913063;6282.842928926722;6121.816345509642;6137.232845894263;6149.169862068564;6172.197614310111;6173.84565385869;5089;5184.14461613837;6126.9452004266695;6236;6248.9062664060075;6151.200343955346;6174.135621013062;6200.968995155085;6249.468795152372;6265.624062556261;6204.441600255908;6206.503479721611;6247.9062814057825;6277.654728989949;6282.057691346335;4235.7670328131635;4738.64460165489;4940.654154209243;4957.652964292537;4991.251312303111;4380.145871555016;4440.911181776411;4442.044960333479;4474.664400170001;4476.798544065525;5875.9118613220835;5882.02946513829;5893.086571581412;5894.2926848778425;5927.911081333784;6263.749450021948;6285.465735412449;6293.842653933688;6293.842653933688;6299.370062993756;5610.13042978339;5725.59920805548;5734.971325143348;5848.64908105515;5895.498882595028;5422.213779002102;5422.7017514036315;5439.755211015514;5443.537299329601;5474.863128421821;6337.176167098248;6342.524310676626;6356.300806911236;6357.300696923242;6375.234971803413;5917.497012753792;6216.222972128484;6270.592411493239;6298.842528936813;6299.842503937438;6205.131281620667;6253.812385628438;6261.092141639528;6286.968565157145;6357.586756860789;6214.751409943552;6231.688415579293;6239.5008399327035;6249.406306400884;6261.186045814015;6194;6250.906236406367;6266.091416744638;6287.654179020198;6306.527010474325;6014.849628759316;6065.2418447694035;6078.361772014052;6092.8781424372155;6142.846428839315;6142.652254521386;6151.8311520812695;6176.969115154396;6186.1339412483185;6187.3503282154725;5922.5261979040715;6017.67792653482;6043.758249669964;6045.365236650265;6094.725737341919;6008.218931538872;6065.54508411862;6151.292601350902;6173.753049877954;6198.6280823150755;5928.466438020599;5936.851578710567;5982.730777115127;5990.251218597676;6009 08:28:41 INFO - PROCESS | 1641 | |1;ext.html;445.7058341494614;459.78390156626386;468.58296116456336;524.892397058603;532.0263917031831;30760.923367682135;31450.68549314506;38238.42642360367;39356.40965385522;39600.207995840065;40792.18415631693;41108.38337424934;41167.58832411675;41194.79402602983;41226.763197104134;9114.45313281202;9136.269098472107;9179.403338782991;9303.25573954082;9318.394304370227;8558.358123140759;8952.239059679903;9212.216961558275;9324.440533567973;9393.483358415284;9248.26013918885;9332.113449222314;9341.76645583859;9357;9370.437773733564;9229.584668689891;9276.304277179239;9294.117058879396;9296.674616388462;9309.627614895397;9235.538223088886;9318.72043838686;9320.533973301308;9321.673741419085;9324;32601.836990814892;38634;38983;38991.610083899366;39024.80487597572;3259.1852036990754;3326.7172290355347;3718.4794128821986;3822.2737679840743;3844.4041173618134;29588.852055739582;29629.962951296602;29653.851730741422;29999.550006749916;30340.393192136034;3109.113902537766;3176.682331766828;3178.3802158579088;3184.0447865640217;3201.743860491155;418003;483504;483798;487796;488926.33052521205;32470.025899223063;33465.83267083649;57290.85418291611;57638.13542796861;57932;34425.827870860485;34712.652873471445;34793.82603087019;34819;34822;14069.929650351683;14205.502807401692;14206.573802785933;14206.786898196535;14254;11907.880921190677;11988.520459181535;11993.340366279765;11996;12001.459934302977;7536.547807131589;7657.234276572298;7677.692892284358;7746.845063098706;7766.883496747553;3189.3780712761254;3234.5956755405573;3244.6755324467836;3277.4264503711756;3277.672232776751;899.7448559259796;930.5021813329836;933;991.9752006199902;1115.5649296774266;27807.165785026482;28604.570931435628;28750.71249287522;28846.573440444645;29239.415211695647;864.4424346296604;1062.309498825754;1068.9791249356845;1083.9458027098613;1182.526989204308;14418.42326306936;14664.340104695315;14787.852121478863;14914.254287285592;15180.508483048961;663406;665010;667549;669425;670586;53542;54052;54068;54110.18834717482;54191.45808541943;1208.4924331780674;1240.2000709542294;1321.2799024531462;1351.6147897849262;1366.6241783509415;28951.84192632271;29688;29763.851180743957;30201.697983019887;30549.23626909345;45509.544904551854;46093.76953115213;46146.53853461422;46258.537414625425;46344.536554634025;464357;466226;467770;467989;468388;23537.88231058834;26105.955761769317;26130.73869261359;26137.477250454504;26160.34599135037;45114.323285150094;45122.323165151895;45192.87017824449;45690.31464528101;45891.54108458872;36166;36354.27291454209;38185.045373865876;39035.80482097571;40469.59530404658;36838.44742328921;36964.63035369612;37003.81498092492;37175.442368363954;38435.6156438432;36506.634933650326;37342.62657373391;37383.81308093442;37514.62485375111;37577.684781033626;33105.50341744828;33281.167970800925;34138.65861341355;34223.657763422045;34252.828735856165;24517.877410612833;24715;25218.74781252164;25263.747362526876;25353.112641057476 08:28:41 INFO - PROCESS | 1641 | |2;jquery.html;335.71128829206884;355.3585777671302;376.4372263466118;381.68320294155853;405.52350987589574;14601.488947886826;14744.189069601167;15629.921850390761;19930.900345498252;20462.2838200663;19271.903640481814;19595.804041959575;20194.39416817498;20226.39320820374;20430;5625.40933202014;5806.82579522615;5818.1272809078555;5820.039693450597;5843.620164689303;4514.187446259672;5689.374168841424;5764.164196191549;5768.451997060272;5774;4977.42759582649;5570.498655121039;5704.030314846465;5709.8287051388525;5720.742566584493;5508.476694713996;5698.886022279531;5729.455701708331;5730.111832665943;5730.398308177623;4886.902261954777;4981;5052.242163675442;5059.06407314648;5061.342025536694;24287.392815179584;24871.378215544577;25333.873330633414;25338.746612534007;25678.614820777704;2312.2600767754325;2416.673749043874;2472.5178590174924;2625.868706564683;2789.288731373507;14576.854231457655;14723.705525889422;15155.393784248618;15568.299426525833;15651.843481565153;1734.7397890316427;1754.2544418622163;1783.482789990901;1788.9910550447294;1898.3830255166988;122321;122771.77228227693;125206.37396813049;128680.06979895216;131048.34475827655;11138.777224455627;11480.712982175512;11509.597164099383;11525.76948461026;11716.648500544998;14712.926435367755;14740.41038358475;14745.262736863115;14746.262686865828;14761.33573989173;7986.241307075818;8105.148959359182;8286.668533258722;8329.33365330772;8452.915470845213;6746.628935408501;7290.744823931243;8034.464835472373;8068.596570171468;8232.794180145544;4512.075024619945;4515.345274935116;4541.3188021797;4544.341070544819;4594.47163576193;1889.527618095476;1926.248762982438;1928.151613290148;1942.8834269943848;1949.9317523886596;701.8175274428584;891.1009119628103;935.0696057422907;945.4091193004373;945.8297506448768;20872.165113395597;21411.250606228707;21471.89264053701;21516.462088447603;21582.892085539785;838.7106448275335;841.8063845315489;896.4352457951449;914.8490499067713;939.3049143633662;9237.3533852631;9474.715758527254;11252.774944501065;11360.54557817678;11410.942945285387;587301;593327;601413;612975;616650;121862;126994;130242;132595.33702331426;132709;968.1915600473683;1008.7881544875659;1009.5557954499997;1020.7039958411978;1021.3361315145215;15989.920050399674;18666.346677866208;19835.60328793445;19863.106160223113;19880;48467.75766121147;52453.7377313111;54289;55468.44531554633;56389;81914;84647.34439605635;84727;86645;87538.56230718805;7794.337481314152;8023.719169829028;8043.718469853527;8357.749267521984;8375.62309696053;10022.398656080659;12588.937055314665;12702.682432939251;12769.744605108031;13055.673608159872;8642.611082501428;10665.733356666145;11028;11209.271397359145;11637.825432618687;10949.781004380027;11045.779084418105;11078.058365039062;11280.830787538358;11474.827877581676;11075.83386249223;11136.387498687325;11339.659810205707;11584.304941703525;11800.645980620595;7449.92550074514;7917.802054948442;8018.558979256197;8185.386096042702;8238.711645092393;9189.448633082036;9565.760855978657;10624.575016999233;11123.27698699582;11211.383373914514 08:28:41 INFO - PROCESS | 1641 | |3;mootools.html;1332.8867046301064;1468.8017117689108;1494.1782019889063;1663.941762038329;1853.6653609401235;7461.552306861586;7837.3338266247365;8161.34709223263;8427.91572084279;8434.072252052269;8294.377921655885;8371.246587807096;8376.036755773073;8383.455075420094;8393.664253429855;2329.196427232602;2344.882755862209;2347.3310106619615;2348.58899692554;2351.670766092748;2321.616933206019;2359.7640235976437;2372.6085195942737;2373.6914201153827;2381.5594115088766;1940.3499827557785;2002.6395248855201;2327.674125622414;2328.185135202674;2336.5560543496767;2026.3009261804655;2029.9695504567444;2045.9590808183902;2048.528838367184;2051.497383141125;2276.0326861083972;2301.4476525633895;2304.9423764405856;2306.423394151462;2306.6194077977116;8294.087650358455;8363.456375335583;8372.246497815226;8382.916170838274;8395.622197001088;5028.094942910275;5222.608304377187;5229.08491014071;5257.605679574009;5259.579233661298;3472.5485686860798;3565.6969157621634;3632.128289210597;3639.47227651989;3646.252518233756;904.2946501728662;1061.3737894642197;1070.9196810239264;1094.0372472224392;1096.9670909872716;10926.453677316102;10965.287256328313;11054.447277636167;11070.225084244106;11092.667219983414;3584.354816133069;4404.801783919731;4490.079533695584;4502.144592527409;4508.233600287959;8700.216980471661;8735.039145693969;8738.650453981896;8741.475511469205;8771.692990745294;5111.437741848394;5112.258722485293;5119.795208191631;5124.461931497213;5126.79492820283;4044.8584299549375;4284.4430224070675;4396.670249731283;4430.069685366045;4808.230683090689;3062.8162310261455;3174.3016536361965;3712.090537818252;3791.2038471920655;3796.145867179918;985.1970643925179;1148.655403378983;1150.6433005768238;1156.4275683536534;1161.9128565357632;811.2374368093973;894.6376717429451;900.6892622045384;920.889493260813;929.0987741890491;4882.365292512021;5354.143337066051;5432.51107400328;5898.085796701518;5945.227120474397;432.761980910498;439.1458612997689;455.26702009764256;458.9288660257665;462.08507155831643;3806.6003069677286;3822.3884178531302;3882.107115363482;3931.66580840626;4001.6198461146146;9841.015898410102;10130.696079117639;10225.94887025575;10230.59077636901;10242.283040187265;10177.491125443697;10180.694579162637;10193.949030254802;10215.387076775418;10227.43749093807;7666.310032097138;7769.339606133542;7956.920430795618;7983.042034955843;8010.479318844373;5440.211169380338;5595.300587426572;5626.88746225065;5644.125160600113;5740.483356497935;9030.413023153475;9238.076192380708;9265.583048762957;9286.442813431217;9371.344005919793;7584.317411432998;9511.762205944906;9555.713328600154;9621.759456013655;9628.18160456369;6571.901421478778;7129.572225666477;7152.713891444284;7298.56208627484;7322.853542929218;7657.808554786175;7866.449348545547;7943.205679432011;7950.562719050507;7957.602119894098;7187.532810367309;7226.0244866944395;7246.89129663045;7250.854982900418;7390.371818395497;7109.644517773987;7151.678174482056;7174.928250717426;7177.928220717726;7189.1013623297085;6523.934760652332;6552.3775241352;6574.8356291091195;6584.539082264196;6618.801435956929;4182.686298527562;4833.613310935047;4876.049170411736;4909.846186146324;4951.405831300267;4841.443234027991;4845.903081938412;4903.779329930233;4977.9751101244265;5111.923321150111 08:28:41 INFO - PROCESS | 1641 | |4;prototype.html;298.5834760509089;306.83277613700517;313.8368048614721;318.4872355507634;320.80751549070544;4925.94591353361;5185.351831021122;5313.601479889005;5373.408925018245;5424.321959755031;4973.806286491244;5027.371578552672;5157.974210128944;5202.583793296537;5322.4411436799155;4044.170944956277;4047.372657238133;4067.3288907330257;4085.0808568072243;4119.505659320886;3414.658534146578;3422.640622734602;3557.630312329759;4073;4113.506379234496;3920.6863450924;4022.296098182821;4044.6157615026527;4050.7367021143677;4065.959340406602;4059.6346328830546;4065.1259979104634;4115.814788334532;4119.608637179464;4135.710500264968;3419.863205471778;3528.964710352915;3545.556805399325;3569.428891377367;3585.623509531488;4327.935080973788;4950.480199579064;5230.633855630107;5234.790608375661;5286.392064912546;4458.897814011118;4525.389072475206;4603.846365239821;4634.096351211515;4636.536346365337;4788.545088216614;5165.896682066337;5563.332400111972;5585.832425027255;5642.6582968068205;3386.6951974322183;3782.205736795249;3867.6600656602914;3885.1739682349253;3926.685865130811;5404.1083221268445;5573.080441727151;5715.856553792286;5728.052462162843;5776.480116789509;3089.192822198994;3632.0919770057485;3893.7469064510715;3939.940900886489;3968.8412463501136;4000.7999600019884;4039.3738970459626;4039.4748682671066;4049.542164820662;4064.837406503766;4254.829806807693;4356.520782713899;4574.908501829945;4670.855640368063;4672.252439609646;5627.549796016309;5638.605297629127;5651;5664.461876121762;5664.638228563983;1193.4390836306923;1201.2612243470378;1204.3320001797574;1252.5616034387892;1275.7065874848652;695.4471195399568;753.9821241324278;827.5283088639418;832.4963397144761;856.8629019356997;4611.777878862104;4623.144718227139;4945.851624451272;5002.52476014778;5040.88282921174;4865.56209941107;5390.407055223922;5392.191171324254;5455.809046683347;5507.284053073155;4499.685022048425;4901.3383193269165;4933.3473286448725;4950.5247376311845;5421.593380496479;2485.652008718781;2800.8319500830016;2895.420915816845;2913.956290655642;2915.242037070377;5187.584993200535;5536.4186760390385;5687.374388817227;5696.259486266842;5722.256106706185;4337.697409746717;4982.60139188864;5608.803691870765;5609.962157000968;5746.396628354046;5490.6349452186405;5521.365043020104;5601.383847776741;5619.157126431068;5653.321601407857;4635.582797548237;4812.8796780080775;5339.78640854377;5459.426760190123;5470.72646367672;5138.768755405941;5680.005998950084;5741.1388291756575;5748.5688573358;5764.36591974874;5337.919931200958;5393.514583687487;5978.760849565969;6004.819855404345;6109.419605137505;3778.697704183604;3851.921461990535;3887.3974533947285;3922.470466486988;3982.78094704794;4798.49615790337;5476.808311709071;5778.701215674934;5794.536437084938;5798;5021.949780502148;5198.9204318273;5374.140137577969;5636.267285252891;5656.208130861765;4485.708428952108;5459.836204913859;5488.451154884479;5613.642270246424;5674.4892959633835;5253.159494480865;5515.73854719563;5529.834104976857;5538.307711536058;5556.555475562026;4902.779374928065;5496.8456624109385;5508.338999319947;5519.558435325084;5524.281843360337;4684.718916864999;5483.588730845123;5509.228707980966;5511.145772405283;5512.724363781715 08:28:41 INFO - PROCESS | 1641 | |5;yui.html;600.9218801555797;720.6792977125182;744.2706147974981;770.8265640230945;806.1535387842764;14174.574762757107;14419.92790036051;14794.704105917888;14891.925540372284;15105.395784168648;12951.222926624409;13266.336683165828;13299;13326.733465330688;13415.396307166176;805.5005896344275;827.611022819274;829.8298848735994;831.966721331146;832.4173078844802;662.5362246427496;682.1439093937101;683.1665368250726;684.0081881272175;684.9315068493136;844.2612713875359;882.5234373438368;883.6509578716403;885.9943963601295;888.3825741109931;870.8171284030361;872.1278721278721;873.7029410000598;874.6588830356167;875.3872289397384;440.5660424481883;540.9756560954727;653.624166104487;653.947684185264;656.6191608866845;13583.117097388638;14062.367193476319;14101.153930764085;14382;14398.136111833324;13684.110532815435;13979.440822367093;14067.788983165261;14158.646033849132;14190.21953792541;9732.562034708384;10896.618618348399;11496;11634.185607007514;11923.82114268278;3642.7267954903223;3701.648343407372;3733;3745.490521350702;3792.431135329695;77227;89388;90434;91533.5423322879;91977.16045679049;10336.741581460523;10383.792324153475;10431.94784026075;10472.68581942543;10505.947470262752;19918.60162796736;19999.200031998847;20101.899490502747;20174.092165852573;20201.888896110562;7448.4786064976015;7622.961885190539;7659.885101723478;7697.268759467842;7802.960985195151;4240.49114106303;4270.231358355526;4383.035732138925;4393.83563355711;4405.625521830681;3391.8304084795664;3492.318997795456;3500.1599616091953;3517.8592856285463;3541.610422853484;627.8901192291331;633.7781776378323;637.7735903754193;637.964911929839;639.7313128486047;592.4046333434957;623.510544222783;652.1815122021832;660.986780264392;668.4685674888475;8626.91373086261;9074.773130671654;9211.861822072675;9256.8611470828;9421.717348479557;597.7847974729094;600.3666132230538;629.902365133405;662.2979641579844;671.7783131566571;4323.227904109471;4542.500324964251;4556.407667003335;4563.566461186182;4650.930236046462;74906.87639685409;78975.65633890191;113956;118329.40835295769;120037.79962200442;67965.32034679689;71893.640531797;84187.5790621043;102356;104141;82186;83861.16138838533;87178.56410717906;89955;91229;7672.271134242238;8172.427930045078;8845.601947912488;8920.955395222982;9080.954595226982;56944;57770.711146444;57848.77824338277;58472.4152758484;59149.40850591611;74765.252347478;79274.82900683908;82355;83937;84327.15672843439;10982.560697572328;11147.940945610155;11282.266652667551;11509.07927365792;11680.532778689098;12015.519379225083;12054.638360849189;12355.62933112008;12394.318312492538;12609.747805044031;11725.41372931367;11928.463219154984;12034.157608967289;12168.391580420766;12282.07884408655;11646.24299420535;11666.241694289844;11762.764744704888;11960.880391196324;12357.567485137977;11536;11601.709957251136;11608.419579020847;11776.52893884235;12245.142840001114;8357.874631880648;9348.766280843034;9487.288453365887;9844.96124031025;10140.898591013995;10040.946769200156;10258.846117308098;10276.845847312146;10326;10468.842967355647 08:28:41 INFO - PROCESS | 1641 | __end_tp_report 08:28:41 INFO - PROCESS | 1641 | __start_cc_report 08:28:41 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1953 08:28:41 INFO - PROCESS | 1641 | __end_cc_report 08:28:41 INFO - PROCESS | 1641 | __startTimestamp1481646521609__endTimestamp 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | ------- Summary: start ------- 08:28:41 INFO - PROCESS | 1641 | Number of tests: 6 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5736.75 Median:5936.85 stddev:662.18 (11.2%) stddev-sans-first:632.98 08:28:41 INFO - PROCESS | 1641 | Values: 3175.0 3324.7 3335.2 3359.4 3381.1 5731.7 5805.6 5806.0 5842.7 5854.8 5660.4 5741.1 5757.3 5787.2 5792.4 5193.9 5213.3 5227.4 5231.1 5282.0 4127.7 5203.9 5206.8 5296.5 5305.3 5226.2 5258.3 5260.8 5272.5 5273.8 5253.8 5264.7 5288.8 5303.1 5306.6 5197.2 5225.2 5253.6 5276.1 5319.8 5614.2 5625.6 5663.4 5671.6 5671.9 5143.8 6202.6 6205.8 6241.7 6242.2 5983.7 6280.4 6294.4 6308.0 6308.4 5716.5 5735.5 5753.9 5757.9 5821.1 5308.0 6245.5 6250.7 6260.8 6282.8 6121.8 6137.2 6149.2 6172.2 6173.8 5089.0 5184.1 6126.9 6236.0 6248.9 6151.2 6174.1 6201.0 6249.5 6265.6 6204.4 6206.5 6247.9 6277.7 6282.1 4235.8 4738.6 4940.7 4957.7 4991.3 4380.1 4440.9 4442.0 4474.7 4476.8 5875.9 5882.0 5893.1 5894.3 5927.9 6263.7 6285.5 6293.8 6293.8 6299.4 5610.1 5725.6 5735.0 5848.6 5895.5 5422.2 5422.7 5439.8 5443.5 5474.9 6337.2 6342.5 6356.3 6357.3 6375.2 5917.5 6216.2 6270.6 6298.8 6299.8 6205.1 6253.8 6261.1 6287.0 6357.6 6214.8 6231.7 6239.5 6249.4 6261.2 6194.0 6250.9 6266.1 6287.7 6306.5 6014.8 6065.2 6078.4 6092.9 6142.8 6142.7 6151.8 6177.0 6186.1 6187.4 5922.5 6017.7 6043.8 6045.4 6094.7 6008.2 6065.5 6151.3 6173.8 6198.6 5928.5 5936.9 5982.7 5990.3 6009.0 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:65086.79 Median:21030.35 stddev:145710.89 (692.9%) stddev-sans-first:146038.82 08:28:41 INFO - PROCESS | 1641 | Values: 445.7 459.8 468.6 524.9 532.0 30760.9 31450.7 38238.4 39356.4 39600.2 40792.2 41108.4 41167.6 41194.8 41226.8 9114.5 9136.3 9179.4 9303.3 9318.4 8558.4 8952.2 9212.2 9324.4 9393.5 9248.3 9332.1 9341.8 9357.0 9370.4 9229.6 9276.3 9294.1 9296.7 9309.6 9235.5 9318.7 9320.5 9321.7 9324.0 32601.8 38634.0 38983.0 38991.6 39024.8 3259.2 3326.7 3718.5 3822.3 3844.4 29588.9 29630.0 29653.9 29999.6 30340.4 3109.1 3176.7 3178.4 3184.0 3201.7 418003.0 483504.0 483798.0 487796.0 488926.3 32470.0 33465.8 57290.9 57638.1 57932.0 34425.8 34712.7 34793.8 34819.0 34822.0 14069.9 14205.5 14206.6 14206.8 14254.0 11907.9 11988.5 11993.3 11996.0 12001.5 7536.5 7657.2 7677.7 7746.8 7766.9 3189.4 3234.6 3244.7 3277.4 3277.7 899.7 930.5 933.0 992.0 1115.6 27807.2 28604.6 28750.7 28846.6 29239.4 864.4 1062.3 1069.0 1083.9 1182.5 14418.4 14664.3 14787.9 14914.3 15180.5 663406.0 665010.0 667549.0 669425.0 670586.0 53542.0 54052.0 54068.0 54110.2 54191.5 1208.5 1240.2 1321.3 1351.6 1366.6 28951.8 29688.0 29763.9 30201.7 30549.2 45509.5 46093.8 46146.5 46258.5 46344.5 464357.0 466226.0 467770.0 467989.0 468388.0 23537.9 26106.0 26130.7 26137.5 26160.3 45114.3 45122.3 45192.9 45690.3 45891.5 36166.0 36354.3 38185.0 39035.8 40469.6 36838.4 36964.6 37003.8 37175.4 38435.6 36506.6 37342.6 37383.8 37514.6 37577.7 33105.5 33281.2 34138.7 34223.7 34252.8 24517.9 24715.0 25218.7 25263.7 25353.1 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:35531.97 Median:25508.68 stddev:100835.13 (395.3%) stddev-sans-first:101083.37 08:28:41 INFO - PROCESS | 1641 | Values: 335.7 355.4 376.4 381.7 405.5 14601.5 14744.2 15629.9 19930.9 20462.3 19271.9 19595.8 20194.4 20226.4 20430.0 5625.4 5806.8 5818.1 5820.0 5843.6 4514.2 5689.4 5764.2 5768.5 5774.0 4977.4 5570.5 5704.0 5709.8 5720.7 5508.5 5698.9 5729.5 5730.1 5730.4 4886.9 4981.0 5052.2 5059.1 5061.3 24287.4 24871.4 25333.9 25338.7 25678.6 2312.3 2416.7 2472.5 2625.9 2789.3 14576.9 14723.7 15155.4 15568.3 15651.8 1734.7 1754.3 1783.5 1789.0 1898.4 122321.0 122771.8 125206.4 128680.1 131048.3 11138.8 11480.7 11509.6 11525.8 11716.6 14712.9 14740.4 14745.3 14746.3 14761.3 7986.2 8105.1 8286.7 8329.3 8452.9 6746.6 7290.7 8034.5 8068.6 8232.8 4512.1 4515.3 4541.3 4544.3 4594.5 1889.5 1926.2 1928.2 1942.9 1949.9 701.8 891.1 935.1 945.4 945.8 20872.2 21411.3 21471.9 21516.5 21582.9 838.7 841.8 896.4 914.8 939.3 9237.4 9474.7 11252.8 11360.5 11410.9 587301.0 593327.0 601413.0 612975.0 616650.0 121862.0 126994.0 130242.0 132595.3 132709.0 968.2 1008.8 1009.6 1020.7 1021.3 15989.9 18666.3 19835.6 19863.1 19880.0 48467.8 52453.7 54289.0 55468.4 56389.0 81914.0 84647.3 84727.0 86645.0 87538.6 7794.3 8023.7 8043.7 8357.7 8375.6 10022.4 12588.9 12702.7 12769.7 13055.7 8642.6 10665.7 11028.0 11209.3 11637.8 10949.8 11045.8 11078.1 11280.8 11474.8 11075.8 11136.4 11339.7 11584.3 11800.6 7449.9 7917.8 8018.6 8185.4 8238.7 9189.4 9565.8 10624.6 11123.3 11211.4 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5395.50 Median:4964.69 stddev:3014.44 (60.7%) stddev-sans-first:3007.43 08:28:41 INFO - PROCESS | 1641 | Values: 1332.9 1468.8 1494.2 1663.9 1853.7 7461.6 7837.3 8161.3 8427.9 8434.1 8294.4 8371.2 8376.0 8383.5 8393.7 2329.2 2344.9 2347.3 2348.6 2351.7 2321.6 2359.8 2372.6 2373.7 2381.6 1940.3 2002.6 2327.7 2328.2 2336.6 2026.3 2030.0 2046.0 2048.5 2051.5 2276.0 2301.4 2304.9 2306.4 2306.6 8294.1 8363.5 8372.2 8382.9 8395.6 5028.1 5222.6 5229.1 5257.6 5259.6 3472.5 3565.7 3632.1 3639.5 3646.3 904.3 1061.4 1070.9 1094.0 1097.0 10926.5 10965.3 11054.4 11070.2 11092.7 3584.4 4404.8 4490.1 4502.1 4508.2 8700.2 8735.0 8738.7 8741.5 8771.7 5111.4 5112.3 5119.8 5124.5 5126.8 4044.9 4284.4 4396.7 4430.1 4808.2 3062.8 3174.3 3712.1 3791.2 3796.1 985.2 1148.7 1150.6 1156.4 1161.9 811.2 894.6 900.7 920.9 929.1 4882.4 5354.1 5432.5 5898.1 5945.2 432.8 439.1 455.3 458.9 462.1 3806.6 3822.4 3882.1 3931.7 4001.6 9841.0 10130.7 10225.9 10230.6 10242.3 10177.5 10180.7 10193.9 10215.4 10227.4 7666.3 7769.3 7956.9 7983.0 8010.5 5440.2 5595.3 5626.9 5644.1 5740.5 9030.4 9238.1 9265.6 9286.4 9371.3 7584.3 9511.8 9555.7 9621.8 9628.2 6571.9 7129.6 7152.7 7298.6 7322.9 7657.8 7866.4 7943.2 7950.6 7957.6 7187.5 7226.0 7246.9 7250.9 7390.4 7109.6 7151.7 7174.9 7177.9 7189.1 6523.9 6552.4 6574.8 6584.5 6618.8 4182.7 4833.6 4876.0 4909.8 4951.4 4841.4 4845.9 4903.8 4978.0 5111.9 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4487.91 Median:4992.56 stddev:1372.49 (27.5%) stddev-sans-first:1339.84 08:28:41 INFO - PROCESS | 1641 | Values: 298.6 306.8 313.8 318.5 320.8 4925.9 5185.4 5313.6 5373.4 5424.3 4973.8 5027.4 5158.0 5202.6 5322.4 4044.2 4047.4 4067.3 4085.1 4119.5 3414.7 3422.6 3557.6 4073.0 4113.5 3920.7 4022.3 4044.6 4050.7 4066.0 4059.6 4065.1 4115.8 4119.6 4135.7 3419.9 3529.0 3545.6 3569.4 3585.6 4327.9 4950.5 5230.6 5234.8 5286.4 4458.9 4525.4 4603.8 4634.1 4636.5 4788.5 5165.9 5563.3 5585.8 5642.7 3386.7 3782.2 3867.7 3885.2 3926.7 5404.1 5573.1 5715.9 5728.1 5776.5 3089.2 3632.1 3893.7 3939.9 3968.8 4000.8 4039.4 4039.5 4049.5 4064.8 4254.8 4356.5 4574.9 4670.9 4672.3 5627.5 5638.6 5651.0 5664.5 5664.6 1193.4 1201.3 1204.3 1252.6 1275.7 695.4 754.0 827.5 832.5 856.9 4611.8 4623.1 4945.9 5002.5 5040.9 4865.6 5390.4 5392.2 5455.8 5507.3 4499.7 4901.3 4933.3 4950.5 5421.6 2485.7 2800.8 2895.4 2914.0 2915.2 5187.6 5536.4 5687.4 5696.3 5722.3 4337.7 4982.6 5608.8 5610.0 5746.4 5490.6 5521.4 5601.4 5619.2 5653.3 4635.6 4812.9 5339.8 5459.4 5470.7 5138.8 5680.0 5741.1 5748.6 5764.4 5337.9 5393.5 5978.8 6004.8 6109.4 3778.7 3851.9 3887.4 3922.5 3982.8 4798.5 5476.8 5778.7 5794.5 5798.0 5021.9 5198.9 5374.1 5636.3 5656.2 4485.7 5459.8 5488.5 5613.6 5674.5 5253.2 5515.7 5529.8 5538.3 5556.6 4902.8 5496.8 5508.3 5519.6 5524.3 4684.7 5483.6 5509.2 5511.1 5512.7 08:28:41 INFO - PROCESS | 1641 | 08:28:41 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20162.30 Median:2595.95 stddev:29684.77 (1143.5%) stddev-sans-first:29731.71 08:28:41 INFO - PROCESS | 1641 | Values: 600.9 720.7 744.3 770.8 806.2 14174.6 14419.9 14794.7 14891.9 15105.4 12951.2 13266.3 13299.0 13326.7 13415.4 805.5 827.6 829.8 832.0 832.4 662.5 682.1 683.2 684.0 684.9 844.3 882.5 883.7 886.0 888.4 870.8 872.1 873.7 874.7 875.4 440.6 541.0 653.6 653.9 656.6 13583.1 14062.4 14101.2 14382.0 14398.1 13684.1 13979.4 14067.8 14158.6 14190.2 9732.6 10896.6 11496.0 11634.2 11923.8 3642.7 3701.6 3733.0 3745.5 3792.4 77227.0 89388.0 90434.0 91533.5 91977.2 10336.7 10383.8 10431.9 10472.7 10505.9 19918.6 19999.2 20101.9 20174.1 20201.9 7448.5 7623.0 7659.9 7697.3 7803.0 4240.5 4270.2 4383.0 4393.8 4405.6 3391.8 3492.3 3500.2 3517.9 3541.6 627.9 633.8 637.8 638.0 639.7 592.4 623.5 652.2 661.0 668.5 8626.9 9074.8 9211.9 9256.9 9421.7 597.8 600.4 629.9 662.3 671.8 4323.2 4542.5 4556.4 4563.6 4650.9 74906.9 78975.7 113956.0 118329.4 120037.8 67965.3 71893.6 84187.6 102356.0 104141.0 82186.0 83861.2 87178.6 89955.0 91229.0 7672.3 8172.4 8845.6 8921.0 9081.0 56944.0 57770.7 57848.8 58472.4 59149.4 74765.3 79274.8 82355.0 83937.0 84327.2 10982.6 11147.9 11282.3 11509.1 11680.5 12015.5 12054.6 12355.6 12394.3 12609.7 11725.4 11928.5 12034.2 12168.4 12282.1 11646.2 11666.2 11762.8 11960.9 12357.6 11536.0 11601.7 11608.4 11776.5 12245.1 8357.9 9348.8 9487.3 9845.0 10140.9 10040.9 10258.8 10276.8 10326.0 10468.8 08:28:41 INFO - PROCESS | 1641 | -------- Summary: end -------- 08:28:41 INFO - PROCESS | 1641 | 08:28:42 INFO - TEST-INFO | 1641: exit 0 08:28:42 INFO - TEST-OK | dromaeo_css | took 1120169ms 08:28:42 INFO - TEST-START | kraken 08:28:42 INFO - operating with platform_type : mac_ 08:28:42 INFO - Initialising browser for kraken test... 08:28:42 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpTkeIzz/profile http://localhost:49236/getInfo.html) 08:28:44 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:28:44 INFO - PROCESS | 1651 | colorDepth:24 08:28:44 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:28:44 INFO - PROCESS | 1651 | __metrics 08:28:44 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:28:44 INFO - PROCESS | 1651 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:28:45 INFO - TEST-INFO | 1651: exit 0 08:28:45 INFO - Browser initialized. 08:28:45 INFO - Running cycle 1/1 for kraken test... 08:28:45 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpTkeIzz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:58 INFO - PROCESS | 1652 | RSS: Main: 222826496 08:28:58 INFO - PROCESS | 1652 | 08:29:00 INFO - PROCESS | 1652 | 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) 08:29:01 INFO - PROCESS | 1652 | RSS: Main: 270536704 08:29:01 INFO - PROCESS | 1652 | 08:29:04 INFO - PROCESS | 1652 | 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) 08:29:05 INFO - PROCESS | 1652 | RSS: Main: 284667904 08:29:05 INFO - PROCESS | 1652 | 08:29:08 INFO - PROCESS | 1652 | 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) 08:29:08 INFO - PROCESS | 1652 | RSS: Main: 381808640 08:29:08 INFO - PROCESS | 1652 | 08:29:10 INFO - PROCESS | 1652 | 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) 08:29:11 INFO - PROCESS | 1652 | RSS: Main: 283553792 08:29:11 INFO - PROCESS | 1652 | 08:29:13 INFO - PROCESS | 1652 | 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) 08:29:14 INFO - PROCESS | 1652 | RSS: Main: 286003200 08:29:14 INFO - PROCESS | 1652 | 08:29:16 INFO - PROCESS | 1652 | 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) 08:29:17 INFO - PROCESS | 1652 | RSS: Main: 325836800 08:29:17 INFO - PROCESS | 1652 | 08:29:20 INFO - PROCESS | 1652 | 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) 08:29:21 INFO - PROCESS | 1652 | RSS: Main: 328040448 08:29:21 INFO - PROCESS | 1652 | 08:29:23 INFO - PROCESS | 1652 | 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) 08:29:24 INFO - PROCESS | 1652 | RSS: Main: 324304896 08:29:24 INFO - PROCESS | 1652 | 08:29:25 INFO - PROCESS | 1652 | 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) 08:29:26 INFO - PROCESS | 1652 | RSS: Main: 300285952 08:29:26 INFO - PROCESS | 1652 | 08:29:28 INFO - PROCESS | 1652 | 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) 08:29:29 INFO - PROCESS | 1652 | RSS: Main: 300806144 08:29:29 INFO - PROCESS | 1652 | 08:29:31 INFO - PROCESS | 1652 | 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) 08:29:31 INFO - PROCESS | 1652 | RSS: Main: 302391296 08:29:31 INFO - PROCESS | 1652 | 08:29:34 INFO - PROCESS | 1652 | 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) 08:29:35 INFO - PROCESS | 1652 | RSS: Main: 313126912 08:29:35 INFO - PROCESS | 1652 | 08:29:38 INFO - PROCESS | 1652 | 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) 08:29:39 INFO - PROCESS | 1652 | RSS: Main: 312037376 08:29:39 INFO - PROCESS | 1652 | 08:29:40 INFO - PROCESS | 1652 | 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) 08:29:41 INFO - PROCESS | 1652 | RSS: Main: 356663296 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | __start_tp_report 08:29:41 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 08:29:41 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 08:29:41 INFO - PROCESS | 1652 | |i|pagename|runs| 08:29:41 INFO - PROCESS | 1652 | |0;ai-astar;103;114;114;115;114;114;112;113;114;113 08:29:41 INFO - PROCESS | 1652 | |1;audio-beat-detection;145;110;112;144;110;109;110;108;141;111 08:29:41 INFO - PROCESS | 1652 | |2;audio-dft;182;183;184;185;195;256;239;184;184;184 08:29:41 INFO - PROCESS | 1652 | |3;audio-fft;81;59;60;88;61;59;58;60;82;58 08:29:41 INFO - PROCESS | 1652 | |4;audio-oscillator;88;70;93;70;69;69;90;67;69;93 08:29:41 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;86;87;87;84;79;127;131;83;86;96 08:29:41 INFO - PROCESS | 1652 | |6;imaging-darkroom;93;93;93;93;93;102;132;93;93;94 08:29:41 INFO - PROCESS | 1652 | |7;imaging-desaturate;80;84;79;79;81;84;83;85;84;81 08:29:41 INFO - PROCESS | 1652 | |8;json-parse-financial;55;55;56;56;57;59;57;58;59;57 08:29:41 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;68;75;77;73;74;71;70;70;71;72 08:29:41 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;82;92;87;83;80;83;85;82;84;82 08:29:41 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;146;139;151;154;162;145;144;143;224;133 08:29:41 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;163;169;219;166;164;158;163;161;162;162 08:29:41 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;68;68;69;66;67;67;67;67;67;68 08:29:41 INFO - PROCESS | 1652 | __end_tp_report 08:29:41 INFO - PROCESS | 1652 | __start_cc_report 08:29:41 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1984 08:29:41 INFO - PROCESS | 1652 | __end_cc_report 08:29:41 INFO - PROCESS | 1652 | __startTimestamp1481646581099__endTimestamp 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | ------- Summary: start ------- 08:29:41 INFO - PROCESS | 1652 | Number of tests: 14 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.47 (3.0%) stddev-sans-first:0.87 08:29:41 INFO - PROCESS | 1652 | Values: 103.0 114.0 114.0 115.0 114.0 114.0 112.0 113.0 114.0 113.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:111.50 stddev:16.17 (14.5%) stddev-sans-first:14.39 08:29:41 INFO - PROCESS | 1652 | Values: 145.0 110.0 112.0 144.0 110.0 109.0 110.0 108.0 141.0 111.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:197.60 Median:184.50 stddev:26.85 (14.6%) stddev-sans-first:27.87 08:29:41 INFO - PROCESS | 1652 | Values: 182.0 183.0 184.0 185.0 195.0 256.0 239.0 184.0 184.0 184.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:11.95 (19.7%) stddev-sans-first:11.48 08:29:41 INFO - PROCESS | 1652 | Values: 81.0 59.0 60.0 88.0 61.0 59.0 58.0 60.0 82.0 58.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.48 (14.5%) stddev-sans-first:11.57 08:29:41 INFO - PROCESS | 1652 | Values: 88.0 70.0 93.0 70.0 69.0 69.0 90.0 67.0 69.0 93.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:86.00 stddev:18.65 (21.7%) stddev-sans-first:19.52 08:29:41 INFO - PROCESS | 1652 | Values: 86.0 87.0 87.0 84.0 79.0 127.0 131.0 83.0 86.0 96.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:93.00 stddev:12.31 (13.2%) stddev-sans-first:12.92 08:29:41 INFO - PROCESS | 1652 | Values: 93.0 93.0 93.0 93.0 93.0 102.0 132.0 93.0 93.0 94.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:83.50 stddev:2.26 (2.7%) stddev-sans-first:2.28 08:29:41 INFO - PROCESS | 1652 | Values: 80.0 84.0 79.0 79.0 81.0 84.0 83.0 85.0 84.0 81.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:1.45 (2.5%) stddev-sans-first:1.36 08:29:41 INFO - PROCESS | 1652 | Values: 55.0 55.0 56.0 56.0 57.0 59.0 57.0 58.0 59.0 57.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.50 stddev:2.69 (3.7%) stddev-sans-first:2.40 08:29:41 INFO - PROCESS | 1652 | Values: 68.0 75.0 77.0 73.0 74.0 71.0 70.0 70.0 71.0 72.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.50 stddev:3.40 (4.1%) stddev-sans-first:3.53 08:29:41 INFO - PROCESS | 1652 | Values: 82.0 92.0 87.0 83.0 80.0 83.0 85.0 82.0 84.0 82.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:148.50 stddev:25.83 (17.4%) stddev-sans-first:27.23 08:29:41 INFO - PROCESS | 1652 | Values: 146.0 139.0 151.0 154.0 162.0 145.0 144.0 143.0 224.0 133.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:163.50 stddev:17.91 (11.0%) stddev-sans-first:18.88 08:29:41 INFO - PROCESS | 1652 | Values: 163.0 169.0 219.0 166.0 164.0 158.0 163.0 161.0 162.0 162.0 08:29:41 INFO - PROCESS | 1652 | 08:29:41 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:0.84 (1.2%) stddev-sans-first:0.87 08:29:41 INFO - PROCESS | 1652 | Values: 68.0 68.0 69.0 66.0 67.0 67.0 67.0 67.0 67.0 68.0 08:29:41 INFO - PROCESS | 1652 | -------- Summary: end -------- 08:29:41 INFO - PROCESS | 1652 | 08:29:42 INFO - TEST-INFO | 1652: exit 0 08:29:42 INFO - TEST-OK | kraken | took 59926ms 08:29:42 INFO - SUITE-END | took 1180s 08:29:42 INFO - Completed test suite (00:19:40) 08:29:42 INFO - javascript benchmark 08:29:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3174.9841250793743, 3324.7007769300744, 3335.199552107495, 3359.378514974728, 3381.070205693434, 5731.65610063396, 5805.593608447411, 5805.983952808263, 5842.737076831542, 5854.824355269343, 5660.405657405975, 5741.052726300166, 5757.309122905247, 5787.21872547206, 5792.449717276862, 5193.87015324618, 5213.2701421800975, 5227.398849132342, 5231.136862417716, 5282.0, 4127.669786417079, 5203.86990325243, 5206.817761378353, 5296.523312901838, 5305.336832895888, 5226.163813789794, 5258.316418865561, 5260.763265653055, 5272.472752724735, 5273.789048438058, 5253.763580638862, 5264.736763161826, 5288.788448462057, 5303.098473259516, 5306.628536002481, 5197.22041693749, 5225.2162175673575, 5253.553447956928, 5276.050310944029, 5319.840404787863, 5614.214010038598, 5625.606207565472, 5663.433656634304, 5671.602987790856, 5671.922334756383, 5143.8456846294675, 6202.627842329474, 6205.7517699291975, 6241.7191226394925, 6242.157308763304, 5983.730732117066, 6280.403361680633, 6294.3705629437145, 6308.022256550242, 6308.369163083701, 5716.542676585863, 5735.541156707453, 5753.942460575425, 5757.88484230313, 5821.097729851837, 5307.9734601326745, 6245.469135123474, 6250.6874656267, 6260.843478913063, 6282.842928926722, 6121.816345509642, 6137.232845894263, 6149.169862068564, 6172.197614310111, 6173.84565385869, 5089.0, 5184.14461613837, 6126.9452004266695, 6236.0, 6248.9062664060075, 6151.200343955346, 6174.135621013062, 6200.968995155085, 6249.468795152372, 6265.624062556261, 6204.441600255908, 6206.503479721611, 6247.9062814057825, 6277.654728989949, 6282.057691346335, 4235.7670328131635, 4738.64460165489, 4940.654154209243, 4957.652964292537, 4991.251312303111, 4380.145871555016, 4440.911181776411, 4442.044960333479, 4474.664400170001, 4476.798544065525, 5875.9118613220835, 5882.02946513829, 5893.086571581412, 5894.2926848778425, 5927.911081333784, 6263.749450021948, 6285.465735412449, 6293.842653933688, 6293.842653933688, 6299.370062993756, 5610.13042978339, 5725.59920805548, 5734.971325143348, 5848.64908105515, 5895.498882595028, 5422.213779002102, 5422.7017514036315, 5439.755211015514, 5443.537299329601, 5474.863128421821, 6337.176167098248, 6342.524310676626, 6356.300806911236, 6357.300696923242, 6375.234971803413, 5917.497012753792, 6216.222972128484, 6270.592411493239, 6298.842528936813, 6299.842503937438, 6205.131281620667, 6253.812385628438, 6261.092141639528, 6286.968565157145, 6357.586756860789, 6214.751409943552, 6231.688415579293, 6239.5008399327035, 6249.406306400884, 6261.186045814015, 6194.0, 6250.906236406367, 6266.091416744638, 6287.654179020198, 6306.527010474325, 6014.849628759316, 6065.2418447694035, 6078.361772014052, 6092.8781424372155, 6142.846428839315, 6142.652254521386, 6151.8311520812695, 6176.969115154396, 6186.1339412483185, 6187.3503282154725, 5922.5261979040715, 6017.67792653482, 6043.758249669964, 6045.365236650265, 6094.725737341919, 6008.218931538872, 6065.54508411862, 6151.292601350902, 6173.753049877954, 6198.6280823150755, 5928.466438020599, 5936.851578710567, 5982.730777115127, 5990.251218597676, 6009.0], "value": 5694.127478267156, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.7058341494614, 459.78390156626386, 468.58296116456336, 524.892397058603, 532.0263917031831, 30760.923367682135, 31450.68549314506, 38238.42642360367, 39356.40965385522, 39600.207995840065, 40792.18415631693, 41108.38337424934, 41167.58832411675, 41194.79402602983, 41226.763197104134, 9114.45313281202, 9136.269098472107, 9179.403338782991, 9303.25573954082, 9318.394304370227, 8558.358123140759, 8952.239059679903, 9212.216961558275, 9324.440533567973, 9393.483358415284, 9248.26013918885, 9332.113449222314, 9341.76645583859, 9357.0, 9370.437773733564, 9229.584668689891, 9276.304277179239, 9294.117058879396, 9296.674616388462, 9309.627614895397, 9235.538223088886, 9318.72043838686, 9320.533973301308, 9321.673741419085, 9324.0, 32601.836990814892, 38634.0, 38983.0, 38991.610083899366, 39024.80487597572, 3259.1852036990754, 3326.7172290355347, 3718.4794128821986, 3822.2737679840743, 3844.4041173618134, 29588.852055739582, 29629.962951296602, 29653.851730741422, 29999.550006749916, 30340.393192136034, 3109.113902537766, 3176.682331766828, 3178.3802158579088, 3184.0447865640217, 3201.743860491155, 418003.0, 483504.0, 483798.0, 487796.0, 488926.33052521205, 32470.025899223063, 33465.83267083649, 57290.85418291611, 57638.13542796861, 57932.0, 34425.827870860485, 34712.652873471445, 34793.82603087019, 34819.0, 34822.0, 14069.929650351683, 14205.502807401692, 14206.573802785933, 14206.786898196535, 14254.0, 11907.880921190677, 11988.520459181535, 11993.340366279765, 11996.0, 12001.459934302977, 7536.547807131589, 7657.234276572298, 7677.692892284358, 7746.845063098706, 7766.883496747553, 3189.3780712761254, 3234.5956755405573, 3244.6755324467836, 3277.4264503711756, 3277.672232776751, 899.7448559259796, 930.5021813329836, 933.0, 991.9752006199902, 1115.5649296774266, 27807.165785026482, 28604.570931435628, 28750.71249287522, 28846.573440444645, 29239.415211695647, 864.4424346296604, 1062.309498825754, 1068.9791249356845, 1083.9458027098613, 1182.526989204308, 14418.42326306936, 14664.340104695315, 14787.852121478863, 14914.254287285592, 15180.508483048961, 663406.0, 665010.0, 667549.0, 669425.0, 670586.0, 53542.0, 54052.0, 54068.0, 54110.18834717482, 54191.45808541943, 1208.4924331780674, 1240.2000709542294, 1321.2799024531462, 1351.6147897849262, 1366.6241783509415, 28951.84192632271, 29688.0, 29763.851180743957, 30201.697983019887, 30549.23626909345, 45509.544904551854, 46093.76953115213, 46146.53853461422, 46258.537414625425, 46344.536554634025, 464357.0, 466226.0, 467770.0, 467989.0, 468388.0, 23537.88231058834, 26105.955761769317, 26130.73869261359, 26137.477250454504, 26160.34599135037, 45114.323285150094, 45122.323165151895, 45192.87017824449, 45690.31464528101, 45891.54108458872, 36166.0, 36354.27291454209, 38185.045373865876, 39035.80482097571, 40469.59530404658, 36838.44742328921, 36964.63035369612, 37003.81498092492, 37175.442368363954, 38435.6156438432, 36506.634933650326, 37342.62657373391, 37383.81308093442, 37514.62485375111, 37577.684781033626, 33105.50341744828, 33281.167970800925, 34138.65861341355, 34223.657763422045, 34252.828735856165, 24517.877410612833, 24715.0, 25218.74781252164, 25263.747362526876, 25353.112641057476], "value": 17948.785192364354, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.71128829206884, 355.3585777671302, 376.4372263466118, 381.68320294155853, 405.52350987589574, 14601.488947886826, 14744.189069601167, 15629.921850390761, 19930.900345498252, 20462.2838200663, 19271.903640481814, 19595.804041959575, 20194.39416817498, 20226.39320820374, 20430.0, 5625.40933202014, 5806.82579522615, 5818.1272809078555, 5820.039693450597, 5843.620164689303, 4514.187446259672, 5689.374168841424, 5764.164196191549, 5768.451997060272, 5774.0, 4977.42759582649, 5570.498655121039, 5704.030314846465, 5709.8287051388525, 5720.742566584493, 5508.476694713996, 5698.886022279531, 5729.455701708331, 5730.111832665943, 5730.398308177623, 4886.902261954777, 4981.0, 5052.242163675442, 5059.06407314648, 5061.342025536694, 24287.392815179584, 24871.378215544577, 25333.873330633414, 25338.746612534007, 25678.614820777704, 2312.2600767754325, 2416.673749043874, 2472.5178590174924, 2625.868706564683, 2789.288731373507, 14576.854231457655, 14723.705525889422, 15155.393784248618, 15568.299426525833, 15651.843481565153, 1734.7397890316427, 1754.2544418622163, 1783.482789990901, 1788.9910550447294, 1898.3830255166988, 122321.0, 122771.77228227693, 125206.37396813049, 128680.06979895216, 131048.34475827655, 11138.777224455627, 11480.712982175512, 11509.597164099383, 11525.76948461026, 11716.648500544998, 14712.926435367755, 14740.41038358475, 14745.262736863115, 14746.262686865828, 14761.33573989173, 7986.241307075818, 8105.148959359182, 8286.668533258722, 8329.33365330772, 8452.915470845213, 6746.628935408501, 7290.744823931243, 8034.464835472373, 8068.596570171468, 8232.794180145544, 4512.075024619945, 4515.345274935116, 4541.3188021797, 4544.341070544819, 4594.47163576193, 1889.527618095476, 1926.248762982438, 1928.151613290148, 1942.8834269943848, 1949.9317523886596, 701.8175274428584, 891.1009119628103, 935.0696057422907, 945.4091193004373, 945.8297506448768, 20872.165113395597, 21411.250606228707, 21471.89264053701, 21516.462088447603, 21582.892085539785, 838.7106448275335, 841.8063845315489, 896.4352457951449, 914.8490499067713, 939.3049143633662, 9237.3533852631, 9474.715758527254, 11252.774944501065, 11360.54557817678, 11410.942945285387, 587301.0, 593327.0, 601413.0, 612975.0, 616650.0, 121862.0, 126994.0, 130242.0, 132595.33702331426, 132709.0, 968.1915600473683, 1008.7881544875659, 1009.5557954499997, 1020.7039958411978, 1021.3361315145215, 15989.920050399674, 18666.346677866208, 19835.60328793445, 19863.106160223113, 19880.0, 48467.75766121147, 52453.7377313111, 54289.0, 55468.44531554633, 56389.0, 81914.0, 84647.34439605635, 84727.0, 86645.0, 87538.56230718805, 7794.337481314152, 8023.719169829028, 8043.718469853527, 8357.749267521984, 8375.62309696053, 10022.398656080659, 12588.937055314665, 12702.682432939251, 12769.744605108031, 13055.673608159872, 8642.611082501428, 10665.733356666145, 11028.0, 11209.271397359145, 11637.825432618687, 10949.781004380027, 11045.779084418105, 11078.058365039062, 11280.830787538358, 11474.827877581676, 11075.83386249223, 11136.387498687325, 11339.659810205707, 11584.304941703525, 11800.645980620595, 7449.92550074514, 7917.802054948442, 8018.558979256197, 8185.386096042702, 8238.711645092393, 9189.448633082036, 9565.760855978657, 10624.575016999233, 11123.27698699582, 11211.383373914514], "value": 9517.161587877208, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.8867046301064, 1468.8017117689108, 1494.1782019889063, 1663.941762038329, 1853.6653609401235, 7461.552306861586, 7837.3338266247365, 8161.34709223263, 8427.91572084279, 8434.072252052269, 8294.377921655885, 8371.246587807096, 8376.036755773073, 8383.455075420094, 8393.664253429855, 2329.196427232602, 2344.882755862209, 2347.3310106619615, 2348.58899692554, 2351.670766092748, 2321.616933206019, 2359.7640235976437, 2372.6085195942737, 2373.6914201153827, 2381.5594115088766, 1940.3499827557785, 2002.6395248855201, 2327.674125622414, 2328.185135202674, 2336.5560543496767, 2026.3009261804655, 2029.9695504567444, 2045.9590808183902, 2048.528838367184, 2051.497383141125, 2276.0326861083972, 2301.4476525633895, 2304.9423764405856, 2306.423394151462, 2306.6194077977116, 8294.087650358455, 8363.456375335583, 8372.246497815226, 8382.916170838274, 8395.622197001088, 5028.094942910275, 5222.608304377187, 5229.08491014071, 5257.605679574009, 5259.579233661298, 3472.5485686860798, 3565.6969157621634, 3632.128289210597, 3639.47227651989, 3646.252518233756, 904.2946501728662, 1061.3737894642197, 1070.9196810239264, 1094.0372472224392, 1096.9670909872716, 10926.453677316102, 10965.287256328313, 11054.447277636167, 11070.225084244106, 11092.667219983414, 3584.354816133069, 4404.801783919731, 4490.079533695584, 4502.144592527409, 4508.233600287959, 8700.216980471661, 8735.039145693969, 8738.650453981896, 8741.475511469205, 8771.692990745294, 5111.437741848394, 5112.258722485293, 5119.795208191631, 5124.461931497213, 5126.79492820283, 4044.8584299549375, 4284.4430224070675, 4396.670249731283, 4430.069685366045, 4808.230683090689, 3062.8162310261455, 3174.3016536361965, 3712.090537818252, 3791.2038471920655, 3796.145867179918, 985.1970643925179, 1148.655403378983, 1150.6433005768238, 1156.4275683536534, 1161.9128565357632, 811.2374368093973, 894.6376717429451, 900.6892622045384, 920.889493260813, 929.0987741890491, 4882.365292512021, 5354.143337066051, 5432.51107400328, 5898.085796701518, 5945.227120474397, 432.761980910498, 439.1458612997689, 455.26702009764256, 458.9288660257665, 462.08507155831643, 3806.6003069677286, 3822.3884178531302, 3882.107115363482, 3931.66580840626, 4001.6198461146146, 9841.015898410102, 10130.696079117639, 10225.94887025575, 10230.59077636901, 10242.283040187265, 10177.491125443697, 10180.694579162637, 10193.949030254802, 10215.387076775418, 10227.43749093807, 7666.310032097138, 7769.339606133542, 7956.920430795618, 7983.042034955843, 8010.479318844373, 5440.211169380338, 5595.300587426572, 5626.88746225065, 5644.125160600113, 5740.483356497935, 9030.413023153475, 9238.076192380708, 9265.583048762957, 9286.442813431217, 9371.344005919793, 7584.317411432998, 9511.762205944906, 9555.713328600154, 9621.759456013655, 9628.18160456369, 6571.901421478778, 7129.572225666477, 7152.713891444284, 7298.56208627484, 7322.853542929218, 7657.808554786175, 7866.449348545547, 7943.205679432011, 7950.562719050507, 7957.602119894098, 7187.532810367309, 7226.0244866944395, 7246.89129663045, 7250.854982900418, 7390.371818395497, 7109.644517773987, 7151.678174482056, 7174.928250717426, 7177.928220717726, 7189.1013623297085, 6523.934760652332, 6552.3775241352, 6574.8356291091195, 6584.539082264196, 6618.801435956929, 4182.686298527562, 4833.613310935047, 4876.049170411736, 4909.846186146324, 4951.405831300267, 4841.443234027991, 4845.903081938412, 4903.779329930233, 4977.9751101244265, 5111.923321150111], "value": 4278.714690888183, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.5834760509089, 306.83277613700517, 313.8368048614721, 318.4872355507634, 320.80751549070544, 4925.94591353361, 5185.351831021122, 5313.601479889005, 5373.408925018245, 5424.321959755031, 4973.806286491244, 5027.371578552672, 5157.974210128944, 5202.583793296537, 5322.4411436799155, 4044.170944956277, 4047.372657238133, 4067.3288907330257, 4085.0808568072243, 4119.505659320886, 3414.658534146578, 3422.640622734602, 3557.630312329759, 4073.0, 4113.506379234496, 3920.6863450924, 4022.296098182821, 4044.6157615026527, 4050.7367021143677, 4065.959340406602, 4059.6346328830546, 4065.1259979104634, 4115.814788334532, 4119.608637179464, 4135.710500264968, 3419.863205471778, 3528.964710352915, 3545.556805399325, 3569.428891377367, 3585.623509531488, 4327.935080973788, 4950.480199579064, 5230.633855630107, 5234.790608375661, 5286.392064912546, 4458.897814011118, 4525.389072475206, 4603.846365239821, 4634.096351211515, 4636.536346365337, 4788.545088216614, 5165.896682066337, 5563.332400111972, 5585.832425027255, 5642.6582968068205, 3386.6951974322183, 3782.205736795249, 3867.6600656602914, 3885.1739682349253, 3926.685865130811, 5404.1083221268445, 5573.080441727151, 5715.856553792286, 5728.052462162843, 5776.480116789509, 3089.192822198994, 3632.0919770057485, 3893.7469064510715, 3939.940900886489, 3968.8412463501136, 4000.7999600019884, 4039.3738970459626, 4039.4748682671066, 4049.542164820662, 4064.837406503766, 4254.829806807693, 4356.520782713899, 4574.908501829945, 4670.855640368063, 4672.252439609646, 5627.549796016309, 5638.605297629127, 5651.0, 5664.461876121762, 5664.638228563983, 1193.4390836306923, 1201.2612243470378, 1204.3320001797574, 1252.5616034387892, 1275.7065874848652, 695.4471195399568, 753.9821241324278, 827.5283088639418, 832.4963397144761, 856.8629019356997, 4611.777878862104, 4623.144718227139, 4945.851624451272, 5002.52476014778, 5040.88282921174, 4865.56209941107, 5390.407055223922, 5392.191171324254, 5455.809046683347, 5507.284053073155, 4499.685022048425, 4901.3383193269165, 4933.3473286448725, 4950.5247376311845, 5421.593380496479, 2485.652008718781, 2800.8319500830016, 2895.420915816845, 2913.956290655642, 2915.242037070377, 5187.584993200535, 5536.4186760390385, 5687.374388817227, 5696.259486266842, 5722.256106706185, 4337.697409746717, 4982.60139188864, 5608.803691870765, 5609.962157000968, 5746.396628354046, 5490.6349452186405, 5521.365043020104, 5601.383847776741, 5619.157126431068, 5653.321601407857, 4635.582797548237, 4812.8796780080775, 5339.78640854377, 5459.426760190123, 5470.72646367672, 5138.768755405941, 5680.005998950084, 5741.1388291756575, 5748.5688573358, 5764.36591974874, 5337.919931200958, 5393.514583687487, 5978.760849565969, 6004.819855404345, 6109.419605137505, 3778.697704183604, 3851.921461990535, 3887.3974533947285, 3922.470466486988, 3982.78094704794, 4798.49615790337, 5476.808311709071, 5778.701215674934, 5794.536437084938, 5798.0, 5021.949780502148, 5198.9204318273, 5374.140137577969, 5636.267285252891, 5656.208130861765, 4485.708428952108, 5459.836204913859, 5488.451154884479, 5613.642270246424, 5674.4892959633835, 5253.159494480865, 5515.73854719563, 5529.834104976857, 5538.307711536058, 5556.555475562026, 4902.779374928065, 5496.8456624109385, 5508.338999319947, 5519.558435325084, 5524.281843360337, 4684.718916864999, 5483.588730845123, 5509.228707980966, 5511.145772405283, 5512.724363781715], "value": 4039.937985796922, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.9218801555797, 720.6792977125182, 744.2706147974981, 770.8265640230945, 806.1535387842764, 14174.574762757107, 14419.92790036051, 14794.704105917888, 14891.925540372284, 15105.395784168648, 12951.222926624409, 13266.336683165828, 13299.0, 13326.733465330688, 13415.396307166176, 805.5005896344275, 827.611022819274, 829.8298848735994, 831.966721331146, 832.4173078844802, 662.5362246427496, 682.1439093937101, 683.1665368250726, 684.0081881272175, 684.9315068493136, 844.2612713875359, 882.5234373438368, 883.6509578716403, 885.9943963601295, 888.3825741109931, 870.8171284030361, 872.1278721278721, 873.7029410000598, 874.6588830356167, 875.3872289397384, 440.5660424481883, 540.9756560954727, 653.624166104487, 653.947684185264, 656.6191608866845, 13583.117097388638, 14062.367193476319, 14101.153930764085, 14382.0, 14398.136111833324, 13684.110532815435, 13979.440822367093, 14067.788983165261, 14158.646033849132, 14190.21953792541, 9732.562034708384, 10896.618618348399, 11496.0, 11634.185607007514, 11923.82114268278, 3642.7267954903223, 3701.648343407372, 3733.0, 3745.490521350702, 3792.431135329695, 77227.0, 89388.0, 90434.0, 91533.5423322879, 91977.16045679049, 10336.741581460523, 10383.792324153475, 10431.94784026075, 10472.68581942543, 10505.947470262752, 19918.60162796736, 19999.200031998847, 20101.899490502747, 20174.092165852573, 20201.888896110562, 7448.4786064976015, 7622.961885190539, 7659.885101723478, 7697.268759467842, 7802.960985195151, 4240.49114106303, 4270.231358355526, 4383.035732138925, 4393.83563355711, 4405.625521830681, 3391.8304084795664, 3492.318997795456, 3500.1599616091953, 3517.8592856285463, 3541.610422853484, 627.8901192291331, 633.7781776378323, 637.7735903754193, 637.964911929839, 639.7313128486047, 592.4046333434957, 623.510544222783, 652.1815122021832, 660.986780264392, 668.4685674888475, 8626.91373086261, 9074.773130671654, 9211.861822072675, 9256.8611470828, 9421.717348479557, 597.7847974729094, 600.3666132230538, 629.902365133405, 662.2979641579844, 671.7783131566571, 4323.227904109471, 4542.500324964251, 4556.407667003335, 4563.566461186182, 4650.930236046462, 74906.87639685409, 78975.65633890191, 113956.0, 118329.40835295769, 120037.79962200442, 67965.32034679689, 71893.640531797, 84187.5790621043, 102356.0, 104141.0, 82186.0, 83861.16138838533, 87178.56410717906, 89955.0, 91229.0, 7672.271134242238, 8172.427930045078, 8845.601947912488, 8920.955395222982, 9080.954595226982, 56944.0, 57770.711146444, 57848.77824338277, 58472.4152758484, 59149.40850591611, 74765.252347478, 79274.82900683908, 82355.0, 83937.0, 84327.15672843439, 10982.560697572328, 11147.940945610155, 11282.266652667551, 11509.07927365792, 11680.532778689098, 12015.519379225083, 12054.638360849189, 12355.62933112008, 12394.318312492538, 12609.747805044031, 11725.41372931367, 11928.463219154984, 12034.157608967289, 12168.391580420766, 12282.07884408655, 11646.24299420535, 11666.241694289844, 11762.764744704888, 11960.880391196324, 12357.567485137977, 11536.0, 11601.709957251136, 11608.419579020847, 11776.52893884235, 12245.142840001114, 8357.874631880648, 9348.766280843034, 9487.288453365887, 9844.96124031025, 10140.898591013995, 10040.946769200156, 10258.846117308098, 10276.845847312146, 10326.0, 10468.842967355647], "value": 7109.059944125292, "unit": "score"}], "name": "dromaeo_css", "value": 7018.631928817411, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 115.0, 114.0, 114.0, 112.0, 113.0, 114.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 112.0, 144.0, 110.0, 109.0, 110.0, 108.0, 141.0, 111.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 184.0, 185.0, 195.0, 256.0, 239.0, 184.0, 184.0, 184.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 61.0, 59.0, 58.0, 60.0, 82.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 93.0, 70.0, 69.0, 69.0, 90.0, 67.0, 69.0, 93.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 84.0, 79.0, 127.0, 131.0, 83.0, 86.0, 96.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 102.0, 132.0, 93.0, 93.0, 94.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 79.0, 79.0, 81.0, 84.0, 83.0, 85.0, 84.0, 81.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 56.0, 57.0, 59.0, 57.0, 58.0, 59.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 77.0, 73.0, 74.0, 71.0, 70.0, 70.0, 71.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 92.0, 87.0, 83.0, 80.0, 83.0, 85.0, 82.0, 84.0, 82.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 139.0, 151.0, 154.0, 162.0, 145.0, 144.0, 143.0, 224.0, 133.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 169.0, 219.0, 166.0, 164.0, 158.0, 163.0, 161.0, 162.0, 162.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1452.3, "alertThreshold": 2.0}]} 08:29:42 INFO - Return code: 0 08:29:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:29:42 INFO - # TBPL SUCCESS # 08:29:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:29:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:29:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:29:42 INFO - 2016-12-13 08:29:42.411 screenresolution[1656:10014] starting screenresolution argv=screenresolution get 08:29:42 INFO - 2016-12-13 08:29:42.415 screenresolution[1656:10014] Display 0: 1600x1200x32@60 08:29:42 INFO - 2016-12-13 08:29:42.421 screenresolution[1657:10017] starting screenresolution argv=screenresolution list 08:29:42 INFO - Available Modes on Display 0 08:29:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:29:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:29:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:29:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:29:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:29:42 INFO - Intel Iris: 08:29:42 INFO - Chipset Model: Intel Iris 08:29:42 INFO - Type: GPU 08:29:42 INFO - Bus: Built-In 08:29:42 INFO - VRAM (Dynamic, Max): 1536 MB 08:29:42 INFO - Vendor: Intel (0x8086) 08:29:42 INFO - Device ID: 0x0a2e 08:29:42 INFO - Revision ID: 0x0009 08:29:42 INFO - Displays: 08:29:42 INFO - Display: 08:29:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:29:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:29:42 INFO - Display Serial Number: 1600x1200 60 08:29:42 INFO - Main Display: Yes 08:29:42 INFO - Mirror: Off 08:29:42 INFO - Online: Yes 08:29:42 INFO - Rotation: Supported 08:29:42 INFO - Return code: 0 08:29:42 INFO - Running post-action listener: _resource_record_post_action 08:29:42 INFO - [mozharness: 2016-12-13 16:29:42.668617Z] Finished run-tests step (success) 08:29:42 INFO - Running post-run listener: _resource_record_post_run 08:29:42 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37610496; Write bytes: 655413760; Read time: 237; Write time: 1872 08:29:42 INFO - TinderboxPrint: CPU usage
53.5% 08:29:42 INFO - TinderboxPrint: I/O read bytes / time
37,610,496 / 237 08:29:42 INFO - TinderboxPrint: I/O write bytes / time
655,413,760 / 1,872 08:29:42 INFO - TinderboxPrint: CPU idle
2,253.0 (46.5%) 08:29:42 INFO - TinderboxPrint: CPU system
966.5 (19.9%) 08:29:42 INFO - TinderboxPrint: CPU user
1,626.1 (33.6%) 08:29:42 INFO - TinderboxPrint: Swap in / out
400,568,320 / 0 08:29:42 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349723136; Write bytes: 351520768; Read time: 26792; Write time: 726 08:29:42 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 34292736; Write bytes: 302553600; Read time: 211; Write time: 1138 08:29:43 INFO - Running post-run listener: _upload_blobber_files 08:29:43 INFO - Blob upload gear active. 08:29:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:43 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 08:29:43 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:29:43 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:29:43 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:29:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:29:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:44 INFO - (blobuploader) - INFO - Done attempting. 08:29:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:29:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:45 INFO - (blobuploader) - INFO - Done attempting. 08:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:29:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:47 INFO - (blobuploader) - INFO - Done attempting. 08:29:47 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:47 INFO - Return code: 0 08:29:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:29:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4915fdff5d1ca2252603e639dbe6e8174958ff0f418fbd27a1824ba23fccc884c828824c31e548498dcb01a26bf782bc76774b2ac76f2ade665074280f5695e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e099009c150bfdd9c1d266259d3c10a0d28a188dfbe6954dd31199bf15e9487a643260fbac03cab99ac825593e107fad1f9c293b7d50294dc8c08b51d1ee2103", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd321917e492c54f6b54cd73dc6c526a40b1885bb7704379b75118a7de18a236456074005aec3978a92b2977c0a34baebc12d5e7b99dd948d09783dd64913bdf"} 08:29:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:29:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:29:47 INFO - Contents: 08:29:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4915fdff5d1ca2252603e639dbe6e8174958ff0f418fbd27a1824ba23fccc884c828824c31e548498dcb01a26bf782bc76774b2ac76f2ade665074280f5695e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e099009c150bfdd9c1d266259d3c10a0d28a188dfbe6954dd31199bf15e9487a643260fbac03cab99ac825593e107fad1f9c293b7d50294dc8c08b51d1ee2103", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd321917e492c54f6b54cd73dc6c526a40b1885bb7704379b75118a7de18a236456074005aec3978a92b2977c0a34baebc12d5e7b99dd948d09783dd64913bdf"} 08:29:47 INFO - Running post-run listener: copy_logs_to_upload_dir 08:29:47 INFO - Copying logs to upload dir... 08:29:47 INFO - mkdir: /builds/slave/test/build/upload/logs 08:29:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.564844 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-13 08:29:47.925487) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 08:29:47.929369) ========= 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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4915fdff5d1ca2252603e639dbe6e8174958ff0f418fbd27a1824ba23fccc884c828824c31e548498dcb01a26bf782bc76774b2ac76f2ade665074280f5695e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e099009c150bfdd9c1d266259d3c10a0d28a188dfbe6954dd31199bf15e9487a643260fbac03cab99ac825593e107fad1f9c293b7d50294dc8c08b51d1ee2103", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd321917e492c54f6b54cd73dc6c526a40b1885bb7704379b75118a7de18a236456074005aec3978a92b2977c0a34baebc12d5e7b99dd948d09783dd64913bdf"} build_url:https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010955 build_url: 'https://queue.taskcluster.net/v1/task/R-mHJRpiTmaXLMW1nRumKg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4915fdff5d1ca2252603e639dbe6e8174958ff0f418fbd27a1824ba23fccc884c828824c31e548498dcb01a26bf782bc76774b2ac76f2ade665074280f5695e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e099009c150bfdd9c1d266259d3c10a0d28a188dfbe6954dd31199bf15e9487a643260fbac03cab99ac825593e107fad1f9c293b7d50294dc8c08b51d1ee2103", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd321917e492c54f6b54cd73dc6c526a40b1885bb7704379b75118a7de18a236456074005aec3978a92b2977c0a34baebc12d5e7b99dd948d09783dd64913bdf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 08:29:47.963367) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:29:47.963695) ========= 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.5wwI2UeBpV/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.9uduzQn1Z9/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005094 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:29:48.012847) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 08:29:48.013187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 08:29:48.013540) ========= ========= Total master_lag: 0.18 =========