builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0356 starttime: 1481988246.19 results: success (0) buildid: 20161217062449 builduid: 4b33c635732b403aaf02a762ff61caa0 revision: eb41245ead336aeba73bc628e413e7b55f40eddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.188301) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.188739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.189029) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.253160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.253492) ========= 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.w6sjZPrPML/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.KVy9eQiW8O/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-17 07:24:06-- 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% 151M=0s 2016-12-17 07:24:06 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.583550 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.861038) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.861338) ========= 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.w6sjZPrPML/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.KVy9eQiW8O/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.019832 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:06.909495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 07:24:06.909816) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev eb41245ead336aeba73bc628e413e7b55f40eddd --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 eb41245ead336aeba73bc628e413e7b55f40eddd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w6sjZPrPML/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.KVy9eQiW8O/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-17 07:24:06,990 truncating revision to first 12 chars 2016-12-17 07:24:06,990 Setting DEBUG logging. 2016-12-17 07:24:06,990 attempt 1/10 2016-12-17 07:24:06,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/eb41245ead33?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 07:24:08,158 unpacking tar archive at: mozilla-aurora-eb41245ead33/testing/mozharness/ program finished with exit code 0 elapsedTime=1.415061 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 07:24:08.346633) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:08.346993) ========= script_repo_revision: eb41245ead336aeba73bc628e413e7b55f40eddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:08.347400) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:08.347671) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 07:24:08.365772) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-17 07:24:08.366057) ========= /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.w6sjZPrPML/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.KVy9eQiW8O/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:08 INFO - MultiFileLogger online at 20161217 07:24:08 in /builds/slave/test 07:24:08 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 07:24:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:24:08 INFO - {'append_to_log': False, 07:24:08 INFO - 'base_work_dir': '/builds/slave/test', 07:24:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:24:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:24:08 INFO - 'branch': 'Mozilla-Aurora', 07:24:08 INFO - 'buildbot_json_path': 'buildprops.json', 07:24:08 INFO - 'config_files': ('talos/mac_config.py',), 07:24:08 INFO - 'default_actions': ('clobber', 07:24:08 INFO - 'read-buildbot-config', 07:24:08 INFO - 'download-and-extract', 07:24:08 INFO - 'populate-webroot', 07:24:08 INFO - 'create-virtualenv', 07:24:08 INFO - 'install', 07:24:08 INFO - 'run-tests'), 07:24:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:24:08 INFO - 'download_minidump_stackwalk': True, 07:24:08 INFO - 'download_symbols': 'ondemand', 07:24:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:24:08 INFO - 'tooltool.py': '/tools/tooltool.py', 07:24:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:24:08 INFO - '/tools/misc-python/virtualenv.py')}, 07:24:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:24:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:24:08 INFO - 'installer_path': 'installer.exe', 07:24:08 INFO - 'log_level': 'info', 07:24:08 INFO - 'log_name': 'talos', 07:24:08 INFO - 'log_to_console': True, 07:24:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:24:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:24:08 INFO - 'opt_config_files': (), 07:24:08 INFO - 'pip_index': False, 07:24:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:08 INFO - 'cmd': ('bash', 07:24:08 INFO - '-c', 07:24:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:08 INFO - 'enabled': True, 07:24:08 INFO - 'halt_on_failure': False, 07:24:08 INFO - 'name': 'check_screen_resolution'},), 07:24:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:08 INFO - 'cmd': ('bash', 07:24:08 INFO - '-c', 07:24:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:08 INFO - 'enabled': True, 07:24:08 INFO - 'halt_on_failure': False, 07:24:08 INFO - 'name': 'check_screen_resolution'},), 07:24:08 INFO - 'run_cmd_checks_enabled': True, 07:24:08 INFO - 'sps_profile': False, 07:24:08 INFO - 'sps_profile_interval': 0, 07:24:08 INFO - 'suite': 'dromaeojs', 07:24:08 INFO - 'system_bits': '32', 07:24:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:24:08 INFO - 'title': 't-yosemite-r7-0356', 07:24:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:24:08 INFO - 'use_talos_json': True, 07:24:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:24:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:24:08 INFO - 'work_dir': 'build'} 07:24:08 INFO - [mozharness: 2016-12-17 15:24:08.554430Z] Running clobber step. 07:24:08 INFO - Running pre-action listener: _resource_record_pre_action 07:24:08 INFO - Running main action method: clobber 07:24:08 INFO - rmtree: /builds/slave/test/build 07:24:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:24:10 INFO - Running post-action listener: _resource_record_post_action 07:24:10 INFO - [mozharness: 2016-12-17 15:24:10.780728Z] Finished clobber step (success) 07:24:10 INFO - [mozharness: 2016-12-17 15:24:10.780892Z] Running read-buildbot-config step. 07:24:10 INFO - Running pre-action listener: _resource_record_pre_action 07:24:10 INFO - Running main action method: read_buildbot_config 07:24:10 INFO - Using buildbot properties: 07:24:10 INFO - { 07:24:10 INFO - "project": "", 07:24:10 INFO - "product": "firefox", 07:24:10 INFO - "script_repo_revision": "production", 07:24:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:24:10 INFO - "repository": "", 07:24:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:24:10 INFO - "buildid": "20161217062449", 07:24:10 INFO - "pgo_build": "False", 07:24:10 INFO - "basedir": "/builds/slave/test", 07:24:10 INFO - "buildnumber": 24, 07:24:10 INFO - "slavename": "t-yosemite-r7-0356", 07:24:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:24:10 INFO - "platform": "yosemite_r7", 07:24:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:24:10 INFO - "branch": "mozilla-aurora", 07:24:10 INFO - "repo_path": "releases/mozilla-aurora", 07:24:10 INFO - "revision": "eb41245ead336aeba73bc628e413e7b55f40eddd", 07:24:10 INFO - "stage_platform": "macosx64", 07:24:10 INFO - "builduid": "4b33c635732b403aaf02a762ff61caa0", 07:24:10 INFO - "slavebuilddir": "test" 07:24:10 INFO - } 07:24:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:24:10 INFO - Running post-action listener: _resource_record_post_action 07:24:10 INFO - [mozharness: 2016-12-17 15:24:10.784161Z] Finished read-buildbot-config step (success) 07:24:10 INFO - [mozharness: 2016-12-17 15:24:10.784260Z] Running download-and-extract step. 07:24:10 INFO - Running pre-action listener: _resource_record_pre_action 07:24:10 INFO - Running main action method: download_and_extract 07:24:10 INFO - mkdir: /builds/slave/test/build/tests 07:24:10 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')]} 07:24:10 INFO - trying https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:24:10 INFO - Downloading https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/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 07:24:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/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 07:24:11 INFO - Downloaded 1199 bytes. 07:24:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:24:11 INFO - Using the following test package requirements: 07:24:11 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:24:11 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:24:11 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:24:11 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'jsshell-mac.zip'], 07:24:11 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:24:11 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:24:11 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:24:11 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:24:11 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:24:11 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:24:11 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:24:11 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:24:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:24:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:24:11 INFO - Fetch https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:24:12 INFO - Http code: 200 07:24:12 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 07:24:12 INFO - x-amz-cf-id: ZMQ5iqyreJy_YAvBvFaIXbFQWnCZSqEzxPfiZovvbOm3SoZ1_6K6mQ== 07:24:12 INFO - x-amz-version-id: WIRccO1n9Ew3PWhHl6g3rItm5AP4JElt 07:24:12 INFO - Expected file size: 16280369 07:24:12 INFO - Obtained file size: 16280369 07:24:19 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 07:24:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:24:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:24:19 INFO - Fetch https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:24:19 INFO - Http code: 200 07:24:19 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 07:24:19 INFO - x-amz-cf-id: YWnL6Tqas3D6vuczf6wm7ij5lphZmhz2aITiRX3-4TbgsINtpSBK4Q== 07:24:19 INFO - x-amz-version-id: WIRccO1n9Ew3PWhHl6g3rItm5AP4JElt 07:24:20 INFO - Expected file size: 16280369 07:24:20 INFO - Obtained file size: 16280369 07:24:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:24:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:24:26 INFO - Fetch https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:24:26 INFO - Http code: 200 07:24:26 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 07:24:26 INFO - x-amz-cf-id: o6orj4Plf286hCZ8BJw7_yFBlln1AC_I0m6XHyzGgFi8e926rhEsJQ== 07:24:26 INFO - x-amz-version-id: lnnUpDRc.aJmNdDpKrz2FciWYFBszTvS 07:24:27 INFO - Expected file size: 14409038 07:24:27 INFO - Obtained file size: 14409038 07:24:27 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')]} 07:24:27 INFO - trying https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:24:27 INFO - Downloading https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:24:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/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 07:24:30 INFO - Downloaded 109660951 bytes. 07:24:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:24:30 INFO - mkdir: /builds/slave/test/properties 07:24:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:24:30 INFO - Writing to file /builds/slave/test/properties/build_url 07:24:30 INFO - Contents: 07:24:30 INFO - build_url:https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:24:30 INFO - Running post-action listener: _resource_record_post_action 07:24:30 INFO - Running post-action listener: set_extra_try_arguments 07:24:30 INFO - [mozharness: 2016-12-17 15:24:30.995127Z] Finished download-and-extract step (success) 07:24:30 INFO - [mozharness: 2016-12-17 15:24:30.995230Z] Running populate-webroot step. 07:24:30 INFO - Running pre-action listener: _resource_record_pre_action 07:24:30 INFO - Running main action method: populate_webroot 07:24:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:24:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:24:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:24:31 INFO - '--tppagecycles', 07:24:31 INFO - '7'], 07:24:31 INFO - 'tests': ['tsvgm']}}, 07:24:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:24:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:24:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:24:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:24:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['damp', 'tps']}, 07:24:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'tests': ['damp', 'tps']}, 07:24:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['dromaeo_dom']}, 07:24:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:24:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['basic_compositor_video']}, 07:24:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:24:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['a11yr', 07:24:31 INFO - 'ts_paint', 07:24:31 INFO - 'tpaint', 07:24:31 INFO - 'sessionrestore', 07:24:31 INFO - 'sessionrestore_no_auto_restore', 07:24:31 INFO - 'tabpaint']}, 07:24:31 INFO - 'other-e10s': {'tests': ['a11yr', 07:24:31 INFO - 'ts_paint', 07:24:31 INFO - 'tpaint', 07:24:31 INFO - 'sessionrestore', 07:24:31 INFO - 'sessionrestore_no_auto_restore', 07:24:31 INFO - 'tabpaint']}, 07:24:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:24:31 INFO - 'ts_paint', 07:24:31 INFO - 'tpaint', 07:24:31 INFO - 'sessionrestore', 07:24:31 INFO - 'sessionrestore_no_auto_restore', 07:24:31 INFO - 'tabpaint']}, 07:24:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:24:31 INFO - 'ts_paint', 07:24:31 INFO - 'tpaint', 07:24:31 INFO - 'sessionrestore', 07:24:31 INFO - 'sessionrestore_no_auto_restore', 07:24:31 INFO - 'tabpaint']}, 07:24:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['a11yr', 07:24:31 INFO - 'ts_paint', 07:24:31 INFO - 'tpaint', 07:24:31 INFO - 'sessionrestore', 07:24:31 INFO - 'sessionrestore_no_auto_restore', 07:24:31 INFO - 'tabpaint']}, 07:24:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['a11yr', 07:24:31 INFO - 'ts_paint', 07:24:31 INFO - 'tpaint', 07:24:31 INFO - 'sessionrestore', 07:24:31 INFO - 'sessionrestore_no_auto_restore', 07:24:31 INFO - 'tabpaint']}, 07:24:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['tsvgx', 07:24:31 INFO - 'tsvgr_opacity', 07:24:31 INFO - 'tart', 07:24:31 INFO - 'tscrollx', 07:24:31 INFO - 'cart']}, 07:24:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:24:31 INFO - 'tsvgr_opacity', 07:24:31 INFO - 'tart', 07:24:31 INFO - 'tscrollx', 07:24:31 INFO - 'cart']}, 07:24:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'talos_options': ['--disable-e10s'], 07:24:31 INFO - 'tests': ['tp5o']}, 07:24:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'tests': ['tp5o']}, 07:24:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'talos_options': ['--disable-e10s', 07:24:31 INFO - '--xperf_path', 07:24:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:31 INFO - 'tests': ['tp5n']}, 07:24:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:31 INFO - 'talos_options': ['--xperf_path', 07:24:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:31 INFO - 'tests': ['tp5n']}}, 07:24:31 INFO - 'talos.zip': {'path': '', 07:24:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:24:31 INFO - Running post-action listener: _resource_record_post_action 07:24:31 INFO - [mozharness: 2016-12-17 15:24:31.014143Z] Finished populate-webroot step (success) 07:24:31 INFO - [mozharness: 2016-12-17 15:24:31.014253Z] Running create-virtualenv step. 07:24:31 INFO - Running pre-action listener: _resource_record_pre_action 07:24:31 INFO - Running main action method: create_virtualenv 07:24:31 INFO - Creating virtualenv /builds/slave/test/build/venv 07:24:31 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 07:24:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:24:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:24:31 INFO - Using real prefix '/tools/python27' 07:24:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:24:31 INFO - Installing distribute.............................................................................................................................................................................................done. 07:24:34 INFO - Installing pip.................done. 07:24:34 INFO - Return code: 0 07:24:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:24:34 INFO - Reading from file tmpfile_stdout 07:24:34 INFO - Output received: 07:24:34 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) 07:24:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:24: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')]} 07:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24: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')]} 07:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:34 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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:34 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 07:24:34 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 07:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:34 INFO - 'HOME': '/Users/cltbld', 07:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:34 INFO - 'LOGNAME': 'cltbld', 07:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:34 INFO - 'MOZ_NO_REMOTE': '1', 07:24:34 INFO - 'NO_EM_RESTART': '1', 07:24:34 INFO - 'PAGER': '/bin/cat', 07:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:34 INFO - 'PWD': '/builds/slave/test', 07:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:34 INFO - 'SHELL': '/bin/bash', 07:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:34 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:34 INFO - 'USER': 'cltbld', 07:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:34 INFO - 'XPC_FLAGS': '0x0', 07:24:34 INFO - 'XPC_SERVICE_NAME': '0', 07:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:35 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 07:24:35 INFO - Cleaning up... 07:24:35 INFO - Return code: 0 07:24:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:24:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:35 INFO - 'HOME': '/Users/cltbld', 07:24:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:35 INFO - 'LOGNAME': 'cltbld', 07:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:35 INFO - 'MOZ_NO_REMOTE': '1', 07:24:35 INFO - 'NO_EM_RESTART': '1', 07:24:35 INFO - 'PAGER': '/bin/cat', 07:24:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:35 INFO - 'PWD': '/builds/slave/test', 07:24:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:35 INFO - 'SHELL': '/bin/bash', 07:24:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:35 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:35 INFO - 'USER': 'cltbld', 07:24:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:35 INFO - 'XPC_FLAGS': '0x0', 07:24:35 INFO - 'XPC_SERVICE_NAME': '0', 07:24:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:35 INFO - Downloading/unpacking psutil>=3.1.1 07:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:24:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:39 INFO - Installing collected packages: psutil 07:24:39 INFO - Running setup.py install for psutil 07:24:39 INFO - building 'psutil._psutil_osx' extension 07:24:39 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 07:24:39 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 07:24:39 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 07:24:39 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 07:24:39 INFO - building 'psutil._psutil_posix' extension 07:24:39 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 07:24:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:24:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:24:40 INFO - ^ 07:24:40 INFO - 1 warning generated. 07:24:40 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 07:24:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:40 INFO - Successfully installed psutil 07:24:40 INFO - Cleaning up... 07:24:40 INFO - Return code: 0 07:24:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:24:40 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')]} 07:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:40 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')]} 07:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:40 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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:40 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 07:24:40 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 07:24:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:40 INFO - 'HOME': '/Users/cltbld', 07:24:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:40 INFO - 'LOGNAME': 'cltbld', 07:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:40 INFO - 'MOZ_NO_REMOTE': '1', 07:24:40 INFO - 'NO_EM_RESTART': '1', 07:24:40 INFO - 'PAGER': '/bin/cat', 07:24:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:40 INFO - 'PWD': '/builds/slave/test', 07:24:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:40 INFO - 'SHELL': '/bin/bash', 07:24:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:40 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:40 INFO - 'USER': 'cltbld', 07:24:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:40 INFO - 'XPC_FLAGS': '0x0', 07:24:40 INFO - 'XPC_SERVICE_NAME': '0', 07:24:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:24:40 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 07:24:40 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 07:24:40 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 07:24:40 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 07:24:40 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 07:24:40 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 07:24:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:24:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:24:43 INFO - Installing collected packages: mozsystemmonitor 07:24:43 INFO - Running setup.py install for mozsystemmonitor 07:24:43 INFO - Successfully installed mozsystemmonitor 07:24:43 INFO - Cleaning up... 07:24:43 INFO - Return code: 0 07:24:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:24:43 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')]} 07:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:43 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')]} 07:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:43 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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:43 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 07:24:43 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 07:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:43 INFO - 'HOME': '/Users/cltbld', 07:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:43 INFO - 'LOGNAME': 'cltbld', 07:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:43 INFO - 'MOZ_NO_REMOTE': '1', 07:24:43 INFO - 'NO_EM_RESTART': '1', 07:24:43 INFO - 'PAGER': '/bin/cat', 07:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:43 INFO - 'PWD': '/builds/slave/test', 07:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:43 INFO - 'SHELL': '/bin/bash', 07:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:43 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:43 INFO - 'USER': 'cltbld', 07:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:43 INFO - 'XPC_FLAGS': '0x0', 07:24:43 INFO - 'XPC_SERVICE_NAME': '0', 07:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:43 INFO - Downloading/unpacking jsonschema==2.5.1 07:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:24:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:46 INFO - Downloading functools32-3.2.3-2.tar.gz 07:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:24:46 INFO - warning: no files found matching '*.txt' 07:24:46 INFO - no previously-included directories found matching 'build' 07:24:46 INFO - no previously-included directories found matching 'dist' 07:24:46 INFO - no previously-included directories found matching '.git*' 07:24:46 INFO - Installing collected packages: jsonschema, functools32 07:24:46 INFO - Running setup.py install for functools32 07:24:46 INFO - warning: no files found matching '*.txt' 07:24:46 INFO - no previously-included directories found matching 'build' 07:24:46 INFO - no previously-included directories found matching 'dist' 07:24:46 INFO - no previously-included directories found matching '.git*' 07:24:46 INFO - Successfully installed jsonschema functools32 07:24:46 INFO - Cleaning up... 07:24:46 INFO - Return code: 0 07:24:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:24:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:46 INFO - 'HOME': '/Users/cltbld', 07:24:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:46 INFO - 'LOGNAME': 'cltbld', 07:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:46 INFO - 'MOZ_NO_REMOTE': '1', 07:24:46 INFO - 'NO_EM_RESTART': '1', 07:24:46 INFO - 'PAGER': '/bin/cat', 07:24:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:46 INFO - 'PWD': '/builds/slave/test', 07:24:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:46 INFO - 'SHELL': '/bin/bash', 07:24:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:46 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:46 INFO - 'USER': 'cltbld', 07:24:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:46 INFO - 'XPC_FLAGS': '0x0', 07:24:46 INFO - 'XPC_SERVICE_NAME': '0', 07:24:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:24:47 INFO - Cleaning up... 07:24:47 INFO - Return code: 0 07:24:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:47 INFO - 'HOME': '/Users/cltbld', 07:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:47 INFO - 'LOGNAME': 'cltbld', 07:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:47 INFO - 'MOZ_NO_REMOTE': '1', 07:24:47 INFO - 'NO_EM_RESTART': '1', 07:24:47 INFO - 'PAGER': '/bin/cat', 07:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:47 INFO - 'PWD': '/builds/slave/test', 07:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:47 INFO - 'SHELL': '/bin/bash', 07:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:47 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:47 INFO - 'USER': 'cltbld', 07:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:47 INFO - 'XPC_FLAGS': '0x0', 07:24:47 INFO - 'XPC_SERVICE_NAME': '0', 07:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:47 INFO - Downloading/unpacking blobuploader==1.2.4 07:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:50 INFO - Downloading blobuploader-1.2.4.tar.gz 07:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:24:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:24:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:51 INFO - Downloading docopt-0.6.1.tar.gz 07:24:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:24:51 INFO - Installing collected packages: blobuploader, requests, docopt 07:24:51 INFO - Running setup.py install for blobuploader 07:24:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:24:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:24:51 INFO - Running setup.py install for requests 07:24:52 INFO - Running setup.py install for docopt 07:24:52 INFO - Successfully installed blobuploader requests docopt 07:24:52 INFO - Cleaning up... 07:24:52 INFO - Return code: 0 07:24:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:52 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 07:24:52 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 07:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:52 INFO - 'HOME': '/Users/cltbld', 07:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:52 INFO - 'LOGNAME': 'cltbld', 07:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:52 INFO - 'MOZ_NO_REMOTE': '1', 07:24:52 INFO - 'NO_EM_RESTART': '1', 07:24:52 INFO - 'PAGER': '/bin/cat', 07:24:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:52 INFO - 'PWD': '/builds/slave/test', 07:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:52 INFO - 'SHELL': '/bin/bash', 07:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:52 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:52 INFO - 'USER': 'cltbld', 07:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:52 INFO - 'XPC_FLAGS': '0x0', 07:24:52 INFO - 'XPC_SERVICE_NAME': '0', 07:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:24:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-R59wnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:24:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-tDD4w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:24:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-P98hOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:24:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-iuv70p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:24:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-GsdpDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-PGhF2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-87YWmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-wwvPKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-QRV0Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-nTl1qA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ptF9cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xdygCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Gr3U5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:24:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-PQQ38n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-TGDL_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:24:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-m1OBAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:24:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Rx3Z33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:24:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:24:54 INFO - Running setup.py install for manifestparser 07:24:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:24:54 INFO - Running setup.py install for mozcrash 07:24:54 INFO - Running setup.py install for mozdebug 07:24:54 INFO - Running setup.py install for mozdevice 07:24:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:24:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:24:54 INFO - Running setup.py install for mozfile 07:24:55 INFO - Running setup.py install for mozhttpd 07:24:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Running setup.py install for mozinfo 07:24:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Running setup.py install for mozInstall 07:24:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Running setup.py install for mozleak 07:24:55 INFO - Running setup.py install for mozlog 07:24:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Running setup.py install for moznetwork 07:24:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:24:55 INFO - Running setup.py install for mozprocess 07:24:56 INFO - Running setup.py install for mozprofile 07:24:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:24:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:24:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:24:56 INFO - Running setup.py install for mozrunner 07:24:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:24:56 INFO - Running setup.py install for mozscreenshot 07:24:56 INFO - Running setup.py install for moztest 07:24:56 INFO - Running setup.py install for mozversion 07:24:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:24:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:24:56 INFO - Cleaning up... 07:24:56 INFO - Return code: 0 07:24:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:24:56 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')]} 07:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:56 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')]} 07:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:56 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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:24:56 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 07:24:56 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 07:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:56 INFO - 'HOME': '/Users/cltbld', 07:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:56 INFO - 'LOGNAME': 'cltbld', 07:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:56 INFO - 'MOZ_NO_REMOTE': '1', 07:24:56 INFO - 'NO_EM_RESTART': '1', 07:24:56 INFO - 'PAGER': '/bin/cat', 07:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:56 INFO - 'PWD': '/builds/slave/test', 07:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:56 INFO - 'SHELL': '/bin/bash', 07:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:24:56 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:56 INFO - 'USER': 'cltbld', 07:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:56 INFO - 'XPC_FLAGS': '0x0', 07:24:56 INFO - 'XPC_SERVICE_NAME': '0', 07:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-r69gDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:24:57 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)) 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-O3Ayeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:24:57 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)) 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-7k_Y3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:24:57 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)) 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-gxaN1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:24:57 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)) 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-HHI32z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:24:57 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)) 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-VwgKm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:24:57 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)) 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-w_qqjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:24:57 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)) 07:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:24:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-sDAeCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-AKZrjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xljufd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-hkOuT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-MrhqUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-mV2AuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ynBG3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ZMSZkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-dGBOY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:24:58 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)) 07:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:24:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-35QGr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:24:59 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)) 07:24:59 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)) 07:24:59 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)) 07:24:59 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)) 07:24:59 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)) 07:24:59 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)) 07:24:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:24:59 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 07:24:59 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 07:24:59 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 07:24:59 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 07:24:59 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 07:24:59 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 07:25:01 INFO - Downloading blessings-1.6.tar.gz 07:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:25:01 INFO - Installing collected packages: blessings 07:25:01 INFO - Running setup.py install for blessings 07:25:01 INFO - Successfully installed blessings 07:25:01 INFO - Cleaning up... 07:25:01 INFO - Return code: 0 07:25:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:02 INFO - Reading from file tmpfile_stdout 07:25:02 INFO - Current package versions: 07:25:02 INFO - blessings == 1.6 07:25:02 INFO - blobuploader == 1.2.4 07:25:02 INFO - docopt == 0.6.1 07:25:02 INFO - functools32 == 3.2.3-2 07:25:02 INFO - jsonschema == 2.5.1 07:25:02 INFO - manifestparser == 1.1 07:25:02 INFO - mozInstall == 1.12 07:25:02 INFO - mozcrash == 1.0 07:25:02 INFO - mozdebug == 0.1 07:25:02 INFO - mozdevice == 0.48 07:25:02 INFO - mozfile == 1.2 07:25:02 INFO - mozhttpd == 0.7 07:25:02 INFO - mozinfo == 0.9 07:25:02 INFO - mozleak == 0.1 07:25:02 INFO - mozlog == 3.4 07:25:02 INFO - moznetwork == 0.27 07:25:02 INFO - mozprocess == 0.23 07:25:02 INFO - mozprofile == 0.28 07:25:02 INFO - mozrunner == 6.13 07:25:02 INFO - mozscreenshot == 0.1 07:25:02 INFO - mozsystemmonitor == 0.3 07:25:02 INFO - moztest == 0.7 07:25:02 INFO - mozversion == 1.4 07:25:02 INFO - psutil == 3.1.1 07:25:02 INFO - requests == 1.2.3 07:25:02 INFO - wsgiref == 0.1.2 07:25:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:02 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')]} 07:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:02 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')]} 07:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:02 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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:25:02 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 07:25:02 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 07:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:02 INFO - 'HOME': '/Users/cltbld', 07:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:02 INFO - 'LOGNAME': 'cltbld', 07:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:02 INFO - 'MOZ_NO_REMOTE': '1', 07:25:02 INFO - 'NO_EM_RESTART': '1', 07:25:02 INFO - 'PAGER': '/bin/cat', 07:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:02 INFO - 'PWD': '/builds/slave/test', 07:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:02 INFO - 'SHELL': '/bin/bash', 07:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:25:02 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:02 INFO - 'USER': 'cltbld', 07:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:02 INFO - 'XPC_FLAGS': '0x0', 07:25:02 INFO - 'XPC_SERVICE_NAME': '0', 07:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 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)) 07:25:02 INFO - Cleaning up... 07:25:02 INFO - Return code: 0 07:25:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:25:02 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')]} 07:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:02 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')]} 07:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:02 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 0x10f4b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab61ccd750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, '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.w6sjZPrPML/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.KVy9eQiW8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 07:25:02 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 07:25:02 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 07:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:02 INFO - 'HOME': '/Users/cltbld', 07:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:02 INFO - 'LOGNAME': 'cltbld', 07:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:02 INFO - 'MOZ_NO_REMOTE': '1', 07:25:02 INFO - 'NO_EM_RESTART': '1', 07:25:02 INFO - 'PAGER': '/bin/cat', 07:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:02 INFO - 'PWD': '/builds/slave/test', 07:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:02 INFO - 'SHELL': '/bin/bash', 07:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:25:02 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:02 INFO - 'USER': 'cltbld', 07:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:02 INFO - 'XPC_FLAGS': '0x0', 07:25:02 INFO - 'XPC_SERVICE_NAME': '0', 07:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:25:02 INFO - Cleaning up... 07:25:02 INFO - Return code: 0 07:25:02 INFO - Running post-action listener: _resource_record_post_action 07:25:02 INFO - Running post-action listener: _start_resource_monitoring 07:25:02 INFO - Starting resource monitoring. 07:25:02 INFO - [mozharness: 2016-12-17 15:25:02.708493Z] Finished create-virtualenv step (success) 07:25:02 INFO - [mozharness: 2016-12-17 15:25:02.709520Z] Running install step. 07:25:02 INFO - Running pre-action listener: _resource_record_pre_action 07:25:02 INFO - Running main action method: install 07:25:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:02 INFO - Reading from file tmpfile_stdout 07:25:02 INFO - Detecting whether we're running mozinstall >=1.0... 07:25:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:25:03 INFO - Reading from file tmpfile_stdout 07:25:03 INFO - Output received: 07:25:03 INFO - Usage: mozinstall [options] installer 07:25:03 INFO - Options: 07:25:03 INFO - -h, --help show this help message and exit 07:25:03 INFO - -d DEST, --destination=DEST 07:25:03 INFO - Directory to install application into. [default: 07:25:03 INFO - "/builds/slave/test"] 07:25:03 INFO - --app=APP Application being installed. [default: firefox] 07:25:03 INFO - mkdir: /builds/slave/test/build/application 07:25:03 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'] 07:25:03 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 07:25:33 INFO - Reading from file tmpfile_stdout 07:25:33 INFO - Output received: 07:25:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:25:33 INFO - Running post-action listener: _resource_record_post_action 07:25:33 INFO - [mozharness: 2016-12-17 15:25:33.883917Z] Finished install step (success) 07:25:33 INFO - [mozharness: 2016-12-17 15:25:33.884038Z] Running run-tests step. 07:25:33 INFO - Running pre-action listener: _resource_record_pre_action 07:25:33 INFO - Running main action method: run_tests 07:25:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:25:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:25:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:25:33 INFO - 2016-12-17 07:25:33.898 screenresolution[2226:17243] starting screenresolution argv=screenresolution get 07:25:33 INFO - 2016-12-17 07:25:33.901 screenresolution[2226:17243] Display 0: 1600x1200x32@60 07:25:33 INFO - 2016-12-17 07:25:33.907 screenresolution[2227:17245] starting screenresolution argv=screenresolution list 07:25:33 INFO - Available Modes on Display 0 07:25:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:25:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:25:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:25:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:25:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:25:34 INFO - Intel Iris: 07:25:34 INFO - Chipset Model: Intel Iris 07:25:34 INFO - Type: GPU 07:25:34 INFO - Bus: Built-In 07:25:34 INFO - VRAM (Dynamic, Max): 1536 MB 07:25:34 INFO - Vendor: Intel (0x8086) 07:25:34 INFO - Device ID: 0x0a2e 07:25:34 INFO - Revision ID: 0x0009 07:25:34 INFO - Displays: 07:25:34 INFO - Display: 07:25:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:25:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:25:34 INFO - Display Serial Number: 1600x1200 60 07:25:34 INFO - Main Display: Yes 07:25:34 INFO - Mirror: Off 07:25:34 INFO - Online: Yes 07:25:34 INFO - Rotation: Supported 07:25:34 INFO - Return code: 0 07:25:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:25:34 INFO - Python 2.7.3 07:25:34 INFO - Return code: 0 07:25:34 INFO - grabbing minidump binary from tooltool 07:25: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')]} 07:25:34 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 0x10f2c8d30>, '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 0x10f12edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f2c0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:25:34 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 07:25:34 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 07:25:34 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 07:25:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:25:34 INFO - Return code: 0 07:25:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:25:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:25:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:25:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:25:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:25:34 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/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--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 07:25:34 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/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0356 --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 07:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6sjZPrPML/Render', 07:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:34 INFO - 'HOME': '/Users/cltbld', 07:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:34 INFO - 'LOGNAME': 'cltbld', 07:25:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:25:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:34 INFO - 'MOZ_NO_REMOTE': '1', 07:25:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:25:34 INFO - 'NO_EM_RESTART': '1', 07:25:34 INFO - 'PAGER': '/bin/cat', 07:25:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:34 INFO - 'PWD': '/builds/slave/test', 07:25:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:34 INFO - 'SHELL': '/bin/bash', 07:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVy9eQiW8O/Listeners', 07:25:34 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 07:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:34 INFO - 'USER': 'cltbld', 07:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:34 INFO - 'XPC_FLAGS': '0x0', 07:25:34 INFO - 'XPC_SERVICE_NAME': '0', 07:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:34 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/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--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 07:25:34 INFO - mozversion application_buildid: 20161217062449 07:25:34 INFO - mozversion application_changeset: eb41245ead336aeba73bc628e413e7b55f40eddd 07:25:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:25:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:25:34 INFO - mozversion application_name: Firefox 07:25:34 INFO - mozversion application_remotingname: firefox-dev 07:25:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:25:34 INFO - mozversion application_vendor: Mozilla 07:25:34 INFO - mozversion application_version: 52.0a2 07:25:34 INFO - mozversion platform_buildid: 20161217062449 07:25:34 INFO - mozversion platform_changeset: eb41245ead336aeba73bc628e413e7b55f40eddd 07:25:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:25:34 INFO - mozversion platform_version: 52.0a2 07:25:34 INFO - using testdate: 1481988334 07:25:34 INFO - actual date: 1481988334 07:25:34 INFO - starting webserver on 'localhost:51677' 07:25:34 INFO - SUITE-START | Running 2 tests 07:25:34 INFO - TEST-START | dromaeo_css 07:25:34 INFO - operating with platform_type : mac_ 07:25:34 INFO - Initialising browser for dromaeo_css test... 07:25:34 INFO - TEST-INFO | started process 2237 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpxvPIMx/profile http://localhost:51677/getInfo.html) 07:25:36 INFO - PROCESS | 2237 | __metrics Screen width/height:1600/1200 07:25:36 INFO - PROCESS | 2237 | colorDepth:24 07:25:36 INFO - PROCESS | 2237 | Browser inner width/height: 1024/689 07:25:36 INFO - PROCESS | 2237 | __metrics 07:25:37 INFO - PROCESS | 2237 | 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" 07:25:37 INFO - PROCESS | 2237 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:25:38 INFO - TEST-INFO | 2237: exit 0 07:25:38 INFO - Browser initialized. 07:25:38 INFO - Running cycle 1/1 for dromaeo_css test... 07:25:38 INFO - TEST-INFO | started process 2239 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpxvPIMx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:25:50 INFO - PROCESS | 2239 | RSS: Main: 222695424 07:25:50 INFO - PROCESS | 2239 | 07:26:41 INFO - PROCESS | 2239 | 1481988401414 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] 07:28:55 INFO - PROCESS | 2239 | Cycle 1(1): loaded http://localhost:51677/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51677/tests/dromaeo/cssquery-ext.html) 07:28:56 INFO - PROCESS | 2239 | RSS: Main: 359460864 07:28:56 INFO - PROCESS | 2239 | 07:31:59 INFO - PROCESS | 2239 | Cycle 1(1): loaded http://localhost:51677/tests/dromaeo/cssquery-ext.html (next: http://localhost:51677/tests/dromaeo/cssquery-jquery.html) 07:32:00 INFO - PROCESS | 2239 | RSS: Main: 353300480 07:32:00 INFO - PROCESS | 2239 | 07:35:02 INFO - PROCESS | 2239 | Cycle 1(1): loaded http://localhost:51677/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51677/tests/dromaeo/cssquery-mootools.html) 07:35:03 INFO - PROCESS | 2239 | RSS: Main: 322211840 07:35:03 INFO - PROCESS | 2239 | 07:38:05 INFO - PROCESS | 2239 | Cycle 1(1): loaded http://localhost:51677/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51677/tests/dromaeo/cssquery-prototype.html) 07:38:06 INFO - PROCESS | 2239 | RSS: Main: 322641920 07:38:06 INFO - PROCESS | 2239 | 07:41:09 INFO - PROCESS | 2239 | Cycle 1(1): loaded http://localhost:51677/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51677/tests/dromaeo/cssquery-yui.html) 07:41:10 INFO - PROCESS | 2239 | RSS: Main: 310255616 07:41:10 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | Cycle 1(1): loaded http://localhost:51677/tests/dromaeo/cssquery-yui.html (next: http://localhost:51677/tests/dromaeo/cssquery-dojo.html) 07:44:13 INFO - PROCESS | 2239 | RSS: Main: 457498624 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | __start_tp_report 07:44:13 INFO - PROCESS | 2239 | _x_x_mozilla_page_load 07:44:13 INFO - PROCESS | 2239 | _x_x_mozilla_page_load_details 07:44:13 INFO - PROCESS | 2239 | |i|pagename|runs| 07:44:13 INFO - PROCESS | 2239 | |0;dojo.html;2917.3144311086894;3219.839008049597;3239.951400728988;3274.492453669679;3277.623073346566;5513.9448605513935;5570.053090974534;5612.0178968680475;5618.438156184379;5636.633618814774;4587.243104887698;5503.257060296859;5600.915986260199;5611.214429979796;5614.719264036792;5063.063333283345;5088.516590923857;5102.132637451624;5182.948170518303;5198.558122559568;4986;5027.798888044474;5083.18669012958;5084.135696931512;5099.490050994889;5110.540051395374;5121.231815227727;5146.974265128669;5158.200478925773;5181.404138524062;5136.075506408845;5141.640085194057;5148.867249205187;5169.948300517022;5181.119209734316;4962.727050012248;4983.277424773424;5038.697678139323;5076.44159142494;5078.97460512695;5455.809046683386;5486.890262194734;5496.148097044925;5580.051391263495;5597.916031259534;6061.787837425713;6096.116063170815;6113.724882380304;6134;6152.600080994721;6030.517558595346;6187.350328215518;6198.721057552376;6214.5649804513705;6227.06594010902;5549.0289199390345;5664.291963504562;5701.828945131652;5705.942940570624;5712.542996560265;6117.248930028882;6225.28409232935;6228.564000520011;6250;6253.1245625612455;6019.127226552126;6019.879602407927;6031.728572214261;6033.9698301508215;6086.147939288503;5947.613405128653;6048.758049678052;6054.273487181478;6111.35830737775;6116.3883611638485;6038.275406951194;6089.360617135226;6120.357362476876;6142.512282525495;6157.076438534255;6078.574499784972;6127.4485296322655;6138.71087071711;6164.8767024659255;6183.659898705614;4726.314684370747;4758.595519380891;4847.079054979543;4854.053459575349;4862.586680132228;4309.28896732042;4332.090261045153;4338.457692788402;4349.412829268072;4381.3427985801745;5557;5574.386817450078;5582.302212223472;5587.273654424899;5589.357223919302;6095.603785753912;6108.51131909455;6110.205673262448;6114.205153330127;6116;5556.055470570012;5590.664560126405;5592.664440133605;5600.831975040755;5607.775688972477;5254.448282930313;5280.524752772192;5284.180951952453;5286.07493688603;5287.841364758987;6168.35232300602;6214.192155019909;6234.096056071844;6238.906416403848;6248.4688801451475;6131.509479241739;6196.504279657706;6206.317305096345;6209.906851397142;6213.596116252429;6104;6238.812835614939;6256.812295631138;6257.311695713377;6264.060390941213;5904.114382842608;5945.256842894638;5958.433948774859;5962.612430192024;6036.9698151508965;6006.459418652343;6020.127081573235;6111.86523267538;6128.203333566607;6138.754449822136;5822.883542329215;5852.619579727304;5986.2517185351835;5989.161517387656;6009.098635204754;5803.7968671096305;5823.970880145742;5832.9708351457975;5963.254593175853;5972.641641501524;5718.714064296852;5729.341125770589;5747.856303592276;5766.654000759968;5881;5974.850628734142;5993.910091348721;6013.428724271187;6017.217761690952;6029.939700602938;5652.689102099424;5692.743826527899;5712.742926568398;5714.942850571441;5725.427457254408 07:44:13 INFO - PROCESS | 2239 | |1;ext.html;479.55401476626747;501.24562533387245;506.7744853540174;520.3885434614327;539.5333036923063;30596.54105188421;42877.78561107198;43344.566554334444;43395.13209735804;43563.78218108913;42025.15949681005;42337;42930.356044659355;43035.92410189747;43120.353194702024;8363.08006119328;8500.489970601784;8509.191626795475;8520.318374530054;8530.848335474693;9327.113924177193;9431.95284023579;9447.338686291961;9449.480278584675;9459.952700236488;9431.95284023579;9441.29190310726;9449.574769135406;9481.62073517062;9511.904880951171;9398.812023759521;9465.100815422524;9465.858012129824;9475.336726429152;9476.47879366631;9342.299327550461;9415.670451534232;9420.963693993655;9423.199028082621;9448.527573621292;40033.79983100095;40222.59777402218;40250.59749402498;40273;40502.79748601268;3103.953440698391;3149.480335744622;3272.4109660261142;3802.7338086333966;3870.0518372998513;28085.29786755327;29780.255493612618;30518.694813051807;31180.064598062094;32182.517262241086;3076.200187951149;3165.984170079158;3211.1008917503136;3234.628017777962;3252.9186770330693;451146;457575;458613;458691;458691;62237;62673;62827.6858615704;62910;62960.68519657464;34905.30189396198;35619.4657080144;35732.64267357345;36226.456603150975;36326;15953.202339882959;16102.919485402497;16125.516234512983;16127.516174514549;16135.273912673723;12441.440135193938;12512.562060327844;12544.24734515932;12560.120791544687;12576;19155.425337239903;19190.904045479685;19244.71132933007;19267;19278.71081933772;6119.663418511936;6384.457321127757;7521.923606862298;7546.811329716691;7621.961890190624;824.4929368438487;962.1484985787489;967.6419724701906;974.3617930255693;988.614440368257;28473.715262847105;29000.274993125342;29972;30447.695523044928;31042.844785775927;860.4622111180512;880.2913654508054;897.4525539420948;998.0418797953901;1033.4367769565604;13052.608421747364;13966.511172108927;14696.94242806008;15441.691166176613;15709.607259818597;527159;530882;530939;531593;535614.3219283957;58192.709036454544;58798;58808.705956469945;58817.70591147017;58971.82056358849;1126.2341607706837;1227.9017678585692;1266.202292555684;1332.1474256476056;1381.854905234956;29838.55242171326;29943.251418714706;30777.846110769304;30842.228944276572;31355.372892542477;50225.74887125541;50529;50561.4831555054;50569.494305056476;50720;465307;467977;468055;636098;651071;40604.79697601493;40823.79588102159;41228;41335;41430;44050.559494404646;44990.77504612456;45191.548084518734;45658.54341456676;46063.53936460726;29748.85125574445;38107.618923810405;38318.80840595779;38331.616683832806;39336.6066339333;36321.091972700895;37588;38156.61843381642;38195.04512387213;39703.80148099241;31242.062738117893;31263.53104703477;37628;38248.808755956044;39097;32611.34777304488;34070.48894266637;34947.65052349444;35018;35205;25154.868030938287;25526.489470210865;25628.871855640602;25674.71626418709;26267.737322627294 07:44:13 INFO - PROCESS | 2239 | |2;jquery.html;337.84121462912424;349.52639173919323;376.67605858961304;381.1481339206873;403.82635466749286;14638.26808659568;14827.925860370697;15784.999999999985;19993.300234491795;20481.487962800937;16193.35226590938;18780.71828922567;18911.243550257972;19732.01339933001;19735.309264175758;5106.5404113629675;5379.542738867201;5383.111786555213;5393.541548968343;5398.055340315449;5357.357117145948;5362.597805164628;5825.417458254162;5879.0593505039205;5893.292804863401;5313.22958171065;5576.9163462548095;5841.970790146043;5854.707264636772;5864.091065884794;5151.278820965068;5238.4499627539135;5311.389190243094;5317.468253174651;5707.743151558169;5127.384713834326;5165.66423182492;5176.456472070454;5178.559822415099;5178.637495375326;13121.212727236301;16130.75803862943;19926.30257940965;24354;24602.17075036376;1857.322077441724;2229.3534874886263;2288.5422915416984;2293.942651433711;2410.5178964207053;9582.712518624452;9716.854247186298;9958.942942108111;10274.640387586398;10455.738606534822;1687.7721507596564;1717.9507514175784;1806.7289906513997;1821.5172979160534;1906.7235250888682;114661;117089.4145529267;117689;117754.41122794332;118768.40615796953;10232.61859648938;10670.786584268271;11436.656900293005;11584.884151158549;11593.652190434132;14311.57065288043;14637.92681036588;14647.121172729458;14665.340059697339;14690.706185876223;6567.671616419256;8272.917270827335;8409.20112589303;8523.40336176474;8531.872021919551;6705.430038446689;7851.764447066597;8200.876986845202;8272.8345433091;8391.958040209882;4373.671974601918;4374.650027997753;4448.110377924363;4470.150671372428;4530.184566778012;1777.013757364649;1830.4508647405726;1839.5217243516781;1904.1247715948741;1913.2544798214606;668.2715839734616;698.870292056299;753.6194221917897;800.8398320335956;985.1970643925322;19543.315983940494;19804.70292945607;20465.590688186152;20767.68848467274;20923.953802309825;926.6802952981345;931.7391130483476;944.5985456181232;962.4658314635304;977.7604486900755;10301.690949271533;10931.56273749057;10931.781364372668;10973.83539246912;10976.396298203676;568649;570374;578045;590925.045374779;592676.0366198227;125324;127730.36134819266;128825;129550;133030;928.3501548915785;954.5084281594845;961.8893827209821;964.2912459342377;974.0794948773341;17147.056911869968;17840.643187136444;18338.174782135102;18391.813011250757;18421.90789046046;39891;50690.98618027545;53856;54568.63873618245;54997.45002549923;69504.65247673729;70206.94689579762;71556.92664609931;72692.63653681909;75650;8458.450200736932;11581.031224691991;11741.76516469683;11945.223560468541;12118.697032574257;12951.352432378531;13126.34368281563;13256.270905100311;13274.734505309647;13534.120282181626;10308.484575771032;11026.283291586022;11178.664640060812;11342.773144537228;11567.652970410902;9571.664991725256;9754.707358779247;11422.714432139262;11498.885011149781;11717.941410292893;11004.614838480615;11651.76696466049;11709.355985420883;12044.819327709916;12151.696207594881;11993.400329983291;12066.034717222425;12280.324582148067;12295.815562766744;12299.200551964093;9402.2948278878;9467.66863159786;11441.313521188755;11475.139364547525;11724.648260552198 07:44:13 INFO - PROCESS | 2239 | |3;mootools.html;1452.8619781120792;1484.7698606715956;1625.9593510162251;1799.7930238022632;1825.516238196878;7574.924250757491;8414.200650938166;8430.620622072007;8446.577671116444;8458.746237612866;7400.370968467687;7702.845943081135;8054.677812887478;8294.294984926288;8299;2331.6852224949625;2354.564405584972;2359.4219416243045;2360.8229382796358;2361.3624321433203;2322.384568089458;2345.2729653807287;2370.9644355334685;2381.1903952656094;2385.165192182731;2058.1561559760503;2135.455458857989;2147.280660978574;2283.566122436732;2330.1844354475966;2323.279783267184;2334.4047267946567;2339.02930283932;2339.7309309429465;2345.9178928737583;2336.0655737705056;2366.7988221001237;2368.1829768729763;2372.2883135059415;2377.928662140121;8303.792405189859;8313.41806073575;8406.411551191419;8417.200365965224;8417.326613870875;4392.450943632046;5049.3183420238165;5206.193040078794;5208.088584497736;5212.400573934008;3247.41546521626;3267.77125601208;3414.129397003773;3469.219425629213;3480.930381392358;887.4675194883083;929.7164364868712;1081.5349399758102;1091.5742860284495;1095.5782023920767;10375.221858360654;10897.67306980792;10936.343819370863;10948;10959.232853700245;4323.502797178365;4417.646588272931;4468.865934021985;4479.61923236522;4481.395011673448;8678.132186781271;8683.044865064838;8687.739367818975;8688.218060374596;8716.128387161232;5126.794928202905;5127.307813445212;5128.615353848476;5138.897222055538;5148.2277658350795;4447.866564003085;4579.74811385377;4736.715797052188;4811.542903424169;4815.951840481621;3227.2415982244156;3447.39696041342;3493.1965647901125;3535.964640353615;3572.8928132155556;1088.9509972051276;1094.6332978452149;1096.3257596578046;1108.8225883858545;1110.1119104716195;604.6613896218132;745.2808040241193;847.885535452706;849.8470275350372;898.0660113482036;5100.183970564692;5153.974230128825;5205.577903993783;5465.535429488458;5500.532454741392;394.3375129781937;463.7009129111704;573.9483446489752;622.4429135923323;912.1334732004622;3376.7129793967297;3604;3636.890893273206;3666.6150054244454;3709.851605935786;8949.52332865083;9585.376950498196;9605.327627066039;10201.489925503694;10207.285490015627;9965.40207587547;10086.49567521621;10102;10127;10136.341137826017;6682.365175308435;7409.555426674417;7736.303732664087;7932.603369831485;7933.523988560705;5143.6399452039495;5228.320318358589;5250.89498210026;5266.236395722675;5507.063799154073;9240;9315.441073535609;9356.95321523388;9481.288903332139;9490.525473726424;7338.633068346669;9352.553623619107;9391.389559678599;9655.565499552678;9685.612575496902;6500.837479063061;6574.704138313692;6593.505487088583;6596.175478065242;6670.83322916931;7275.8908616371855;7707.344875685405;7832.412569057457;7862.606869656609;7878.645460954156;6933.930660693329;7007.229204787572;7012.403945664676;7049.259827718016;7065.752698655522;6884.277150899284;7000.61496617691;7014.579125252297;7027.121609798775;7044.29557044312;5639.577031722555;6600.207975043026;6685.0306705526455;6713.765018224339;6725.226598940989;4566.961325416022;4586.931196031996;4670.299455081765;4709.481956984798;4729.834455794031;3977.940330894981;4521.367008618862;4590.816367345269;4672.345871577913;4737.170995075917 07:44:13 INFO - PROCESS | 2239 | |4;prototype.html;299.5013302850753;306.66879769848566;309.185296743082;311.74125475855044;315.93681263747226;5190.157494089601;5262.368515778103;5277.604179686525;5289.497497737719;5300.602454815885;4636;4894.755262236883;4956.578690811285;5146.459621739719;5179.446166150159;2989.805662631922;3669.3028324618235;3770.830312635938;3836.5012548368813;3926;3652.817359132046;3733.626637336258;3971.523417189941;3999.3800960851113;4005.815376775456;3965.8611948581806;3980.5422376426645;4001.118914652782;4007.9198416031663;4012.759234445928;3726.8509259629855;3781.2248489059816;3819.6037747142063;4023.476947996771;4038.535568409641;3475.669811367916;3484.059303987909;3489.94765078524;3506.228629701487;3516.9624960636675;4115.73247738896;4218;5055.823046193403;5058.974705126488;5072.909324495215;3752.5496940367057;4194.517630472474;4325.134973005412;4490.8428205013;4552.954470455286;4535.682502224846;5468.863278418032;5482.972585137049;5492.533134683556;5506.229127922094;3772.7924964127237;3782.978595779124;3788.2423515296773;3788.6211378862167;3813.275477659236;4592.71620791136;5502.944970550323;5768.394318596529;5811.82564523065;5847.356790753014;3814.978061627342;3829.310724069694;3832.9336530775827;3851.826667799956;3855.9178043856564;3895.6883449323986;3903.8243279052513;3906.5312162540677;3906.882793516199;3924.862629807943;3773.9056523586582;4522.276435770261;4541.205289074399;4546.40896683429;4558.358990763322;5176.611754118456;5498.835034948958;5573.498385145275;5576.693281869536;5583.218349431083;1094.0317818730323;1201.8582346770602;1225.66906935127;1268.8287081244098;1292.5699684592905;815.7389635316641;851.7700220940311;862.4092496639696;869.7519060148707;876.3471213945651;4091.284025295561;4987.62592805541;4992.452339774682;5057.443681195065;5144.588432925357;4872.878178045577;4877.487863774323;4885.778555361159;5197.506236907488;5314.910443359102;4450.5104438511735;5079.419009598224;5091.41448733393;5255.710935898486;5294.97352513235;2266.3851157403915;2437.7927876130375;2625.566781481034;2787.3867749095166;2819.7180281971637;4613.746243956614;4632.467266264355;4870.756462176877;5463.808766693147;5591.608587398924;4889.486603906538;5171.974140129275;5549.946519787704;5558.664241891332;5584.301962254718;4922.433920099163;5516.393196748354;5924.526037917042;5971.880562388815;5985.371535988746;5753.453421924911;5799;5809.8547536311935;5827.096799996008;5864.794732184353;4752.857414277577;4927.482614325444;5397.298351214317;5685.658860468385;5724.599278050497;5980.5215582754145;5983.491403230774;6021.397860213943;6022.698865056642;6034.547408944434;3989.503936023991;4019.457373254573;4043.353063509824;4044.1102957349335;4045.8583949561626;5557.194206839905;5657.830265092054;5707.6290041147195;5744.4829965303325;5789.739461724148;4621.913850245257;5455.618106732491;5495.890082198413;5496.285482887199;5506.752196151263;5549.807825908824;5563.833085007456;5566.860828479158;5605.486518639863;5644.463775941279;4751.7882939850115;5149.382074151126;5429.800930622946;5494.80768173112;5502.80740174108;4532.796024178854;5277.656952298088;5537.224788529528;5572.331320241435;5585.162225666182;5147.3308469898675;5224.791008359623;5356.276902618097;5392.325959255093;5394.9460505394445 07:44:13 INFO - PROCESS | 2239 | |5;yui.html;696.5855316086928;746.8207630168754;760.1790066727934;786.1823703348516;791.992080079199;11794.587189448372;16312.673746525063;16316.428924987627;16341.591460213485;16352.672946541063;14952;14963.700725985474;14994.925025374858;15064.2467876606;15098.924505377485;656.2551504042898;678.7861823525599;800.5676934455387;822.2024636102971;824.6247957179469;676.5636164673782;796.6176235407008;812.6058861452191;830.8919840420737;832.2967092806568;868.3530769576674;869.9826003479926;871.8953725552942;873.4409977614332;874.8425283448977;683.825624465763;864.623888608451;865.0614083719172;865.4028720183054;865.4590880699594;522.6628824408274;623.7847942459844;639.2041907824741;646.3116780628662;648.6691787188521;13849.238291893942;14018.92990535051;14144.151350918875;14159;14200.573982780534;10355.89644103557;12719.427625756864;12845.614631561068;12936.417861196269;13167.407466664023;11754.588589399416;11790.469428875722;12182;12195.695107622381;12307.44616492251;4238.894027649302;4342.413774140482;4381.364702118207;4385.166818304512;4418.4256046714045;61897.690511547604;81133;81244;83729.58135209346;85264.72102918461;10035.147012503872;10113.494325283706;10151.390916545031;10202.948985255174;10283.588656453658;18854.81145188558;18860.717089243673;19020.42938711841;19027.429177124708;19035.809641903405;7121.92878071223;7264.709411623581;7314.451416143704;7503.887441688379;7579.431542634215;4117.238310912491;4206.26390381682;4219.535851056381;4278.529361770203;4403.735775853459;3168.207948012997;3266.5426840242385;3270.362279355503;3276.737860971117;3546.184377593168;672.7477196051481;679.7858674517511;682.8395327098128;684.3088480634495;684.8904175331922;555.6471640508306;597.8087012156155;602.1750202222983;645.7707513832615;658.8385845467891;8857.468551886908;9051.502167380726;9053.094690530894;9205.861912071325;9537.570809313458;565.3413772954544;588.8939990801697;604.8125081224835;620.2618883528587;670.554081535783;4468.128714900563;4492.595666389975;4528.298113792302;4531.524189960072;4559.38448309474;108092;108700.45649771701;110015.44992274987;111951;112298;79905;91288;93437;93816.53091734498;100821.49589252006;56291;70810;73897.63051184709;79514.60242698749;82783.99999999879;9388.81222375535;9791.167750740982;9799.951000244953;9806.744448776964;9806.803863922823;42670.5732942679;51051.2342314873;52408.21387679112;52536.73731631317;55166.72416638052;63824.68087659718;66549.66725166343;69218.65390673015;69264.3073569278;70447.64776176086;10650.254482186483;11473.967342939175;11599.478023489131;12009.939950300193;12181.33002684861;12421.44103515362;12843.229406235656;12920.741585168056;13267.668308291984;14053.22707251111;12232.938835305766;12801.807972880228;13033.934830325788;13065.346732663138;13494.865051349361;12318.322492263012;13431;13451.394687239293;13569;13575.32123393846;10111.292209545261;12833.149247890962;13125.60623181306;13129.671758206121;13290.800637990631;9775.501578698924;10778.137748979905;10787.892121078688;10952.507137178687;11278.548858045915;8988.775280618036;9995.500224988577;11123.610673626385;11124;11160.720981975515 07:44:13 INFO - PROCESS | 2239 | __end_tp_report 07:44:13 INFO - PROCESS | 2239 | __start_cc_report 07:44:13 INFO - PROCESS | 2239 | _x_x_mozilla_cycle_collect,1521 07:44:13 INFO - PROCESS | 2239 | __end_cc_report 07:44:13 INFO - PROCESS | 2239 | __startTimestamp1481989453855__endTimestamp 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | ------- Summary: start ------- 07:44:13 INFO - PROCESS | 2239 | Number of tests: 6 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | [#0] dojo.html Cycles:165 Average:5617.33 Median:5747.86 stddev:647.09 (11.3%) stddev-sans-first:613.43 07:44:13 INFO - PROCESS | 2239 | Values: 2917.3 3219.8 3240.0 3274.5 3277.6 5513.9 5570.1 5612.0 5618.4 5636.6 4587.2 5503.3 5600.9 5611.2 5614.7 5063.1 5088.5 5102.1 5182.9 5198.6 4986.0 5027.8 5083.2 5084.1 5099.5 5110.5 5121.2 5147.0 5158.2 5181.4 5136.1 5141.6 5148.9 5169.9 5181.1 4962.7 4983.3 5038.7 5076.4 5079.0 5455.8 5486.9 5496.1 5580.1 5597.9 6061.8 6096.1 6113.7 6134.0 6152.6 6030.5 6187.4 6198.7 6214.6 6227.1 5549.0 5664.3 5701.8 5705.9 5712.5 6117.2 6225.3 6228.6 6250.0 6253.1 6019.1 6019.9 6031.7 6034.0 6086.1 5947.6 6048.8 6054.3 6111.4 6116.4 6038.3 6089.4 6120.4 6142.5 6157.1 6078.6 6127.4 6138.7 6164.9 6183.7 4726.3 4758.6 4847.1 4854.1 4862.6 4309.3 4332.1 4338.5 4349.4 4381.3 5557.0 5574.4 5582.3 5587.3 5589.4 6095.6 6108.5 6110.2 6114.2 6116.0 5556.1 5590.7 5592.7 5600.8 5607.8 5254.4 5280.5 5284.2 5286.1 5287.8 6168.4 6214.2 6234.1 6238.9 6248.5 6131.5 6196.5 6206.3 6209.9 6213.6 6104.0 6238.8 6256.8 6257.3 6264.1 5904.1 5945.3 5958.4 5962.6 6037.0 6006.5 6020.1 6111.9 6128.2 6138.8 5822.9 5852.6 5986.3 5989.2 6009.1 5803.8 5824.0 5833.0 5963.3 5972.6 5718.7 5729.3 5747.9 5766.7 5881.0 5974.9 5993.9 6013.4 6017.2 6029.9 5652.7 5692.7 5712.7 5714.9 5725.4 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | [#1] ext.html Cycles:180 Average:64736.77 Median:41382.50 stddev:136498.49 (329.8%) stddev-sans-first:136796.14 07:44:13 INFO - PROCESS | 2239 | Values: 479.6 501.2 506.8 520.4 539.5 30596.5 42877.8 43344.6 43395.1 43563.8 42025.2 42337.0 42930.4 43035.9 43120.4 8363.1 8500.5 8509.2 8520.3 8530.8 9327.1 9432.0 9447.3 9449.5 9460.0 9432.0 9441.3 9449.6 9481.6 9511.9 9398.8 9465.1 9465.9 9475.3 9476.5 9342.3 9415.7 9421.0 9423.2 9448.5 40033.8 40222.6 40250.6 40273.0 40502.8 3104.0 3149.5 3272.4 3802.7 3870.1 28085.3 29780.3 30518.7 31180.1 32182.5 3076.2 3166.0 3211.1 3234.6 3252.9 451146.0 457575.0 458613.0 458691.0 458691.0 62237.0 62673.0 62827.7 62910.0 62960.7 34905.3 35619.5 35732.6 36226.5 36326.0 15953.2 16102.9 16125.5 16127.5 16135.3 12441.4 12512.6 12544.2 12560.1 12576.0 19155.4 19190.9 19244.7 19267.0 19278.7 6119.7 6384.5 7521.9 7546.8 7622.0 824.5 962.1 967.6 974.4 988.6 28473.7 29000.3 29972.0 30447.7 31042.8 860.5 880.3 897.5 998.0 1033.4 13052.6 13966.5 14696.9 15441.7 15709.6 527159.0 530882.0 530939.0 531593.0 535614.3 58192.7 58798.0 58808.7 58817.7 58971.8 1126.2 1227.9 1266.2 1332.1 1381.9 29838.6 29943.3 30777.8 30842.2 31355.4 50225.7 50529.0 50561.5 50569.5 50720.0 465307.0 467977.0 468055.0 636098.0 651071.0 40604.8 40823.8 41228.0 41335.0 41430.0 44050.6 44990.8 45191.5 45658.5 46063.5 29748.9 38107.6 38318.8 38331.6 39336.6 36321.1 37588.0 38156.6 38195.0 39703.8 31242.1 31263.5 37628.0 38248.8 39097.0 32611.3 34070.5 34947.7 35018.0 35205.0 25154.9 25526.5 25628.9 25674.7 26267.7 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | [#2] jquery.html Cycles:180 Average:34072.22 Median:11576.65 stddev:96966.65 (837.6%) stddev-sans-first:97205.58 07:44:13 INFO - PROCESS | 2239 | Values: 337.8 349.5 376.7 381.1 403.8 14638.3 14827.9 15785.0 19993.3 20481.5 16193.4 18780.7 18911.2 19732.0 19735.3 5106.5 5379.5 5383.1 5393.5 5398.1 5357.4 5362.6 5825.4 5879.1 5893.3 5313.2 5576.9 5842.0 5854.7 5864.1 5151.3 5238.4 5311.4 5317.5 5707.7 5127.4 5165.7 5176.5 5178.6 5178.6 13121.2 16130.8 19926.3 24354.0 24602.2 1857.3 2229.4 2288.5 2293.9 2410.5 9582.7 9716.9 9958.9 10274.6 10455.7 1687.8 1718.0 1806.7 1821.5 1906.7 114661.0 117089.4 117689.0 117754.4 118768.4 10232.6 10670.8 11436.7 11584.9 11593.7 14311.6 14637.9 14647.1 14665.3 14690.7 6567.7 8272.9 8409.2 8523.4 8531.9 6705.4 7851.8 8200.9 8272.8 8392.0 4373.7 4374.7 4448.1 4470.2 4530.2 1777.0 1830.5 1839.5 1904.1 1913.3 668.3 698.9 753.6 800.8 985.2 19543.3 19804.7 20465.6 20767.7 20924.0 926.7 931.7 944.6 962.5 977.8 10301.7 10931.6 10931.8 10973.8 10976.4 568649.0 570374.0 578045.0 590925.0 592676.0 125324.0 127730.4 128825.0 129550.0 133030.0 928.4 954.5 961.9 964.3 974.1 17147.1 17840.6 18338.2 18391.8 18421.9 39891.0 50691.0 53856.0 54568.6 54997.5 69504.7 70206.9 71556.9 72692.6 75650.0 8458.5 11581.0 11741.8 11945.2 12118.7 12951.4 13126.3 13256.3 13274.7 13534.1 10308.5 11026.3 11178.7 11342.8 11567.7 9571.7 9754.7 11422.7 11498.9 11717.9 11004.6 11651.8 11709.4 12044.8 12151.7 11993.4 12066.0 12280.3 12295.8 12299.2 9402.3 9467.7 11441.3 11475.1 11724.6 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | [#3] mootools.html Cycles:180 Average:5300.68 Median:5127.96 stddev:2946.98 (57.5%) stddev-sans-first:2941.06 07:44:13 INFO - PROCESS | 2239 | Values: 1452.9 1484.8 1626.0 1799.8 1825.5 7574.9 8414.2 8430.6 8446.6 8458.7 7400.4 7702.8 8054.7 8294.3 8299.0 2331.7 2354.6 2359.4 2360.8 2361.4 2322.4 2345.3 2371.0 2381.2 2385.2 2058.2 2135.5 2147.3 2283.6 2330.2 2323.3 2334.4 2339.0 2339.7 2345.9 2336.1 2366.8 2368.2 2372.3 2377.9 8303.8 8313.4 8406.4 8417.2 8417.3 4392.5 5049.3 5206.2 5208.1 5212.4 3247.4 3267.8 3414.1 3469.2 3480.9 887.5 929.7 1081.5 1091.6 1095.6 10375.2 10897.7 10936.3 10948.0 10959.2 4323.5 4417.6 4468.9 4479.6 4481.4 8678.1 8683.0 8687.7 8688.2 8716.1 5126.8 5127.3 5128.6 5138.9 5148.2 4447.9 4579.7 4736.7 4811.5 4816.0 3227.2 3447.4 3493.2 3536.0 3572.9 1089.0 1094.6 1096.3 1108.8 1110.1 604.7 745.3 847.9 849.8 898.1 5100.2 5154.0 5205.6 5465.5 5500.5 394.3 463.7 573.9 622.4 912.1 3376.7 3604.0 3636.9 3666.6 3709.9 8949.5 9585.4 9605.3 10201.5 10207.3 9965.4 10086.5 10102.0 10127.0 10136.3 6682.4 7409.6 7736.3 7932.6 7933.5 5143.6 5228.3 5250.9 5266.2 5507.1 9240.0 9315.4 9357.0 9481.3 9490.5 7338.6 9352.6 9391.4 9655.6 9685.6 6500.8 6574.7 6593.5 6596.2 6670.8 7275.9 7707.3 7832.4 7862.6 7878.6 6933.9 7007.2 7012.4 7049.3 7065.8 6884.3 7000.6 7014.6 7027.1 7044.3 5639.6 6600.2 6685.0 6713.8 6725.2 4567.0 4586.9 4670.3 4709.5 4729.8 3977.9 4521.4 4590.8 4672.3 4737.2 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | [#4] prototype.html Cycles:180 Average:4439.48 Median:4942.03 stddev:1378.63 (27.9%) stddev-sans-first:1347.02 07:44:13 INFO - PROCESS | 2239 | Values: 299.5 306.7 309.2 311.7 315.9 5190.2 5262.4 5277.6 5289.5 5300.6 4636.0 4894.8 4956.6 5146.5 5179.4 2989.8 3669.3 3770.8 3836.5 3926.0 3652.8 3733.6 3971.5 3999.4 4005.8 3965.9 3980.5 4001.1 4007.9 4012.8 3726.9 3781.2 3819.6 4023.5 4038.5 3475.7 3484.1 3489.9 3506.2 3517.0 4115.7 4218.0 5055.8 5059.0 5072.9 3752.5 4194.5 4325.1 4490.8 4553.0 4535.7 5468.9 5483.0 5492.5 5506.2 3772.8 3783.0 3788.2 3788.6 3813.3 4592.7 5502.9 5768.4 5811.8 5847.4 3815.0 3829.3 3832.9 3851.8 3855.9 3895.7 3903.8 3906.5 3906.9 3924.9 3773.9 4522.3 4541.2 4546.4 4558.4 5176.6 5498.8 5573.5 5576.7 5583.2 1094.0 1201.9 1225.7 1268.8 1292.6 815.7 851.8 862.4 869.8 876.3 4091.3 4987.6 4992.5 5057.4 5144.6 4872.9 4877.5 4885.8 5197.5 5314.9 4450.5 5079.4 5091.4 5255.7 5295.0 2266.4 2437.8 2625.6 2787.4 2819.7 4613.7 4632.5 4870.8 5463.8 5591.6 4889.5 5172.0 5549.9 5558.7 5584.3 4922.4 5516.4 5924.5 5971.9 5985.4 5753.5 5799.0 5809.9 5827.1 5864.8 4752.9 4927.5 5397.3 5685.7 5724.6 5980.5 5983.5 6021.4 6022.7 6034.5 3989.5 4019.5 4043.4 4044.1 4045.9 5557.2 5657.8 5707.6 5744.5 5789.7 4621.9 5455.6 5495.9 5496.3 5506.8 5549.8 5563.8 5566.9 5605.5 5644.5 4751.8 5149.4 5429.8 5494.8 5502.8 4532.8 5277.7 5537.2 5572.3 5585.2 5147.3 5224.8 5356.3 5392.3 5394.9 07:44:13 INFO - PROCESS | 2239 | 07:44:13 INFO - PROCESS | 2239 | [#5] yui.html Cycles:180 Average:19550.44 Median:4510.45 stddev:28199.09 (625.2%) stddev-sans-first:28242.67 07:44:13 INFO - PROCESS | 2239 | Values: 696.6 746.8 760.2 786.2 792.0 11794.6 16312.7 16316.4 16341.6 16352.7 14952.0 14963.7 14994.9 15064.2 15098.9 656.3 678.8 800.6 822.2 824.6 676.6 796.6 812.6 830.9 832.3 868.4 870.0 871.9 873.4 874.8 683.8 864.6 865.1 865.4 865.5 522.7 623.8 639.2 646.3 648.7 13849.2 14018.9 14144.2 14159.0 14200.6 10355.9 12719.4 12845.6 12936.4 13167.4 11754.6 11790.5 12182.0 12195.7 12307.4 4238.9 4342.4 4381.4 4385.2 4418.4 61897.7 81133.0 81244.0 83729.6 85264.7 10035.1 10113.5 10151.4 10202.9 10283.6 18854.8 18860.7 19020.4 19027.4 19035.8 7121.9 7264.7 7314.5 7503.9 7579.4 4117.2 4206.3 4219.5 4278.5 4403.7 3168.2 3266.5 3270.4 3276.7 3546.2 672.7 679.8 682.8 684.3 684.9 555.6 597.8 602.2 645.8 658.8 8857.5 9051.5 9053.1 9205.9 9537.6 565.3 588.9 604.8 620.3 670.6 4468.1 4492.6 4528.3 4531.5 4559.4 108092.0 108700.5 110015.4 111951.0 112298.0 79905.0 91288.0 93437.0 93816.5 100821.5 56291.0 70810.0 73897.6 79514.6 82784.0 9388.8 9791.2 9800.0 9806.7 9806.8 42670.6 51051.2 52408.2 52536.7 55166.7 63824.7 66549.7 69218.7 69264.3 70447.6 10650.3 11474.0 11599.5 12009.9 12181.3 12421.4 12843.2 12920.7 13267.7 14053.2 12232.9 12801.8 13033.9 13065.3 13494.9 12318.3 13431.0 13451.4 13569.0 13575.3 10111.3 12833.1 13125.6 13129.7 13290.8 9775.5 10778.1 10787.9 10952.5 11278.5 8988.8 9995.5 11123.6 11124.0 11160.7 07:44:13 INFO - PROCESS | 2239 | -------- Summary: end -------- 07:44:13 INFO - PROCESS | 2239 | 07:44:14 INFO - TEST-INFO | 2239: exit 0 07:44:14 INFO - TEST-OK | dromaeo_css | took 1120081ms 07:44:14 INFO - TEST-START | kraken 07:44:14 INFO - operating with platform_type : mac_ 07:44:14 INFO - Initialising browser for kraken test... 07:44:14 INFO - TEST-INFO | started process 2242 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpCLIRW1/profile http://localhost:51677/getInfo.html) 07:44:17 INFO - PROCESS | 2242 | __metrics Screen width/height:1600/1200 07:44:17 INFO - PROCESS | 2242 | colorDepth:24 07:44:17 INFO - PROCESS | 2242 | Browser inner width/height: 1024/689 07:44:17 INFO - PROCESS | 2242 | __metrics 07:44:17 INFO - PROCESS | 2242 | 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" 07:44:17 INFO - PROCESS | 2242 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:44:18 INFO - TEST-INFO | 2242: exit 0 07:44:18 INFO - Browser initialized. 07:44:18 INFO - Running cycle 1/1 for kraken test... 07:44:18 INFO - TEST-INFO | started process 2243 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpCLIRW1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:30 INFO - PROCESS | 2243 | RSS: Main: 222781440 07:44:30 INFO - PROCESS | 2243 | 07:44:33 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51677/tests/kraken/driver.html?testName=audio-beat-detection) 07:44:34 INFO - PROCESS | 2243 | RSS: Main: 270557184 07:44:34 INFO - PROCESS | 2243 | 07:44:37 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51677/tests/kraken/driver.html?testName=audio-dft) 07:44:37 INFO - PROCESS | 2243 | RSS: Main: 284315648 07:44:37 INFO - PROCESS | 2243 | 07:44:40 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51677/tests/kraken/driver.html?testName=audio-fft) 07:44:41 INFO - PROCESS | 2243 | RSS: Main: 381820928 07:44:41 INFO - PROCESS | 2243 | 07:44:43 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51677/tests/kraken/driver.html?testName=audio-oscillator) 07:44:44 INFO - PROCESS | 2243 | RSS: Main: 284553216 07:44:44 INFO - PROCESS | 2243 | 07:44:46 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51677/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:44:46 INFO - PROCESS | 2243 | RSS: Main: 285761536 07:44:46 INFO - PROCESS | 2243 | 07:44:49 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51677/tests/kraken/driver.html?testName=imaging-darkroom) 07:44:50 INFO - PROCESS | 2243 | RSS: Main: 326909952 07:44:50 INFO - PROCESS | 2243 | 07:44:52 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51677/tests/kraken/driver.html?testName=imaging-desaturate) 07:44:53 INFO - PROCESS | 2243 | RSS: Main: 326950912 07:44:53 INFO - PROCESS | 2243 | 07:44:56 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51677/tests/kraken/driver.html?testName=json-parse-financial) 07:44:56 INFO - PROCESS | 2243 | RSS: Main: 323125248 07:44:56 INFO - PROCESS | 2243 | 07:44:58 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51677/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:44:59 INFO - PROCESS | 2243 | RSS: Main: 299388928 07:44:59 INFO - PROCESS | 2243 | 07:45:00 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:45:01 INFO - PROCESS | 2243 | RSS: Main: 300072960 07:45:01 INFO - PROCESS | 2243 | 07:45:03 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:45:04 INFO - PROCESS | 2243 | RSS: Main: 302514176 07:45:04 INFO - PROCESS | 2243 | 07:45:07 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:45:08 INFO - PROCESS | 2243 | RSS: Main: 320573440 07:45:08 INFO - PROCESS | 2243 | 07:45:10 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:45:11 INFO - PROCESS | 2243 | RSS: Main: 314187776 07:45:11 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:51677/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51677/tests/kraken/driver.html?testName=ai-astar) 07:45:13 INFO - PROCESS | 2243 | RSS: Main: 358264832 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | __start_tp_report 07:45:13 INFO - PROCESS | 2243 | _x_x_mozilla_page_load 07:45:13 INFO - PROCESS | 2243 | _x_x_mozilla_page_load_details 07:45:13 INFO - PROCESS | 2243 | |i|pagename|runs| 07:45:13 INFO - PROCESS | 2243 | |0;ai-astar;110;111;113;111;112;112;112;113;110;111 07:45:13 INFO - PROCESS | 2243 | |1;audio-beat-detection;146;111;112;169;139;139;119;109;145;111 07:45:13 INFO - PROCESS | 2243 | |2;audio-dft;185;180;185;178;180;182;183;183;183;191 07:45:13 INFO - PROCESS | 2243 | |3;audio-fft;85;73;81;98;61;59;61;59;84;62 07:45:13 INFO - PROCESS | 2243 | |4;audio-oscillator;91;70;93;70;67;70;90;67;69;93 07:45:13 INFO - PROCESS | 2243 | |5;imaging-gaussian-blur;87;83;86;83;86;83;87;84;87;83 07:45:13 INFO - PROCESS | 2243 | |6;imaging-darkroom;94;92;93;94;94;93;94;93;93;93 07:45:13 INFO - PROCESS | 2243 | |7;imaging-desaturate;84;83;82;80;80;84;82;84;83;81 07:45:13 INFO - PROCESS | 2243 | |8;json-parse-financial;57;56;56;55;57;58;59;56;56;57 07:45:13 INFO - PROCESS | 2243 | |9;json-stringify-tinderbox;66;76;82;71;74;69;71;72;77;105 07:45:13 INFO - PROCESS | 2243 | |10;stanford-crypto-aes;85;83;83;85;84;85;83;84;84;83 07:45:13 INFO - PROCESS | 2243 | |11;stanford-crypto-ccm;139;139;141;139;134;162;150;142;184;150 07:45:13 INFO - PROCESS | 2243 | |12;stanford-crypto-pbkdf2;165;168;167;166;166;169;167;165;165;167 07:45:13 INFO - PROCESS | 2243 | |13;stanford-crypto-sha256-iterative;68;68;67;67;66;68;70;67;68;66 07:45:13 INFO - PROCESS | 2243 | __end_tp_report 07:45:13 INFO - PROCESS | 2243 | __start_cc_report 07:45:13 INFO - PROCESS | 2243 | _x_x_mozilla_cycle_collect,2003 07:45:13 INFO - PROCESS | 2243 | __end_cc_report 07:45:13 INFO - PROCESS | 2243 | __startTimestamp1481989513463__endTimestamp 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | ------- Summary: start ------- 07:45:13 INFO - PROCESS | 2243 | Number of tests: 14 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.08 (1.0%) stddev-sans-first:1.00 07:45:13 INFO - PROCESS | 2243 | Values: 110.0 111.0 113.0 111.0 112.0 112.0 112.0 113.0 110.0 111.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:139.00 stddev:20.47 (14.7%) stddev-sans-first:20.88 07:45:13 INFO - PROCESS | 2243 | Values: 146.0 111.0 112.0 169.0 139.0 139.0 119.0 109.0 145.0 111.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:3.59 (2.0%) stddev-sans-first:3.73 07:45:13 INFO - PROCESS | 2243 | Values: 185.0 180.0 185.0 178.0 180.0 182.0 183.0 183.0 183.0 191.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#3] audio-fft Cycles:10 Average:72.30 Median:77.00 stddev:13.94 (18.1%) stddev-sans-first:14.01 07:45:13 INFO - PROCESS | 2243 | Values: 85.0 73.0 81.0 98.0 61.0 59.0 61.0 59.0 84.0 62.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:80.00 stddev:11.92 (14.9%) stddev-sans-first:11.67 07:45:13 INFO - PROCESS | 2243 | Values: 91.0 70.0 93.0 70.0 67.0 70.0 90.0 67.0 69.0 93.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.85 (2.2%) stddev-sans-first:1.80 07:45:13 INFO - PROCESS | 2243 | Values: 87.0 83.0 86.0 83.0 86.0 83.0 87.0 84.0 87.0 83.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 07:45:13 INFO - PROCESS | 2243 | Values: 94.0 92.0 93.0 94.0 94.0 93.0 94.0 93.0 93.0 93.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#7] imaging-desaturate Cycles:10 Average:82.30 Median:83.00 stddev:1.57 (1.9%) stddev-sans-first:1.54 07:45:13 INFO - PROCESS | 2243 | Values: 84.0 83.0 82.0 80.0 80.0 84.0 82.0 84.0 83.0 81.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.16 (2.0%) stddev-sans-first:1.22 07:45:13 INFO - PROCESS | 2243 | Values: 57.0 56.0 56.0 55.0 57.0 58.0 59.0 56.0 56.0 57.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:73.00 stddev:11.04 (15.1%) stddev-sans-first:11.06 07:45:13 INFO - PROCESS | 2243 | Values: 66.0 76.0 82.0 71.0 74.0 69.0 71.0 72.0 77.0 105.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:0.88 (1.0%) stddev-sans-first:0.83 07:45:13 INFO - PROCESS | 2243 | Values: 85.0 83.0 83.0 85.0 84.0 85.0 83.0 84.0 84.0 83.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:146.00 stddev:15.00 (10.3%) stddev-sans-first:15.55 07:45:13 INFO - PROCESS | 2243 | Values: 139.0 139.0 141.0 139.0 134.0 162.0 150.0 142.0 184.0 150.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:167.00 stddev:1.35 (0.8%) stddev-sans-first:1.32 07:45:13 INFO - PROCESS | 2243 | Values: 165.0 168.0 167.0 166.0 166.0 169.0 167.0 165.0 165.0 167.0 07:45:13 INFO - PROCESS | 2243 | 07:45:13 INFO - PROCESS | 2243 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.18 (1.7%) stddev-sans-first:1.24 07:45:13 INFO - PROCESS | 2243 | Values: 68.0 68.0 67.0 67.0 66.0 68.0 70.0 67.0 68.0 66.0 07:45:13 INFO - PROCESS | 2243 | -------- Summary: end -------- 07:45:13 INFO - PROCESS | 2243 | 07:45:14 INFO - TEST-INFO | 2243: exit 0 07:45:14 INFO - TEST-OK | kraken | took 59959ms 07:45:14 INFO - SUITE-END | took 1180s 07:45:14 INFO - Completed test suite (00:19:40) 07:45:14 INFO - javascript benchmark 07:45:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2917.3144311086894, 3219.839008049597, 3239.951400728988, 3274.492453669679, 3277.623073346566, 5513.9448605513935, 5570.053090974534, 5612.0178968680475, 5618.438156184379, 5636.633618814774, 4587.243104887698, 5503.257060296859, 5600.915986260199, 5611.214429979796, 5614.719264036792, 5063.063333283345, 5088.516590923857, 5102.132637451624, 5182.948170518303, 5198.558122559568, 4986.0, 5027.798888044474, 5083.18669012958, 5084.135696931512, 5099.490050994889, 5110.540051395374, 5121.231815227727, 5146.974265128669, 5158.200478925773, 5181.404138524062, 5136.075506408845, 5141.640085194057, 5148.867249205187, 5169.948300517022, 5181.119209734316, 4962.727050012248, 4983.277424773424, 5038.697678139323, 5076.44159142494, 5078.97460512695, 5455.809046683386, 5486.890262194734, 5496.148097044925, 5580.051391263495, 5597.916031259534, 6061.787837425713, 6096.116063170815, 6113.724882380304, 6134.0, 6152.600080994721, 6030.517558595346, 6187.350328215518, 6198.721057552376, 6214.5649804513705, 6227.06594010902, 5549.0289199390345, 5664.291963504562, 5701.828945131652, 5705.942940570624, 5712.542996560265, 6117.248930028882, 6225.28409232935, 6228.564000520011, 6250.0, 6253.1245625612455, 6019.127226552126, 6019.879602407927, 6031.728572214261, 6033.9698301508215, 6086.147939288503, 5947.613405128653, 6048.758049678052, 6054.273487181478, 6111.35830737775, 6116.3883611638485, 6038.275406951194, 6089.360617135226, 6120.357362476876, 6142.512282525495, 6157.076438534255, 6078.574499784972, 6127.4485296322655, 6138.71087071711, 6164.8767024659255, 6183.659898705614, 4726.314684370747, 4758.595519380891, 4847.079054979543, 4854.053459575349, 4862.586680132228, 4309.28896732042, 4332.090261045153, 4338.457692788402, 4349.412829268072, 4381.3427985801745, 5557.0, 5574.386817450078, 5582.302212223472, 5587.273654424899, 5589.357223919302, 6095.603785753912, 6108.51131909455, 6110.205673262448, 6114.205153330127, 6116.0, 5556.055470570012, 5590.664560126405, 5592.664440133605, 5600.831975040755, 5607.775688972477, 5254.448282930313, 5280.524752772192, 5284.180951952453, 5286.07493688603, 5287.841364758987, 6168.35232300602, 6214.192155019909, 6234.096056071844, 6238.906416403848, 6248.4688801451475, 6131.509479241739, 6196.504279657706, 6206.317305096345, 6209.906851397142, 6213.596116252429, 6104.0, 6238.812835614939, 6256.812295631138, 6257.311695713377, 6264.060390941213, 5904.114382842608, 5945.256842894638, 5958.433948774859, 5962.612430192024, 6036.9698151508965, 6006.459418652343, 6020.127081573235, 6111.86523267538, 6128.203333566607, 6138.754449822136, 5822.883542329215, 5852.619579727304, 5986.2517185351835, 5989.161517387656, 6009.098635204754, 5803.7968671096305, 5823.970880145742, 5832.9708351457975, 5963.254593175853, 5972.641641501524, 5718.714064296852, 5729.341125770589, 5747.856303592276, 5766.654000759968, 5881.0, 5974.850628734142, 5993.910091348721, 6013.428724271187, 6017.217761690952, 6029.939700602938, 5652.689102099424, 5692.743826527899, 5712.742926568398, 5714.942850571441, 5725.427457254408], "value": 5573.534727919494, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.55401476626747, 501.24562533387245, 506.7744853540174, 520.3885434614327, 539.5333036923063, 30596.54105188421, 42877.78561107198, 43344.566554334444, 43395.13209735804, 43563.78218108913, 42025.15949681005, 42337.0, 42930.356044659355, 43035.92410189747, 43120.353194702024, 8363.08006119328, 8500.489970601784, 8509.191626795475, 8520.318374530054, 8530.848335474693, 9327.113924177193, 9431.95284023579, 9447.338686291961, 9449.480278584675, 9459.952700236488, 9431.95284023579, 9441.29190310726, 9449.574769135406, 9481.62073517062, 9511.904880951171, 9398.812023759521, 9465.100815422524, 9465.858012129824, 9475.336726429152, 9476.47879366631, 9342.299327550461, 9415.670451534232, 9420.963693993655, 9423.199028082621, 9448.527573621292, 40033.79983100095, 40222.59777402218, 40250.59749402498, 40273.0, 40502.79748601268, 3103.953440698391, 3149.480335744622, 3272.4109660261142, 3802.7338086333966, 3870.0518372998513, 28085.29786755327, 29780.255493612618, 30518.694813051807, 31180.064598062094, 32182.517262241086, 3076.200187951149, 3165.984170079158, 3211.1008917503136, 3234.628017777962, 3252.9186770330693, 451146.0, 457575.0, 458613.0, 458691.0, 458691.0, 62237.0, 62673.0, 62827.6858615704, 62910.0, 62960.68519657464, 34905.30189396198, 35619.4657080144, 35732.64267357345, 36226.456603150975, 36326.0, 15953.202339882959, 16102.919485402497, 16125.516234512983, 16127.516174514549, 16135.273912673723, 12441.440135193938, 12512.562060327844, 12544.24734515932, 12560.120791544687, 12576.0, 19155.425337239903, 19190.904045479685, 19244.71132933007, 19267.0, 19278.71081933772, 6119.663418511936, 6384.457321127757, 7521.923606862298, 7546.811329716691, 7621.961890190624, 824.4929368438487, 962.1484985787489, 967.6419724701906, 974.3617930255693, 988.614440368257, 28473.715262847105, 29000.274993125342, 29972.0, 30447.695523044928, 31042.844785775927, 860.4622111180512, 880.2913654508054, 897.4525539420948, 998.0418797953901, 1033.4367769565604, 13052.608421747364, 13966.511172108927, 14696.94242806008, 15441.691166176613, 15709.607259818597, 527159.0, 530882.0, 530939.0, 531593.0, 535614.3219283957, 58192.709036454544, 58798.0, 58808.705956469945, 58817.70591147017, 58971.82056358849, 1126.2341607706837, 1227.9017678585692, 1266.202292555684, 1332.1474256476056, 1381.854905234956, 29838.55242171326, 29943.251418714706, 30777.846110769304, 30842.228944276572, 31355.372892542477, 50225.74887125541, 50529.0, 50561.4831555054, 50569.494305056476, 50720.0, 465307.0, 467977.0, 468055.0, 636098.0, 651071.0, 40604.79697601493, 40823.79588102159, 41228.0, 41335.0, 41430.0, 44050.559494404646, 44990.77504612456, 45191.548084518734, 45658.54341456676, 46063.53936460726, 29748.85125574445, 38107.618923810405, 38318.80840595779, 38331.616683832806, 39336.6066339333, 36321.091972700895, 37588.0, 38156.61843381642, 38195.04512387213, 39703.80148099241, 31242.062738117893, 31263.53104703477, 37628.0, 38248.808755956044, 39097.0, 32611.34777304488, 34070.48894266637, 34947.65052349444, 35018.0, 35205.0, 25154.868030938287, 25526.489470210865, 25628.871855640602, 25674.71626418709, 26267.737322627294], "value": 19398.211677880223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.84121462912424, 349.52639173919323, 376.67605858961304, 381.1481339206873, 403.82635466749286, 14638.26808659568, 14827.925860370697, 15784.999999999985, 19993.300234491795, 20481.487962800937, 16193.35226590938, 18780.71828922567, 18911.243550257972, 19732.01339933001, 19735.309264175758, 5106.5404113629675, 5379.542738867201, 5383.111786555213, 5393.541548968343, 5398.055340315449, 5357.357117145948, 5362.597805164628, 5825.417458254162, 5879.0593505039205, 5893.292804863401, 5313.22958171065, 5576.9163462548095, 5841.970790146043, 5854.707264636772, 5864.091065884794, 5151.278820965068, 5238.4499627539135, 5311.389190243094, 5317.468253174651, 5707.743151558169, 5127.384713834326, 5165.66423182492, 5176.456472070454, 5178.559822415099, 5178.637495375326, 13121.212727236301, 16130.75803862943, 19926.30257940965, 24354.0, 24602.17075036376, 1857.322077441724, 2229.3534874886263, 2288.5422915416984, 2293.942651433711, 2410.5178964207053, 9582.712518624452, 9716.854247186298, 9958.942942108111, 10274.640387586398, 10455.738606534822, 1687.7721507596564, 1717.9507514175784, 1806.7289906513997, 1821.5172979160534, 1906.7235250888682, 114661.0, 117089.4145529267, 117689.0, 117754.41122794332, 118768.40615796953, 10232.61859648938, 10670.786584268271, 11436.656900293005, 11584.884151158549, 11593.652190434132, 14311.57065288043, 14637.92681036588, 14647.121172729458, 14665.340059697339, 14690.706185876223, 6567.671616419256, 8272.917270827335, 8409.20112589303, 8523.40336176474, 8531.872021919551, 6705.430038446689, 7851.764447066597, 8200.876986845202, 8272.8345433091, 8391.958040209882, 4373.671974601918, 4374.650027997753, 4448.110377924363, 4470.150671372428, 4530.184566778012, 1777.013757364649, 1830.4508647405726, 1839.5217243516781, 1904.1247715948741, 1913.2544798214606, 668.2715839734616, 698.870292056299, 753.6194221917897, 800.8398320335956, 985.1970643925322, 19543.315983940494, 19804.70292945607, 20465.590688186152, 20767.68848467274, 20923.953802309825, 926.6802952981345, 931.7391130483476, 944.5985456181232, 962.4658314635304, 977.7604486900755, 10301.690949271533, 10931.56273749057, 10931.781364372668, 10973.83539246912, 10976.396298203676, 568649.0, 570374.0, 578045.0, 590925.045374779, 592676.0366198227, 125324.0, 127730.36134819266, 128825.0, 129550.0, 133030.0, 928.3501548915785, 954.5084281594845, 961.8893827209821, 964.2912459342377, 974.0794948773341, 17147.056911869968, 17840.643187136444, 18338.174782135102, 18391.813011250757, 18421.90789046046, 39891.0, 50690.98618027545, 53856.0, 54568.63873618245, 54997.45002549923, 69504.65247673729, 70206.94689579762, 71556.92664609931, 72692.63653681909, 75650.0, 8458.450200736932, 11581.031224691991, 11741.76516469683, 11945.223560468541, 12118.697032574257, 12951.352432378531, 13126.34368281563, 13256.270905100311, 13274.734505309647, 13534.120282181626, 10308.484575771032, 11026.283291586022, 11178.664640060812, 11342.773144537228, 11567.652970410902, 9571.664991725256, 9754.707358779247, 11422.714432139262, 11498.885011149781, 11717.941410292893, 11004.614838480615, 11651.76696466049, 11709.355985420883, 12044.819327709916, 12151.696207594881, 11993.400329983291, 12066.034717222425, 12280.324582148067, 12295.815562766744, 12299.200551964093, 9402.2948278878, 9467.66863159786, 11441.313521188755, 11475.139364547525, 11724.648260552198], "value": 9354.15569501831, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1452.8619781120792, 1484.7698606715956, 1625.9593510162251, 1799.7930238022632, 1825.516238196878, 7574.924250757491, 8414.200650938166, 8430.620622072007, 8446.577671116444, 8458.746237612866, 7400.370968467687, 7702.845943081135, 8054.677812887478, 8294.294984926288, 8299.0, 2331.6852224949625, 2354.564405584972, 2359.4219416243045, 2360.8229382796358, 2361.3624321433203, 2322.384568089458, 2345.2729653807287, 2370.9644355334685, 2381.1903952656094, 2385.165192182731, 2058.1561559760503, 2135.455458857989, 2147.280660978574, 2283.566122436732, 2330.1844354475966, 2323.279783267184, 2334.4047267946567, 2339.02930283932, 2339.7309309429465, 2345.9178928737583, 2336.0655737705056, 2366.7988221001237, 2368.1829768729763, 2372.2883135059415, 2377.928662140121, 8303.792405189859, 8313.41806073575, 8406.411551191419, 8417.200365965224, 8417.326613870875, 4392.450943632046, 5049.3183420238165, 5206.193040078794, 5208.088584497736, 5212.400573934008, 3247.41546521626, 3267.77125601208, 3414.129397003773, 3469.219425629213, 3480.930381392358, 887.4675194883083, 929.7164364868712, 1081.5349399758102, 1091.5742860284495, 1095.5782023920767, 10375.221858360654, 10897.67306980792, 10936.343819370863, 10948.0, 10959.232853700245, 4323.502797178365, 4417.646588272931, 4468.865934021985, 4479.61923236522, 4481.395011673448, 8678.132186781271, 8683.044865064838, 8687.739367818975, 8688.218060374596, 8716.128387161232, 5126.794928202905, 5127.307813445212, 5128.615353848476, 5138.897222055538, 5148.2277658350795, 4447.866564003085, 4579.74811385377, 4736.715797052188, 4811.542903424169, 4815.951840481621, 3227.2415982244156, 3447.39696041342, 3493.1965647901125, 3535.964640353615, 3572.8928132155556, 1088.9509972051276, 1094.6332978452149, 1096.3257596578046, 1108.8225883858545, 1110.1119104716195, 604.6613896218132, 745.2808040241193, 847.885535452706, 849.8470275350372, 898.0660113482036, 5100.183970564692, 5153.974230128825, 5205.577903993783, 5465.535429488458, 5500.532454741392, 394.3375129781937, 463.7009129111704, 573.9483446489752, 622.4429135923323, 912.1334732004622, 3376.7129793967297, 3604.0, 3636.890893273206, 3666.6150054244454, 3709.851605935786, 8949.52332865083, 9585.376950498196, 9605.327627066039, 10201.489925503694, 10207.285490015627, 9965.40207587547, 10086.49567521621, 10102.0, 10127.0, 10136.341137826017, 6682.365175308435, 7409.555426674417, 7736.303732664087, 7932.603369831485, 7933.523988560705, 5143.6399452039495, 5228.320318358589, 5250.89498210026, 5266.236395722675, 5507.063799154073, 9240.0, 9315.441073535609, 9356.95321523388, 9481.288903332139, 9490.525473726424, 7338.633068346669, 9352.553623619107, 9391.389559678599, 9655.565499552678, 9685.612575496902, 6500.837479063061, 6574.704138313692, 6593.505487088583, 6596.175478065242, 6670.83322916931, 7275.8908616371855, 7707.344875685405, 7832.412569057457, 7862.606869656609, 7878.645460954156, 6933.930660693329, 7007.229204787572, 7012.403945664676, 7049.259827718016, 7065.752698655522, 6884.277150899284, 7000.61496617691, 7014.579125252297, 7027.121609798775, 7044.29557044312, 5639.577031722555, 6600.207975043026, 6685.0306705526455, 6713.765018224339, 6725.226598940989, 4566.961325416022, 4586.931196031996, 4670.299455081765, 4709.481956984798, 4729.834455794031, 3977.940330894981, 4521.367008618862, 4590.816367345269, 4672.345871577913, 4737.170995075917], "value": 4243.931573375676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.5013302850753, 306.66879769848566, 309.185296743082, 311.74125475855044, 315.93681263747226, 5190.157494089601, 5262.368515778103, 5277.604179686525, 5289.497497737719, 5300.602454815885, 4636.0, 4894.755262236883, 4956.578690811285, 5146.459621739719, 5179.446166150159, 2989.805662631922, 3669.3028324618235, 3770.830312635938, 3836.5012548368813, 3926.0, 3652.817359132046, 3733.626637336258, 3971.523417189941, 3999.3800960851113, 4005.815376775456, 3965.8611948581806, 3980.5422376426645, 4001.118914652782, 4007.9198416031663, 4012.759234445928, 3726.8509259629855, 3781.2248489059816, 3819.6037747142063, 4023.476947996771, 4038.535568409641, 3475.669811367916, 3484.059303987909, 3489.94765078524, 3506.228629701487, 3516.9624960636675, 4115.73247738896, 4218.0, 5055.823046193403, 5058.974705126488, 5072.909324495215, 3752.5496940367057, 4194.517630472474, 4325.134973005412, 4490.8428205013, 4552.954470455286, 4535.682502224846, 5468.863278418032, 5482.972585137049, 5492.533134683556, 5506.229127922094, 3772.7924964127237, 3782.978595779124, 3788.2423515296773, 3788.6211378862167, 3813.275477659236, 4592.71620791136, 5502.944970550323, 5768.394318596529, 5811.82564523065, 5847.356790753014, 3814.978061627342, 3829.310724069694, 3832.9336530775827, 3851.826667799956, 3855.9178043856564, 3895.6883449323986, 3903.8243279052513, 3906.5312162540677, 3906.882793516199, 3924.862629807943, 3773.9056523586582, 4522.276435770261, 4541.205289074399, 4546.40896683429, 4558.358990763322, 5176.611754118456, 5498.835034948958, 5573.498385145275, 5576.693281869536, 5583.218349431083, 1094.0317818730323, 1201.8582346770602, 1225.66906935127, 1268.8287081244098, 1292.5699684592905, 815.7389635316641, 851.7700220940311, 862.4092496639696, 869.7519060148707, 876.3471213945651, 4091.284025295561, 4987.62592805541, 4992.452339774682, 5057.443681195065, 5144.588432925357, 4872.878178045577, 4877.487863774323, 4885.778555361159, 5197.506236907488, 5314.910443359102, 4450.5104438511735, 5079.419009598224, 5091.41448733393, 5255.710935898486, 5294.97352513235, 2266.3851157403915, 2437.7927876130375, 2625.566781481034, 2787.3867749095166, 2819.7180281971637, 4613.746243956614, 4632.467266264355, 4870.756462176877, 5463.808766693147, 5591.608587398924, 4889.486603906538, 5171.974140129275, 5549.946519787704, 5558.664241891332, 5584.301962254718, 4922.433920099163, 5516.393196748354, 5924.526037917042, 5971.880562388815, 5985.371535988746, 5753.453421924911, 5799.0, 5809.8547536311935, 5827.096799996008, 5864.794732184353, 4752.857414277577, 4927.482614325444, 5397.298351214317, 5685.658860468385, 5724.599278050497, 5980.5215582754145, 5983.491403230774, 6021.397860213943, 6022.698865056642, 6034.547408944434, 3989.503936023991, 4019.457373254573, 4043.353063509824, 4044.1102957349335, 4045.8583949561626, 5557.194206839905, 5657.830265092054, 5707.6290041147195, 5744.4829965303325, 5789.739461724148, 4621.913850245257, 5455.618106732491, 5495.890082198413, 5496.285482887199, 5506.752196151263, 5549.807825908824, 5563.833085007456, 5566.860828479158, 5605.486518639863, 5644.463775941279, 4751.7882939850115, 5149.382074151126, 5429.800930622946, 5494.80768173112, 5502.80740174108, 4532.796024178854, 5277.656952298088, 5537.224788529528, 5572.331320241435, 5585.162225666182, 5147.3308469898675, 5224.791008359623, 5356.276902618097, 5392.325959255093, 5394.9460505394445], "value": 3998.052402080684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.5855316086928, 746.8207630168754, 760.1790066727934, 786.1823703348516, 791.992080079199, 11794.587189448372, 16312.673746525063, 16316.428924987627, 16341.591460213485, 16352.672946541063, 14952.0, 14963.700725985474, 14994.925025374858, 15064.2467876606, 15098.924505377485, 656.2551504042898, 678.7861823525599, 800.5676934455387, 822.2024636102971, 824.6247957179469, 676.5636164673782, 796.6176235407008, 812.6058861452191, 830.8919840420737, 832.2967092806568, 868.3530769576674, 869.9826003479926, 871.8953725552942, 873.4409977614332, 874.8425283448977, 683.825624465763, 864.623888608451, 865.0614083719172, 865.4028720183054, 865.4590880699594, 522.6628824408274, 623.7847942459844, 639.2041907824741, 646.3116780628662, 648.6691787188521, 13849.238291893942, 14018.92990535051, 14144.151350918875, 14159.0, 14200.573982780534, 10355.89644103557, 12719.427625756864, 12845.614631561068, 12936.417861196269, 13167.407466664023, 11754.588589399416, 11790.469428875722, 12182.0, 12195.695107622381, 12307.44616492251, 4238.894027649302, 4342.413774140482, 4381.364702118207, 4385.166818304512, 4418.4256046714045, 61897.690511547604, 81133.0, 81244.0, 83729.58135209346, 85264.72102918461, 10035.147012503872, 10113.494325283706, 10151.390916545031, 10202.948985255174, 10283.588656453658, 18854.81145188558, 18860.717089243673, 19020.42938711841, 19027.429177124708, 19035.809641903405, 7121.92878071223, 7264.709411623581, 7314.451416143704, 7503.887441688379, 7579.431542634215, 4117.238310912491, 4206.26390381682, 4219.535851056381, 4278.529361770203, 4403.735775853459, 3168.207948012997, 3266.5426840242385, 3270.362279355503, 3276.737860971117, 3546.184377593168, 672.7477196051481, 679.7858674517511, 682.8395327098128, 684.3088480634495, 684.8904175331922, 555.6471640508306, 597.8087012156155, 602.1750202222983, 645.7707513832615, 658.8385845467891, 8857.468551886908, 9051.502167380726, 9053.094690530894, 9205.861912071325, 9537.570809313458, 565.3413772954544, 588.8939990801697, 604.8125081224835, 620.2618883528587, 670.554081535783, 4468.128714900563, 4492.595666389975, 4528.298113792302, 4531.524189960072, 4559.38448309474, 108092.0, 108700.45649771701, 110015.44992274987, 111951.0, 112298.0, 79905.0, 91288.0, 93437.0, 93816.53091734498, 100821.49589252006, 56291.0, 70810.0, 73897.63051184709, 79514.60242698749, 82783.99999999879, 9388.81222375535, 9791.167750740982, 9799.951000244953, 9806.744448776964, 9806.803863922823, 42670.5732942679, 51051.2342314873, 52408.21387679112, 52536.73731631317, 55166.72416638052, 63824.68087659718, 66549.66725166343, 69218.65390673015, 69264.3073569278, 70447.64776176086, 10650.254482186483, 11473.967342939175, 11599.478023489131, 12009.939950300193, 12181.33002684861, 12421.44103515362, 12843.229406235656, 12920.741585168056, 13267.668308291984, 14053.22707251111, 12232.938835305766, 12801.807972880228, 13033.934830325788, 13065.346732663138, 13494.865051349361, 12318.322492263012, 13431.0, 13451.394687239293, 13569.0, 13575.32123393846, 10111.292209545261, 12833.149247890962, 13125.60623181306, 13129.671758206121, 13290.800637990631, 9775.501578698924, 10778.137748979905, 10787.892121078688, 10952.507137178687, 11278.548858045915, 8988.775280618036, 9995.500224988577, 11123.610673626385, 11124.0, 11160.720981975515], "value": 7172.16889265566, "unit": "score"}], "name": "dromaeo_css", "value": 7052.918356403728, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 113.0, 111.0, 112.0, 112.0, 112.0, 113.0, 110.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 111.0, 112.0, 169.0, 139.0, 139.0, 119.0, 109.0, 145.0, 111.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 180.0, 185.0, 178.0, 180.0, 182.0, 183.0, 183.0, 183.0, 191.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 73.0, 81.0, 98.0, 61.0, 59.0, 61.0, 59.0, 84.0, 62.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 93.0, 70.0, 67.0, 70.0, 90.0, 67.0, 69.0, 93.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 83.0, 86.0, 83.0, 87.0, 84.0, 87.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 80.0, 80.0, 84.0, 82.0, 84.0, 83.0, 81.0], "value": 82.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 55.0, 57.0, 58.0, 59.0, 56.0, 56.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 82.0, 71.0, 74.0, 69.0, 71.0, 72.0, 77.0, 105.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 85.0, 84.0, 85.0, 83.0, 84.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 141.0, 139.0, 134.0, 162.0, 150.0, 142.0, 184.0, 150.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 167.0, 166.0, 166.0, 169.0, 167.0, 165.0, 165.0, 167.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 66.0, 68.0, 70.0, 67.0, 68.0, 66.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1434.1999999999998, "alertThreshold": 2.0}]} 07:45:14 INFO - Return code: 0 07:45:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:45:14 INFO - # TBPL SUCCESS # 07:45:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:45:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:45:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:45:14 INFO - 2016-12-17 07:45:14.798 screenresolution[2247:19492] starting screenresolution argv=screenresolution get 07:45:14 INFO - 2016-12-17 07:45:14.801 screenresolution[2247:19492] Display 0: 1600x1200x32@60 07:45:14 INFO - 2016-12-17 07:45:14.807 screenresolution[2248:19495] starting screenresolution argv=screenresolution list 07:45:14 INFO - Available Modes on Display 0 07:45:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:45:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:45:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:45:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:45:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:45:15 INFO - Intel Iris: 07:45:15 INFO - Chipset Model: Intel Iris 07:45:15 INFO - Type: GPU 07:45:15 INFO - Bus: Built-In 07:45:15 INFO - VRAM (Dynamic, Max): 1536 MB 07:45:15 INFO - Vendor: Intel (0x8086) 07:45:15 INFO - Device ID: 0x0a2e 07:45:15 INFO - Revision ID: 0x0009 07:45:15 INFO - Displays: 07:45:15 INFO - Display: 07:45:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:45:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:45:15 INFO - Display Serial Number: 1600x1200 60 07:45:15 INFO - Main Display: Yes 07:45:15 INFO - Mirror: Off 07:45:15 INFO - Online: Yes 07:45:15 INFO - Rotation: Supported 07:45:15 INFO - Return code: 0 07:45:15 INFO - Running post-action listener: _resource_record_post_action 07:45:15 INFO - [mozharness: 2016-12-17 15:45:15.051633Z] Finished run-tests step (success) 07:45:15 INFO - Running post-run listener: _resource_record_post_run 07:45:15 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 368640; Write bytes: 655322112; Read time: 5; Write time: 1923 07:45:15 INFO - TinderboxPrint: CPU usage
53.3% 07:45:15 INFO - TinderboxPrint: I/O read bytes / time
368,640 / 5 07:45:15 INFO - TinderboxPrint: I/O write bytes / time
655,322,112 / 1,923 07:45:15 INFO - TinderboxPrint: CPU idle
2,261.3 (46.7%) 07:45:15 INFO - TinderboxPrint: CPU system
968.3 (20.0%) 07:45:15 INFO - TinderboxPrint: CPU user
1,616.1 (33.4%) 07:45:15 INFO - TinderboxPrint: Swap in / out
348,250,112 / 0 07:45:15 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347701760; Write bytes: 345702912; Read time: 26739; Write time: 748 07:45:15 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 204800; Write bytes: 302389760; Read time: 3; Write time: 1141 07:45:15 INFO - Running post-run listener: _upload_blobber_files 07:45:15 INFO - Blob upload gear active. 07:45:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:45:15 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 07:45:15 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'] 07:45:15 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 07:45:15 INFO - (blobuploader) - INFO - Open directory for files ... 07:45:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:45:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:45:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:17 INFO - (blobuploader) - INFO - Done attempting. 07:45:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:45:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:45:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:18 INFO - (blobuploader) - INFO - Done attempting. 07:45:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:45:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:45:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:20 INFO - (blobuploader) - INFO - Done attempting. 07:45:20 INFO - (blobuploader) - INFO - Iteration through files over. 07:45:20 INFO - Return code: 0 07:45:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:45:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:45:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/111bba678e3fb8cfe1729b370026532cd32ff0c9a2b21260291ee9c70ef4d95ae01d1d3f2bdf4422a920321b439b19fdf0582a6fb7c131dbc4e7438f9c88ffa2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/270d444f0485eec881ed7d061b3fed719b70c9f1d44424b953ce95e10ec33fb7864bea6e9317e71049d90e7d9d5cd3f87adbc8039c8332f032cc8b4c7808581a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0c755fd0e70259407a7c9cada1c9ca943ffa01a0c646f1d90f0b104d30f588b5ab8ef1920b326a38e46cce2a1f4dd34ebeadf337e89f0bc0dd32bd40363082"} 07:45:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:45:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:45:20 INFO - Contents: 07:45:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/111bba678e3fb8cfe1729b370026532cd32ff0c9a2b21260291ee9c70ef4d95ae01d1d3f2bdf4422a920321b439b19fdf0582a6fb7c131dbc4e7438f9c88ffa2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/270d444f0485eec881ed7d061b3fed719b70c9f1d44424b953ce95e10ec33fb7864bea6e9317e71049d90e7d9d5cd3f87adbc8039c8332f032cc8b4c7808581a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0c755fd0e70259407a7c9cada1c9ca943ffa01a0c646f1d90f0b104d30f588b5ab8ef1920b326a38e46cce2a1f4dd34ebeadf337e89f0bc0dd32bd40363082"} 07:45:20 INFO - Running post-run listener: copy_logs_to_upload_dir 07:45:20 INFO - Copying logs to upload dir... 07:45:20 INFO - mkdir: /builds/slave/test/build/upload/logs 07:45:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.857503 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-17 07:45:20.263627) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 07:45:20.267348) ========= 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.w6sjZPrPML/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.KVy9eQiW8O/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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/111bba678e3fb8cfe1729b370026532cd32ff0c9a2b21260291ee9c70ef4d95ae01d1d3f2bdf4422a920321b439b19fdf0582a6fb7c131dbc4e7438f9c88ffa2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/270d444f0485eec881ed7d061b3fed719b70c9f1d44424b953ce95e10ec33fb7864bea6e9317e71049d90e7d9d5cd3f87adbc8039c8332f032cc8b4c7808581a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0c755fd0e70259407a7c9cada1c9ca943ffa01a0c646f1d90f0b104d30f588b5ab8ef1920b326a38e46cce2a1f4dd34ebeadf337e89f0bc0dd32bd40363082"} build_url:https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011540 build_url: 'https://queue.taskcluster.net/v1/task/f783eYs-RyypAvaybOVN-Q/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/111bba678e3fb8cfe1729b370026532cd32ff0c9a2b21260291ee9c70ef4d95ae01d1d3f2bdf4422a920321b439b19fdf0582a6fb7c131dbc4e7438f9c88ffa2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/270d444f0485eec881ed7d061b3fed719b70c9f1d44424b953ce95e10ec33fb7864bea6e9317e71049d90e7d9d5cd3f87adbc8039c8332f032cc8b4c7808581a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0c755fd0e70259407a7c9cada1c9ca943ffa01a0c646f1d90f0b104d30f588b5ab8ef1920b326a38e46cce2a1f4dd34ebeadf337e89f0bc0dd32bd40363082"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 07:45:20.302084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 07:45:20.302436) ========= 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.w6sjZPrPML/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.KVy9eQiW8O/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.005153 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 07:45:20.350808) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 07:45:20.351149) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 07:45:20.351488) ========= ========= Total master_lag: 0.18 =========