builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0001 starttime: 1479468202.54 results: success (0) buildid: 20161118004020 builduid: 83a5f4abf7c043e5a7aef121e809f673 revision: 7ad438df29922ee13d21100873883eb9dd93423c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.542526) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.542937) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.543255) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.566131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.566399) ========= 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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-11-18 03:23:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2016-11-18 03:23:22 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128994 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.717189) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.717496) ========= 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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.020541 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:22.766321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-18 03:23:22.766641) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7ad438df29922ee13d21100873883eb9dd93423c --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 7ad438df29922ee13d21100873883eb9dd93423c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-11-18 03:23:22,844 truncating revision to first 12 chars 2016-11-18 03:23:22,845 Setting DEBUG logging. 2016-11-18 03:23:22,845 attempt 1/10 2016-11-18 03:23:22,845 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7ad438df2992?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 03:23:24,851 unpacking tar archive at: mozilla-aurora-7ad438df2992/testing/mozharness/ program finished with exit code 0 elapsedTime=2.319748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-18 03:23:25.108204) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:25.108524) ========= script_repo_revision: 7ad438df29922ee13d21100873883eb9dd93423c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:25.108930) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:25.109251) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 03:23:25.127847) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-18 03:23:25.128115) ========= /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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:23:25 INFO - MultiFileLogger online at 20161118 03:23:25 in /builds/slave/test 03:23:25 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 03:23:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:23:25 INFO - {'append_to_log': False, 03:23:25 INFO - 'base_work_dir': '/builds/slave/test', 03:23:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:23:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:23:25 INFO - 'branch': 'Mozilla-Aurora', 03:23:25 INFO - 'buildbot_json_path': 'buildprops.json', 03:23:25 INFO - 'config_files': ('talos/mac_config.py',), 03:23:25 INFO - 'default_actions': ('clobber', 03:23:25 INFO - 'read-buildbot-config', 03:23:25 INFO - 'download-and-extract', 03:23:25 INFO - 'populate-webroot', 03:23:25 INFO - 'create-virtualenv', 03:23:25 INFO - 'install', 03:23:25 INFO - 'run-tests'), 03:23:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:23:25 INFO - 'download_minidump_stackwalk': True, 03:23:25 INFO - 'download_symbols': 'ondemand', 03:23:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:23:25 INFO - 'tooltool.py': '/tools/tooltool.py', 03:23:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:23:25 INFO - '/tools/misc-python/virtualenv.py')}, 03:23:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:23:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:23:25 INFO - 'installer_path': 'installer.exe', 03:23:25 INFO - 'log_level': 'info', 03:23:25 INFO - 'log_name': 'talos', 03:23:25 INFO - 'log_to_console': True, 03:23:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:23:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:23:25 INFO - 'opt_config_files': (), 03:23:25 INFO - 'pip_index': False, 03:23:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:23:25 INFO - 'cmd': ('bash', 03:23:25 INFO - '-c', 03:23:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:23:25 INFO - 'enabled': True, 03:23:25 INFO - 'halt_on_failure': False, 03:23:25 INFO - 'name': 'check_screen_resolution'},), 03:23:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:23:25 INFO - 'cmd': ('bash', 03:23:25 INFO - '-c', 03:23:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:23:25 INFO - 'enabled': True, 03:23:25 INFO - 'halt_on_failure': False, 03:23:25 INFO - 'name': 'check_screen_resolution'},), 03:23:25 INFO - 'run_cmd_checks_enabled': True, 03:23:25 INFO - 'sps_profile': False, 03:23:25 INFO - 'sps_profile_interval': 0, 03:23:25 INFO - 'suite': 'dromaeojs', 03:23:25 INFO - 'system_bits': '32', 03:23:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:23:25 INFO - 'title': 't-yosemite-r7-0001', 03:23:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:23:25 INFO - 'use_talos_json': True, 03:23:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:23:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:23:25 INFO - 'work_dir': 'build'} 03:23:25 INFO - [mozharness: 2016-11-18 11:23:25.332323Z] Running clobber step. 03:23:25 INFO - Running pre-action listener: _resource_record_pre_action 03:23:25 INFO - Running main action method: clobber 03:23:25 INFO - rmtree: /builds/slave/test/build 03:23:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:23:27 INFO - Running post-action listener: _resource_record_post_action 03:23:27 INFO - [mozharness: 2016-11-18 11:23:27.019834Z] Finished clobber step (success) 03:23:27 INFO - [mozharness: 2016-11-18 11:23:27.020083Z] Running read-buildbot-config step. 03:23:27 INFO - Running pre-action listener: _resource_record_pre_action 03:23:27 INFO - Running main action method: read_buildbot_config 03:23:27 INFO - Using buildbot properties: 03:23:27 INFO - { 03:23:27 INFO - "project": "", 03:23:27 INFO - "product": "firefox", 03:23:27 INFO - "script_repo_revision": "production", 03:23:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:23:27 INFO - "repository": "", 03:23:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:23:27 INFO - "buildid": "20161118004020", 03:23:27 INFO - "stage_platform": "macosx64", 03:23:27 INFO - "basedir": "/builds/slave/test", 03:23:27 INFO - "nightly_build": "True", 03:23:27 INFO - "buildnumber": 24, 03:23:27 INFO - "slavename": "t-yosemite-r7-0001", 03:23:27 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:23:27 INFO - "platform": "yosemite_r7", 03:23:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:23:27 INFO - "branch": "mozilla-aurora", 03:23:27 INFO - "repo_path": "releases/mozilla-aurora", 03:23:27 INFO - "revision": "7ad438df29922ee13d21100873883eb9dd93423c", 03:23:27 INFO - "pgo_build": "False", 03:23:27 INFO - "builduid": "83a5f4abf7c043e5a7aef121e809f673", 03:23:27 INFO - "slavebuilddir": "test" 03:23:27 INFO - } 03:23:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:23:27 INFO - Running post-action listener: _resource_record_post_action 03:23:27 INFO - [mozharness: 2016-11-18 11:23:27.025954Z] Finished read-buildbot-config step (success) 03:23:27 INFO - [mozharness: 2016-11-18 11:23:27.026157Z] Running download-and-extract step. 03:23:27 INFO - Running pre-action listener: _resource_record_pre_action 03:23:27 INFO - Running main action method: download_and_extract 03:23:27 INFO - mkdir: /builds/slave/test/build/tests 03:23: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')]} 03:23:27 INFO - trying https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:23:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/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 03:23:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/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 03:23:28 INFO - Downloaded 1199 bytes. 03:23:28 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:23:28 INFO - Using the following test package requirements: 03:23:28 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:23:28 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:23:28 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:23:28 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'jsshell-mac.zip'], 03:23:28 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:23:28 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:23:28 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:23:28 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:23:28 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:23:28 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:28 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:23:28 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:23:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:23:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:23:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:23:28 INFO - Http code: 200 03:23:28 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 03:23:28 INFO - x-amz-cf-id: 8f3xIn2TTlCecCwO9DTcXl1Q1UODVJjZeiATqDQjUp7LoIg5TNvxxg== 03:23:28 INFO - x-amz-version-id: vcIV7m32PdeGX37PZ0cu0pMu9X6nzEDY 03:23:29 INFO - Expected file size: 16327895 03:23:29 INFO - Obtained file size: 16327895 03:23:35 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 03:23:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:23:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:23:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:23:36 INFO - Http code: 200 03:23:36 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 03:23:36 INFO - x-amz-cf-id: Egz9T5vczpqnQt32TiyhSObHOVdjo3nVS3ZwdNRzNKw-sr-InwgH8A== 03:23:36 INFO - x-amz-version-id: vcIV7m32PdeGX37PZ0cu0pMu9X6nzEDY 03:23:36 INFO - Expected file size: 16327895 03:23:36 INFO - Obtained file size: 16327895 03:23:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:23:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:23:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:23:44 INFO - Http code: 200 03:23:44 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 03:23:44 INFO - x-amz-cf-id: udpIm3wsuYbrm61J06ZVDP8iuNXD6YV9PUrVF83TgL8Nso-XiZP4Rg== 03:23:44 INFO - x-amz-version-id: aziQtoP5WqiXCODf2rILvooJqqCe5mgf 03:23:44 INFO - Expected file size: 14409038 03:23:44 INFO - Obtained file size: 14409038 03:23:45 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')]} 03:23:45 INFO - trying https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:23:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/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 03:23:47 INFO - Downloaded 114591626 bytes. 03:23:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:47 INFO - mkdir: /builds/slave/test/properties 03:23:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:23:47 INFO - Writing to file /builds/slave/test/properties/build_url 03:23:47 INFO - Contents: 03:23:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:48 INFO - Running post-action listener: _resource_record_post_action 03:23:48 INFO - Running post-action listener: set_extra_try_arguments 03:23:48 INFO - [mozharness: 2016-11-18 11:23:48.581850Z] Finished download-and-extract step (success) 03:23:48 INFO - [mozharness: 2016-11-18 11:23:48.582008Z] Running populate-webroot step. 03:23:48 INFO - Running pre-action listener: _resource_record_pre_action 03:23:48 INFO - Running main action method: populate_webroot 03:23:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:23:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:23:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:23:48 INFO - '--tppagecycles', 03:23:48 INFO - '7'], 03:23:48 INFO - 'tests': ['tsvgm']}}, 03:23:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:23:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:23:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:23:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:23:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:23:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:23:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['damp', 'tps']}, 03:23:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'tests': ['damp', 'tps']}, 03:23:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['dromaeo_dom']}, 03:23:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:23:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['basic_compositor_video']}, 03:23:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:23:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['a11yr', 03:23:48 INFO - 'ts_paint', 03:23:48 INFO - 'tpaint', 03:23:48 INFO - 'sessionrestore', 03:23:48 INFO - 'sessionrestore_no_auto_restore', 03:23:48 INFO - 'tabpaint']}, 03:23:48 INFO - 'other-e10s': {'tests': ['a11yr', 03:23:48 INFO - 'ts_paint', 03:23:48 INFO - 'tpaint', 03:23:48 INFO - 'sessionrestore', 03:23:48 INFO - 'sessionrestore_no_auto_restore', 03:23:48 INFO - 'tabpaint']}, 03:23:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:23:48 INFO - 'ts_paint', 03:23:48 INFO - 'tpaint', 03:23:48 INFO - 'sessionrestore', 03:23:48 INFO - 'sessionrestore_no_auto_restore', 03:23:48 INFO - 'tabpaint']}, 03:23:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:23:48 INFO - 'ts_paint', 03:23:48 INFO - 'tpaint', 03:23:48 INFO - 'sessionrestore', 03:23:48 INFO - 'sessionrestore_no_auto_restore', 03:23:48 INFO - 'tabpaint']}, 03:23:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['a11yr', 03:23:48 INFO - 'ts_paint', 03:23:48 INFO - 'tpaint', 03:23:48 INFO - 'sessionrestore', 03:23:48 INFO - 'sessionrestore_no_auto_restore', 03:23:48 INFO - 'tabpaint']}, 03:23:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['a11yr', 03:23:48 INFO - 'ts_paint', 03:23:48 INFO - 'tpaint', 03:23:48 INFO - 'sessionrestore', 03:23:48 INFO - 'sessionrestore_no_auto_restore', 03:23:48 INFO - 'tabpaint']}, 03:23:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['tsvgx', 03:23:48 INFO - 'tsvgr_opacity', 03:23:48 INFO - 'tart', 03:23:48 INFO - 'tscrollx', 03:23:48 INFO - 'cart']}, 03:23:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:23:48 INFO - 'tsvgr_opacity', 03:23:48 INFO - 'tart', 03:23:48 INFO - 'tscrollx', 03:23:48 INFO - 'cart']}, 03:23:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'talos_options': ['--disable-e10s'], 03:23:48 INFO - 'tests': ['tp5o']}, 03:23:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'tests': ['tp5o']}, 03:23:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'talos_options': ['--disable-e10s', 03:23:48 INFO - '--xperf_path', 03:23:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:23:48 INFO - 'tests': ['tp5n']}, 03:23:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:48 INFO - 'talos_options': ['--xperf_path', 03:23:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:23:48 INFO - 'tests': ['tp5n']}}, 03:23:48 INFO - 'talos.zip': {'path': '', 03:23:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:23:48 INFO - Running post-action listener: _resource_record_post_action 03:23:48 INFO - [mozharness: 2016-11-18 11:23:48.600641Z] Finished populate-webroot step (success) 03:23:48 INFO - [mozharness: 2016-11-18 11:23:48.600772Z] Running create-virtualenv step. 03:23:48 INFO - Running pre-action listener: _resource_record_pre_action 03:23:48 INFO - Running main action method: create_virtualenv 03:23:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:23:48 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 03:23:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:23:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:23:48 INFO - Using real prefix '/tools/python27' 03:23:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:23:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:23:52 INFO - Installing pip.................done. 03:23:52 INFO - Return code: 0 03:23:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:23:52 INFO - Reading from file tmpfile_stdout 03:23:52 INFO - Output received: 03:23:52 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) 03:23:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:23: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')]} 03:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23: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')]} 03:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:52 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:23:52 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 03:23:52 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 03:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:52 INFO - 'HOME': '/Users/cltbld', 03:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:52 INFO - 'LOGNAME': 'cltbld', 03:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:52 INFO - 'MOZ_NO_REMOTE': '1', 03:23:52 INFO - 'NO_EM_RESTART': '1', 03:23:52 INFO - 'PAGER': '/bin/cat', 03:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:52 INFO - 'PWD': '/builds/slave/test', 03:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:52 INFO - 'SHELL': '/bin/bash', 03:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:23:52 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:52 INFO - 'USER': 'cltbld', 03:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:52 INFO - 'XPC_FLAGS': '0x0', 03:23:52 INFO - 'XPC_SERVICE_NAME': '0', 03:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:52 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 03:23:52 INFO - Cleaning up... 03:23:52 INFO - Return code: 0 03:23:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:23: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')]} 03:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23: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')]} 03:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:52 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:23:52 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 03:23:52 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 03:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:52 INFO - 'HOME': '/Users/cltbld', 03:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:52 INFO - 'LOGNAME': 'cltbld', 03:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:52 INFO - 'MOZ_NO_REMOTE': '1', 03:23:52 INFO - 'NO_EM_RESTART': '1', 03:23:52 INFO - 'PAGER': '/bin/cat', 03:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:52 INFO - 'PWD': '/builds/slave/test', 03:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:52 INFO - 'SHELL': '/bin/bash', 03:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:23:52 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:52 INFO - 'USER': 'cltbld', 03:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:52 INFO - 'XPC_FLAGS': '0x0', 03:23:52 INFO - 'XPC_SERVICE_NAME': '0', 03:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:53 INFO - Downloading/unpacking psutil>=3.1.1 03:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:23:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:23:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:23:56 INFO - Installing collected packages: psutil 03:23:56 INFO - Running setup.py install for psutil 03:23:56 INFO - building 'psutil._psutil_osx' extension 03:23:56 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 03:23:56 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 03:23:56 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 03:23:56 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 03:23:56 INFO - building 'psutil._psutil_posix' extension 03:23:56 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 03:23:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:23:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:23:57 INFO - ^ 03:23:57 INFO - 1 warning generated. 03:23:57 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 03:23:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:23:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:23:57 INFO - Successfully installed psutil 03:23:57 INFO - Cleaning up... 03:23:57 INFO - Return code: 0 03:23:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:57 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:23:57 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 03:23:57 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 03:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:57 INFO - 'HOME': '/Users/cltbld', 03:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:57 INFO - 'LOGNAME': 'cltbld', 03:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:57 INFO - 'MOZ_NO_REMOTE': '1', 03:23:57 INFO - 'NO_EM_RESTART': '1', 03:23:57 INFO - 'PAGER': '/bin/cat', 03:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:57 INFO - 'PWD': '/builds/slave/test', 03:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:57 INFO - 'SHELL': '/bin/bash', 03:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:23:57 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:57 INFO - 'USER': 'cltbld', 03:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:57 INFO - 'XPC_FLAGS': '0x0', 03:23:57 INFO - 'XPC_SERVICE_NAME': '0', 03:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:23:57 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 03:23:57 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 03:23:57 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 03:23:57 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 03:23:57 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 03:23:57 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 03:24:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:24:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:24:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:24:00 INFO - Installing collected packages: mozsystemmonitor 03:24:00 INFO - Running setup.py install for mozsystemmonitor 03:24:00 INFO - Successfully installed mozsystemmonitor 03:24:00 INFO - Cleaning up... 03:24:00 INFO - Return code: 0 03:24:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:24:00 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')]} 03:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:00 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')]} 03:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:00 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:24:00 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 03:24:00 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 03:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:00 INFO - 'HOME': '/Users/cltbld', 03:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:00 INFO - 'LOGNAME': 'cltbld', 03:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:00 INFO - 'MOZ_NO_REMOTE': '1', 03:24:00 INFO - 'NO_EM_RESTART': '1', 03:24:00 INFO - 'PAGER': '/bin/cat', 03:24:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:00 INFO - 'PWD': '/builds/slave/test', 03:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:00 INFO - 'SHELL': '/bin/bash', 03:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:00 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:00 INFO - 'USER': 'cltbld', 03:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:00 INFO - 'XPC_FLAGS': '0x0', 03:24:00 INFO - 'XPC_SERVICE_NAME': '0', 03:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:00 INFO - Downloading/unpacking jsonschema==2.5.1 03:24:00 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 03:24:00 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 03:24:00 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 03:24:00 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 03:24:00 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 03:24:00 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 03:24:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:24:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:24:03 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 03:24:03 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 03:24:03 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 03:24:03 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 03:24:03 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 03:24:03 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 03:24:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:24:03 INFO - warning: no files found matching '*.txt' 03:24:03 INFO - no previously-included directories found matching 'build' 03:24:03 INFO - no previously-included directories found matching 'dist' 03:24:03 INFO - no previously-included directories found matching '.git*' 03:24:03 INFO - Installing collected packages: jsonschema, functools32 03:24:03 INFO - Running setup.py install for functools32 03:24:03 INFO - warning: no files found matching '*.txt' 03:24:03 INFO - no previously-included directories found matching 'build' 03:24:03 INFO - no previously-included directories found matching 'dist' 03:24:03 INFO - no previously-included directories found matching '.git*' 03:24:03 INFO - Successfully installed jsonschema functools32 03:24:03 INFO - Cleaning up... 03:24:03 INFO - Return code: 0 03:24:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:24:03 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')]} 03:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:03 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')]} 03:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:04 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:24:04 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 03:24:04 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 03:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:04 INFO - 'HOME': '/Users/cltbld', 03:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:04 INFO - 'LOGNAME': 'cltbld', 03:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:04 INFO - 'MOZ_NO_REMOTE': '1', 03:24:04 INFO - 'NO_EM_RESTART': '1', 03:24:04 INFO - 'PAGER': '/bin/cat', 03:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:04 INFO - 'PWD': '/builds/slave/test', 03:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:04 INFO - 'SHELL': '/bin/bash', 03:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:04 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:04 INFO - 'USER': 'cltbld', 03:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:04 INFO - 'XPC_FLAGS': '0x0', 03:24:04 INFO - 'XPC_SERVICE_NAME': '0', 03:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:24:04 INFO - Cleaning up... 03:24:04 INFO - Return code: 0 03:24:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:24:04 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')]} 03:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:04 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')]} 03:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:04 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:24:04 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 03:24:04 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 03:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:04 INFO - 'HOME': '/Users/cltbld', 03:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:04 INFO - 'LOGNAME': 'cltbld', 03:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:04 INFO - 'MOZ_NO_REMOTE': '1', 03:24:04 INFO - 'NO_EM_RESTART': '1', 03:24:04 INFO - 'PAGER': '/bin/cat', 03:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:04 INFO - 'PWD': '/builds/slave/test', 03:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:04 INFO - 'SHELL': '/bin/bash', 03:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:04 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:04 INFO - 'USER': 'cltbld', 03:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:04 INFO - 'XPC_FLAGS': '0x0', 03:24:04 INFO - 'XPC_SERVICE_NAME': '0', 03:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:04 INFO - Downloading/unpacking blobuploader==1.2.4 03:24:04 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 03:24:04 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 03:24:04 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 03:24:04 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 03:24:04 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 03:24:04 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 03:24:07 INFO - Downloading blobuploader-1.2.4.tar.gz 03:24:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:24:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:24:07 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 03:24:07 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 03:24:07 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 03:24:07 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 03:24:07 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 03:24:07 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 03:24:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:24:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:24:07 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 03:24:07 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 03:24:07 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 03:24:07 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 03:24:07 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 03:24:07 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 03:24:08 INFO - Downloading docopt-0.6.1.tar.gz 03:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:24:08 INFO - Installing collected packages: blobuploader, requests, docopt 03:24:08 INFO - Running setup.py install for blobuploader 03:24:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:24:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:24:08 INFO - Running setup.py install for requests 03:24:08 INFO - Running setup.py install for docopt 03:24:09 INFO - Successfully installed blobuploader requests docopt 03:24:09 INFO - Cleaning up... 03:24:09 INFO - Return code: 0 03:24:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:09 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:24:09 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 03:24:09 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 03:24:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:09 INFO - 'HOME': '/Users/cltbld', 03:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:09 INFO - 'LOGNAME': 'cltbld', 03:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:09 INFO - 'MOZ_NO_REMOTE': '1', 03:24:09 INFO - 'NO_EM_RESTART': '1', 03:24:09 INFO - 'PAGER': '/bin/cat', 03:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:09 INFO - 'PWD': '/builds/slave/test', 03:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:09 INFO - 'SHELL': '/bin/bash', 03:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:09 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:09 INFO - 'USER': 'cltbld', 03:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:09 INFO - 'XPC_FLAGS': '0x0', 03:24:09 INFO - 'XPC_SERVICE_NAME': '0', 03:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:24:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-uYKa4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:24:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-nvLT0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:24:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-mdH7yb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:24:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-jJzBwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:24:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-IwVTOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-XrFaPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-QYF9Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-wttDuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-noAs8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-W2icds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-SVJ2Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-vvZP6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-NFg6bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-emYy35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-UdMkCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:24:11 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-wDYbX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:24:11 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-cKkjb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:24:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:24:11 INFO - Running setup.py install for manifestparser 03:24:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:24:11 INFO - Running setup.py install for mozcrash 03:24:11 INFO - Running setup.py install for mozdebug 03:24:11 INFO - Running setup.py install for mozdevice 03:24:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:24:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:24:11 INFO - Running setup.py install for mozfile 03:24:12 INFO - Running setup.py install for mozhttpd 03:24:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Running setup.py install for mozinfo 03:24:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Running setup.py install for mozInstall 03:24:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Running setup.py install for mozleak 03:24:12 INFO - Running setup.py install for mozlog 03:24:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Running setup.py install for moznetwork 03:24:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:24:12 INFO - Running setup.py install for mozprocess 03:24:13 INFO - Running setup.py install for mozprofile 03:24:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:24:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:24:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:24:13 INFO - Running setup.py install for mozrunner 03:24:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:24:13 INFO - Running setup.py install for mozscreenshot 03:24:13 INFO - Running setup.py install for moztest 03:24:13 INFO - Running setup.py install for mozversion 03:24:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:24:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:24:13 INFO - Cleaning up... 03:24:13 INFO - Return code: 0 03:24:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:13 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:24:13 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 03:24:13 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 03:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:13 INFO - 'HOME': '/Users/cltbld', 03:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:13 INFO - 'LOGNAME': 'cltbld', 03:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:13 INFO - 'MOZ_NO_REMOTE': '1', 03:24:13 INFO - 'NO_EM_RESTART': '1', 03:24:13 INFO - 'PAGER': '/bin/cat', 03:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:13 INFO - 'PWD': '/builds/slave/test', 03:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:13 INFO - 'SHELL': '/bin/bash', 03:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:13 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:13 INFO - 'USER': 'cltbld', 03:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:13 INFO - 'XPC_FLAGS': '0x0', 03:24:13 INFO - 'XPC_SERVICE_NAME': '0', 03:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-5jlQzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:24:14 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)) 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-wbiGPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:24:14 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)) 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-xSSZ2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:24:14 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)) 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-fuHVv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:24:14 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)) 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-PHpIrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:24:14 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)) 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-mFrrNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:24:14 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)) 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-D_y3z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:24:14 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)) 03:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:24:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-BFafoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Psrt9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-YfjqP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-nUyquG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-lKteI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-P5t6vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ADl9mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-NGEwmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ajTd9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:24:15 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)) 03:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:24:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-twg4vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:24:16 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)) 03:24:16 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)) 03:24:16 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)) 03:24:16 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)) 03:24:16 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)) 03:24:16 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)) 03:24:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:18 INFO - Downloading blessings-1.6.tar.gz 03:24:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:24:18 INFO - Installing collected packages: blessings 03:24:18 INFO - Running setup.py install for blessings 03:24:18 INFO - Successfully installed blessings 03:24:18 INFO - Cleaning up... 03:24:18 INFO - Return code: 0 03:24:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:24:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:24:19 INFO - Reading from file tmpfile_stdout 03:24:19 INFO - Current package versions: 03:24:19 INFO - blessings == 1.6 03:24:19 INFO - blobuploader == 1.2.4 03:24:19 INFO - docopt == 0.6.1 03:24:19 INFO - functools32 == 3.2.3-2 03:24:19 INFO - jsonschema == 2.5.1 03:24:19 INFO - manifestparser == 1.1 03:24:19 INFO - mozInstall == 1.12 03:24:19 INFO - mozcrash == 1.0 03:24:19 INFO - mozdebug == 0.1 03:24:19 INFO - mozdevice == 0.48 03:24:19 INFO - mozfile == 1.2 03:24:19 INFO - mozhttpd == 0.7 03:24:19 INFO - mozinfo == 0.9 03:24:19 INFO - mozleak == 0.1 03:24:19 INFO - mozlog == 3.4 03:24:19 INFO - moznetwork == 0.27 03:24:19 INFO - mozprocess == 0.23 03:24:19 INFO - mozprofile == 0.28 03:24:19 INFO - mozrunner == 6.13 03:24:19 INFO - mozscreenshot == 0.1 03:24:19 INFO - mozsystemmonitor == 0.3 03:24:19 INFO - moztest == 0.7 03:24:19 INFO - mozversion == 1.4 03:24:19 INFO - psutil == 3.1.1 03:24:19 INFO - requests == 1.2.3 03:24:19 INFO - wsgiref == 0.1.2 03:24:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24:19 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')]} 03:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:20 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:24:20 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 03:24:20 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 03:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:20 INFO - 'HOME': '/Users/cltbld', 03:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:20 INFO - 'LOGNAME': 'cltbld', 03:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:20 INFO - 'MOZ_NO_REMOTE': '1', 03:24:20 INFO - 'NO_EM_RESTART': '1', 03:24:20 INFO - 'PAGER': '/bin/cat', 03:24:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:20 INFO - 'PWD': '/builds/slave/test', 03:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:20 INFO - 'SHELL': '/bin/bash', 03:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:20 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:20 INFO - 'USER': 'cltbld', 03:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:20 INFO - 'XPC_FLAGS': '0x0', 03:24:20 INFO - 'XPC_SERVICE_NAME': '0', 03:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 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)) 03:24:20 INFO - Cleaning up... 03:24:20 INFO - Return code: 0 03:24:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:21 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 0x107755490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0234d3a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, '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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 03:24:21 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 03:24:21 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 03:24:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:21 INFO - 'HOME': '/Users/cltbld', 03:24:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:21 INFO - 'LOGNAME': 'cltbld', 03:24:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:21 INFO - 'MOZ_NO_REMOTE': '1', 03:24:21 INFO - 'NO_EM_RESTART': '1', 03:24:21 INFO - 'PAGER': '/bin/cat', 03:24:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:21 INFO - 'PWD': '/builds/slave/test', 03:24:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:21 INFO - 'SHELL': '/bin/bash', 03:24:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:21 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:21 INFO - 'USER': 'cltbld', 03:24:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:21 INFO - 'XPC_FLAGS': '0x0', 03:24:21 INFO - 'XPC_SERVICE_NAME': '0', 03:24:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:24:21 INFO - Cleaning up... 03:24:21 INFO - Return code: 0 03:24:21 INFO - Running post-action listener: _resource_record_post_action 03:24:21 INFO - Running post-action listener: _start_resource_monitoring 03:24:21 INFO - Starting resource monitoring. 03:24:21 INFO - [mozharness: 2016-11-18 11:24:21.554597Z] Finished create-virtualenv step (success) 03:24:21 INFO - [mozharness: 2016-11-18 11:24:21.555660Z] Running install step. 03:24:21 INFO - Running pre-action listener: _resource_record_pre_action 03:24:21 INFO - Running main action method: install 03:24:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:24:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:24:21 INFO - Reading from file tmpfile_stdout 03:24:21 INFO - Detecting whether we're running mozinstall >=1.0... 03:24:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:24:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:24:21 INFO - Reading from file tmpfile_stdout 03:24:21 INFO - Output received: 03:24:21 INFO - Usage: mozinstall [options] installer 03:24:21 INFO - Options: 03:24:21 INFO - -h, --help show this help message and exit 03:24:21 INFO - -d DEST, --destination=DEST 03:24:21 INFO - Directory to install application into. [default: 03:24:21 INFO - "/builds/slave/test"] 03:24:21 INFO - --app=APP Application being installed. [default: firefox] 03:24:21 INFO - mkdir: /builds/slave/test/build/application 03:24:21 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'] 03:24:21 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 03:24:53 INFO - Reading from file tmpfile_stdout 03:24:53 INFO - Output received: 03:24:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:24:53 INFO - Running post-action listener: _resource_record_post_action 03:24:53 INFO - [mozharness: 2016-11-18 11:24:53.845828Z] Finished install step (success) 03:24:53 INFO - [mozharness: 2016-11-18 11:24:53.845954Z] Running run-tests step. 03:24:53 INFO - Running pre-action listener: _resource_record_pre_action 03:24:53 INFO - Running main action method: run_tests 03:24:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:24:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:24:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:24:53 INFO - 2016-11-18 03:24:53.860 screenresolution[6777:122929] starting screenresolution argv=screenresolution get 03:24:53 INFO - 2016-11-18 03:24:53.863 screenresolution[6777:122929] Display 0: 1600x1200x32@60 03:24:53 INFO - 2016-11-18 03:24:53.869 screenresolution[6778:122931] starting screenresolution argv=screenresolution list 03:24:53 INFO - Available Modes on Display 0 03:24:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:24:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:24:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:24:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:24:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:24:54 INFO - Intel Iris: 03:24:54 INFO - Chipset Model: Intel Iris 03:24:54 INFO - Type: GPU 03:24:54 INFO - Bus: Built-In 03:24:54 INFO - VRAM (Dynamic, Max): 1536 MB 03:24:54 INFO - Vendor: Intel (0x8086) 03:24:54 INFO - Device ID: 0x0a2e 03:24:54 INFO - Revision ID: 0x0009 03:24:54 INFO - Displays: 03:24:54 INFO - Display: 03:24:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:24:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:24:54 INFO - Display Serial Number: 1600x1200 60 03:24:54 INFO - Main Display: Yes 03:24:54 INFO - Mirror: Off 03:24:54 INFO - Online: Yes 03:24:54 INFO - Rotation: Supported 03:24:54 INFO - Return code: 0 03:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:24:54 INFO - Python 2.7.3 03:24:54 INFO - Return code: 0 03:24:54 INFO - grabbing minidump binary from tooltool 03:24:54 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')]} 03:24:54 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 0x107569d30>, '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 0x1073cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107561b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:24:54 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 03:24:54 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 03:24:54 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 03:24:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:24:54 INFO - Return code: 0 03:24:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:24:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:24:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:24:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:24:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:24:54 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/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 03:24:54 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/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0001 --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 03:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YlHb0FRWvX/Render', 03:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:54 INFO - 'HOME': '/Users/cltbld', 03:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:54 INFO - 'LOGNAME': 'cltbld', 03:24:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:24:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:54 INFO - 'MOZ_NO_REMOTE': '1', 03:24:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:24:54 INFO - 'NO_EM_RESTART': '1', 03:24:54 INFO - 'PAGER': '/bin/cat', 03:24:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:54 INFO - 'PWD': '/builds/slave/test', 03:24:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:54 INFO - 'SHELL': '/bin/bash', 03:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sIi9Yh5YQF/Listeners', 03:24:54 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 03:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:54 INFO - 'USER': 'cltbld', 03:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:54 INFO - 'XPC_FLAGS': '0x0', 03:24:54 INFO - 'XPC_SERVICE_NAME': '0', 03:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:54 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/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 03:24:54 INFO - mozversion application_buildid: 20161118004020 03:24:54 INFO - mozversion application_changeset: 7ad438df29922ee13d21100873883eb9dd93423c 03:24:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:24:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:24:54 INFO - mozversion application_name: Firefox 03:24:54 INFO - mozversion application_remotingname: firefox-dev 03:24:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:24:54 INFO - mozversion application_vendor: Mozilla 03:24:54 INFO - mozversion application_version: 52.0a2 03:24:54 INFO - mozversion platform_buildid: 20161118004020 03:24:54 INFO - mozversion platform_changeset: 7ad438df29922ee13d21100873883eb9dd93423c 03:24:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:24:54 INFO - mozversion platform_version: 52.0a2 03:24:54 INFO - using testdate: 1479468294 03:24:54 INFO - actual date: 1479468294 03:24:54 INFO - starting webserver on 'localhost:60188' 03:24:54 INFO - SUITE-START | Running 2 tests 03:24:54 INFO - TEST-START | dromaeo_css 03:24:54 INFO - operating with platform_type : mac_ 03:24:54 INFO - Initialising browser for dromaeo_css test... 03:24:54 INFO - TEST-INFO | started process 6788 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmp48sg0w/profile http://localhost:60188/getInfo.html) 03:24:56 ERROR - PROCESS | 6788 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 03:24:56 INFO - PROCESS | 6788 | __metrics Screen width/height:1600/1200 03:24:56 INFO - PROCESS | 6788 | colorDepth:24 03:24:56 INFO - PROCESS | 6788 | Browser inner width/height: 1024/689 03:24:56 INFO - PROCESS | 6788 | __metrics 03:24:56 INFO - PROCESS | 6788 | 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" 03:24:56 INFO - PROCESS | 6788 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:24:57 INFO - TEST-INFO | 6788: exit 0 03:24:57 INFO - Browser initialized. 03:24:57 INFO - Running cycle 1/1 for dromaeo_css test... 03:24:57 INFO - TEST-INFO | started process 6791 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmp48sg0w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:25:09 INFO - PROCESS | 6791 | RSS: Main: 217104384 03:25:09 INFO - PROCESS | 6791 | 03:26:01 INFO - PROCESS | 6791 | 1479468361651 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] 03:28:11 INFO - PROCESS | 6791 | Cycle 1(1): loaded http://localhost:60188/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60188/tests/dromaeo/cssquery-ext.html) 03:28:14 INFO - PROCESS | 6791 | RSS: Main: 454725632 03:28:14 INFO - PROCESS | 6791 | 03:31:17 INFO - PROCESS | 6791 | Cycle 1(1): loaded http://localhost:60188/tests/dromaeo/cssquery-ext.html (next: http://localhost:60188/tests/dromaeo/cssquery-jquery.html) 03:31:18 INFO - PROCESS | 6791 | RSS: Main: 348024832 03:31:18 INFO - PROCESS | 6791 | 03:34:20 INFO - PROCESS | 6791 | Cycle 1(1): loaded http://localhost:60188/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60188/tests/dromaeo/cssquery-mootools.html) 03:34:21 INFO - PROCESS | 6791 | RSS: Main: 319893504 03:34:21 INFO - PROCESS | 6791 | 03:37:23 INFO - PROCESS | 6791 | Cycle 1(1): loaded http://localhost:60188/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60188/tests/dromaeo/cssquery-prototype.html) 03:37:24 INFO - PROCESS | 6791 | RSS: Main: 329150464 03:37:24 INFO - PROCESS | 6791 | 03:40:27 INFO - PROCESS | 6791 | Cycle 1(1): loaded http://localhost:60188/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60188/tests/dromaeo/cssquery-yui.html) 03:40:28 INFO - PROCESS | 6791 | RSS: Main: 312573952 03:40:28 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | Cycle 1(1): loaded http://localhost:60188/tests/dromaeo/cssquery-yui.html (next: http://localhost:60188/tests/dromaeo/cssquery-dojo.html) 03:43:31 INFO - PROCESS | 6791 | RSS: Main: 365457408 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | __start_tp_report 03:43:31 INFO - PROCESS | 6791 | _x_x_mozilla_page_load 03:43:31 INFO - PROCESS | 6791 | _x_x_mozilla_page_load_details 03:43:31 INFO - PROCESS | 6791 | |i|pagename|runs| 03:43:31 INFO - PROCESS | 6791 | |0;dojo.html;3128.7966282191655;3140.277736120692;3140.874365025399;3148.2286839724266;3180.4275230458525;5206.375234971804;5269.130593452081;5282.286891269678;5285.497877701622;5293.8941221175555;5198.844034678957;5219.45195754446;5245.921311180326;5263.921041184385;5282.2076688496745;4815.34992775976;4821.855344339658;4844.297576851354;4848.587870031051;4892.996935628205;4794.065157294329;4799.016201678665;4801.5438533339275;4840.443349014873;4923.458419573844;4805.159097158001;4811.8075276988875;4824.831130910419;4858.368412106422;4890.266460031006;4811.254255590372;4838.879028024292;4842.055799119174;4868.245421959602;4900.950990490085;4739.6208303336;4796.400449943757;4817.735024573647;4831.251156070814;4849.127157111719;5157.638965272433;5158.690478571298;5164.664296820694;5169.4572069932865;5182.948170518284;5590.133529302964;5593.692346920918;5633.887322253573;5645.463680950304;5673.631213971079;5697.401772813836;5714.971305165069;5716.3426205986425;5744.856378590527;5746.913796293059;5183.766730497099;5254.763535640944;5270.209468579744;5317.069512835315;5332.760025798849;5764.164196191528;5771.220885180531;5776.451237132465;5780.479756821822;5789.044807606782;5437.429069947678;5525.254090697705;5525.502704756591;5540.556755459554;5547.389787123413;5562.3047119110115;5596.776128954796;5611.130274807411;5623.240862483594;5653.491185793298;5553.111502159716;5557.3886872444;5565.693886836263;5569.749361278752;5584.720763961785;5578.246936663499;5584.385717571064;5593.077142271561;5593.133064374947;5604.579656525777;4444.888877778081;4534.274516077412;4537.682362234678;4544.522825103382;4570.862874113782;4137.91724165515;4163.437935878678;4163.646090082317;4193.937090943638;4214.494260688675;5242.947570524322;5262.473752624707;5282.049231138432;5303.28405665238;5313.442088580643;5587.804426845041;5642;5650.802221922213;5678.346990096109;5708.65748055118;5214.191800270964;5260.263563101169;5331.946680533222;5336.46635336471;5337.306150200527;4229.175310814362;4960.683573641864;5005.774740136702;5022.372203474566;5031.345925029723;5771.163181338598;5809.941900581025;5813.505852002627;5817.88364232713;5843.795467158714;5542.667439953616;5600.0479918414785;5619.213310136666;5632.464915832989;5642.181883626769;5754.7122643866805;5755.453231942959;5761.539076873756;5764.164196191613;5765.654060756368;5119.48805119485;5486.61593688438;5528.308961379827;5552.666839989613;5556.9444305558045;5130.589552835839;5604.131359639263;5608.7195640217005;5609.915851262316;5610.438956104357;5425.969065877471;5438.945610543844;5439.782408703608;5450.509454149145;5450.727463626882;5415.945840541544;5426.348838139448;5438.537724293479;5440.292761940922;5445.50990410865;5342.839714808562;5353.9732301338245;5366.624336296578;5369.1140961740875;5418;5477.698726570077;5505.394406615189;5518.310211223597;5520.1167813149705;5558.610897237317;5236.659617124875;5330.093884039645;5333;5395.433479484597;5405.243265942692 03:43:31 INFO - PROCESS | 6791 | |1;ext.html;452.3192595144307;456.97486638234886;511.0519985427034;511.567725272145;525.233159587003;29005.564916526244;39249;40426.595734042654;40629.7968510157;42250.15499690012;40899.386509202384;41280;41315.17369652613;41433.58566414342;41451.79274103625;8446.493210407365;9260.212881905047;9436.292278079172;9443.244540436748;9448.81102377952;8519.062903080656;10715.142788576895;11070.446477676125;11296.887031129707;11480.253783504088;9574.760630984212;11215.607453739121;11289.15331350148;11306.773864522705;11361.488733006992;8541.572921353969;11316.886831131707;11394.658160255205;11394.71513212168;11404;8502;11016.394098324588;11326.093912486982;11343.489542970587;11471.19701620887;39349;39544.20911581781;39613.80193099045;39678;39703.40444893329;3237.983810080958;3238.2066393733685;3276.5249038889465;3279.9836000820083;3454.3954807908517;29143.854280728672;29211;29935.401291974256;30177.396452070836;30847.845760771277;3205.2627895584146;3206.7113959743733;3216.244182617083;3256.202230453554;3364.142143753327;453183;456114;456997;457221.9999999967;457339;59557.70221148953;59781;59875;59911;60063.699681502185;63980.04029939554;64005.35994640087;64105;64126.038109428395;64221.67889160618;15938.123403212934;16370.263338149813;16415.91792041032;16450.848440609043;16451.83548164527;9390.483523406278;11643.825342619868;12535.43590538428;12673.556425525063;12760.6809829755;15155.621109472351;15451.459198927983;16773.161341932853;17282.308707651806;17737.645247094984;5820.30156381237;5932.317783454958;7026.578405295699;7047.154341479055;7093.290670932969;829.4318391901567;941.3457646935451;941.4962994797751;965.9275554333453;1007.6070332570304;28329.433411331658;29437.411251774844;29625.55671633574;30049.849750751106;30211.395772084434;1041.8280983637615;1044.368157264859;1063.707480442869;1067.1302888146136;1077.7521170130915;15409.460668876536;15417.460388886335;15438.845611543966;15486;15547.833912456612;586993.0650346807;592772;592812;593114;594300;57332.140017899765;57472.71263643655;57518.13722794162;57523.13715294274;57572.712136439884;1228.5392977633387;1268.2517314784507;1313.1792629606496;1370.6915943912738;1378.5588611644177;27923;27959.58060629133;28700.282492937007;30767.38465230638;31247.843760781052;49612.50387496223;50204.24693629672;50252.49747502478;50332.49667503424;50441.49558504368;442704;473418;476484;478804;479634;25876.223713288633;26143.73856261413;26677.599836002864;39857.40213896852;40145.59854401419;45798.77100614476;46460.7676961613;46635.53364466312;46975.29537056878;47252.29121563111;36377.81811090928;38194.236115278094;39088.21823563456;39797.00507487336;40614.59385406108;39005.609943901334;39063;39184.608153918096;39984.4002339971;40409;38152.236955261294;38478.80760596179;38606;38717.419238712006;38761;27381.72618273792;33159.17102072371;34840.30319393649;34914.82542587271;34963.300733984666;25407.745922540536;25446.872765636053;25550.744492554837;25920.611190831776;25990.74009259959 03:43:31 INFO - PROCESS | 6791 | |2;jquery.html;349.28222502756824;368.89855289795304;371.9925601487972;380.09537301223094;400.6814582406988;14827.925860370697;14849.40602375905;15730.921345393286;19524.51188720283;20356.287529936457;18888.527786805338;19280;19720.30978915738;20039.899800500978;20269.087891044903;5609.467100625444;5898.439648233432;5898.7640494380175;5900.586958912878;5906.822795316148;4651.906961860761;5828.591998560102;5899;5900.114982752579;5909.881802363951;5611.579131565129;5851.736671849756;5861.296644402656;5862.208601838761;5874.1776151338845;5708.200851880719;5826.038703613898;5826.242588463472;5829.766809327622;5835.5915085944;4791.448983366888;5044.268581055763;5102.591792656579;5111.821086262;5112.539871411554;12713.237205767591;13001.089923705344;13005;13018.60944171667;17901.7314740279;2258.9435264118365;2456.570100232452;2742.4103817679293;2836.957445638317;2841.4317136572563;8987.32595055367;9720.270979676552;10218.744531386701;10280.228982826318;10286.022827831344;1734.037609126934;1754.2018381636324;1777.5733823882304;1783.9464816055538;1796.0391190712905;97061.54407683891;112717.43641281905;118557;121860.1720974186;128939;8986.775330616787;9328.860067098998;9675.419474831533;9680.419174849532;9837.754056148511;12105.515779368903;12117.939410302892;12144.878551214551;12191.939040304918;12242.877571224173;6887.311268873173;7672.079350477867;7734.99445072137;7868.370530357558;8045.9597702011115;8313.293370063613;8337.833243335099;8355.791105222297;8484.745457636282;8491.61787719554;3753.8123093845197;3769.359208934488;3780.584135745066;3834.827432765532;3851.114243723959;1584.698907207627;1921.28912302449;1921.9135138918502;1923.884566925989;1948.824605785486;821.5194111444753;882.9072947340566;890.3366991591176;900.342749792655;930.9805762689844;19074.80925190758;20592.661477003945;20634.79365206359;20638.859819604506;20822.293101896656;870.6517393042834;900.8288425199191;901.8196360727881;942.533445944262;991.2664628175243;10230.02814732614;10503;10715.142788577052;10723.088537474401;10744.30162039465;588558;598729.0063549654;608104;611669;611918;84286.578567108;87357;113922;114558;127311;979.9461029643438;988.906053924876;1015.1979935850743;1016.5171543516918;1017.623479312659;17677.55806104806;17863.374781882572;18276.177572009557;18317.267309308012;18544.629107418044;55942.88114237774;56227.71886140543;56589;56656.86686266334;57221;78462.6076869612;80673;81637.18362816295;84286;84316.57841710752;7690.8077298065755;7839.843203135792;7921.603919803872;8280.58597070156;8314.875276870973;11731.472083756422;12942.741145176855;12998.02514811374;13017.414216360097;13502.189868607522;10830.079443247412;11180.608678696206;11246.493907774333;11406.942965285121;11781.587644432404;9619.855702164614;11070.501827417624;11178.608748693756;11463.885361146282;11498.597549085742;11182.384968826793;11509.53961841517;11670.416479175838;11784.292942423483;11826.46780894879;7977.242161994601;8095.959520202361;8168.591570421337;8335.583220838813;8486.830263394573;9337.112974267433;10036.096751292418;10425.270231083752;10461.529231184462;10576.629817956335 03:43:31 INFO - PROCESS | 6791 | |3;mootools.html;1308.7775078236712;1358.6195865157756;1450.9564713058605;1587.7221486239905;1810.3754204799347;7558.697652093916;7956;8168.346532277418;8398.45410048348;8413.411061225717;8297.75106746797;8395.83208335833;8398.91601083989;8422.11567785383;8427.283680887116;2287.1880482428746;2306.9769302307013;2312.8034117099983;2321.6401457774073;2325.848819826706;2300.9769902301014;2336.4509340305017;2341.1571834139695;2344.179537161997;2350.2831636350907;2245.3264020793695;2245.4498647831306;2276.248837883503;2296.219285442949;2306.8269879759087;2258.153192552793;2267.2178098555974;2301.5972204864247;2304.9654255186188;2307.8730669813153;2308.988455057731;2318.7449380568123;2320.5358928214255;2320.5474932388192;2324.395657129142;8265.917340826574;8428.578571071423;8441.662333506652;8457.238848503664;8473.279771219453;4901.09329773993;5077.466865979056;5082.23766435034;5247.895042099137;5251.55361794248;3522.9295414091575;3587.6053634100226;3588.551431071116;3594.11944073701;3598.064503229179;1040.4121671255791;1064.7497838008062;1092.30638544524;1093.1801149138146;1093.3767752381145;10950.780984380348;10964.451777411177;11034.227604067719;11078.11375089999;11098.944505277423;4357.4988876279;4458.242098843204;4520;4520.728756274569;4523.615492683159;8686.348523860735;8692.304615630734;8695.913040869636;8698.043215246318;8699;5114.872128196825;5150.278960945396;5154.175331946871;5158.097332966716;5166.405863325691;4108.815103320298;4184.204503617018;4717.127331443694;4813.085513752376;4823.445303790038;3622.1487950331652;3651.434027725707;3701.0932321581386;3734.6452087051684;3757.680597149218;959.5058544849409;970.6507953944024;1058.4972138234277;1142.954281828734;1155.2664058323026;761.744815486807;861.1388611388611;862.2886118951765;879.6789171952191;933.7852293972288;5583.162525621108;5761.5102716269585;5950.107483877367;5957.821265362046;6209.161763162006;868.4485351801651;898.1063841477771;929.5398777605129;933.7618907178626;950.8383574792302;3939.369700847908;3946.7039972002217;3970.1861118470642;4061.756294622332;4114.382842573638;9959.402435853872;10124.797504050026;10182.592696292066;10317.226208034428;10326.380417174994;9985.850212246822;10330.586776529006;10346.017128372792;10357.844632330522;10401.94799026;7563.508371955805;7674.769756907302;7932.325752311118;7946.642401091849;8019.558924259221;5655.575831812713;5747.879163563065;5849.210356601974;5874.17761513397;5958.577694298167;9388.342816002814;9392.342536022414;9488.288378371788;9577.802774653168;9593.760155996157;9583.904160958302;9646.228301735984;9737.464439455898;9741;9844.310898237341;6231.501479881689;6305.243370795534;7155.3202445767565;7226.783196504113;7324.974503569398;7919.247671471201;8025.678972841254;8042.235987581171;8042.638081286474;8075.475094118863;6057.818265452043;7037.401820845081;7145.785626431215;7162.176349719849;7285.417166626551;7004.229534751275;7006.859862802818;7020.7542736004;7024.086868707035;7094.893576596252;6431.581947173606;6538.574992625464;6614.966925165343;6620.006998950196;6629.46964242869;4849.636277279147;4878.756062196947;4897.510248975074;4947.6536642434685;4983.8504844854715;3836.405357169643;4799.736014519235;4813.927791083207;4999.275105109811;5026.824061157842 03:43:31 INFO - PROCESS | 6791 | |4;prototype.html;256.7419743158126;295.11759838084123;301.0051778870831;307.7122890097759;309.29172195671913;4381.474223093225;4505.144022635697;5132.615053870961;5162.489507135147;5175.223716442535;4818.710877347362;4917.655764096515;4969.782403311194;4982.725950072744;5111.7699703513335;3268.50972354147;3902.804859757015;3937.133830557287;3945.7237993340477;4014.036631208518;3493.7379696522735;3920.627540383659;3929.803509824512;3951.9802400987955;3963.564007959122;3262.5106234064842;3806.5812760596314;3810.5133802570613;3907.9804600976954;3957.9604203958024;3636.345457817592;3911.2764138634443;3927.8625248116177;3970.3250447424007;3973.132627664993;2890.4508143452886;2975.851207439641;3425.8972230833115;3435.690787829082;3459.2043829919257;4222.050038741289;4732.83435079774;4874;4900.113456319307;4960.16473904654;3943.38877473992;4143.4199212110325;4395.7362558246605;4470.865874023784;4507.954920450787;5135.204043373283;5317.46825317469;5346.1713434417725;5384.93058956395;5386.892262154735;2902.2309088091674;3149.4488464518845;3696.465966623848;3726.06848287928;3747.220070466534;4482.484514280867;5387.865303367408;5683.829485115453;5734.598578099576;5807.88384232313;3768.3593789055926;3769.5099637047556;3780.5633859133354;3782.4893639358884;3784.6783023442763;3494.790312581253;3547.3614749345365;3591.838367273479;3650.7261955352924;3846.788426636506;4124;4450.643948484114;4476.171908196994;4487.865364039084;4507.909841803145;5055.646104772631;5407.486288802557;5482.479164479368;5537;5581.637193582485;982.062130665213;1091.5906535049357;1134.6837668304727;1182.3260741377333;1233.617578550652;792.8226583523578;799.6841247707123;809.2029351089172;840.3361344537747;843.7089204224533;4122.670186385142;4493.562060140789;4791.6406269529925;4831.565159135695;4841.467438581753;4395.868004018049;4849.927251091236;4971.055499455092;5141.665791723526;5256.553192978563;4729.527047295243;4736.423999880069;4741.478437371887;5288.418273989858;5373.596980226499;2410.963835542468;2507.5110353480895;2604.2187343797164;2693.7979651526216;2800.739667149791;5028.742814296426;5121;5422.1053526168525;5483.643563168382;5525.806596769094;5096.833845615599;5306.814261500829;5373.704446255415;5765.4522820332;5816.621919575214;4919.975400122977;4936.48166942473;5838.489449536655;5876.471117599436;5882;4712.9293060604805;5405.72971351423;5408.675479471244;5710.428957104256;5807.622504537191;5511.338639363142;5529.778808847601;5541.196526503554;5568.972155139199;5578.77684892616;4695.1828902658135;5776.653400795965;5794.275715535558;5813.505852002458;5913.5564832636865;3088.4132014917095;3543.5216245806487;3580.9462858056627;3796.9430458543698;3822.3119838429348;4674.976625116853;5171.896562068813;5518.420565840529;5628.465295796893;5659.943400566106;4666;4968.652194346361;5370.9731451342495;5403.783848646168;5465.890682186255;4492.034212644297;5321.414644389037;5340.985212809554;5407.567394608343;5451.800603867143;4973.278874563241;5229.712365819764;5297.284866542967;5325;5350.30446042012;4366.934495982627;5331.893362132813;5472.445467850215;5479.835604931858;5505.889882202414;5094.083065048327;5239.554637855825;5341.62608617393;5370.409254982027;5373.946260537345 03:43:31 INFO - PROCESS | 6791 | |5;yui.html;574.4915749561638;698.8252936765808;750.189795021377;760.3119177144682;775.2828633513998;14036.789448158273;14535.127892326467;14589.270536473174;14730.116193028412;15710.921445392785;14979.700405991875;15125.16811575363;15188.84811151888;15222.010569312986;15243.31405086771;789.3842802613971;819.7540737778672;848.6732607945954;851.5188918261193;851.8722191671268;669.6015870557011;696.0776970514064;701.8947157926326;803.5138741061661;832.9458585191974;858.5792961448876;861.5218553702692;884.3367474394204;886.4814083761007;888.6223355074068;882.5895958379352;882.9558522073935;883.5891310540597;883.9834190680705;884.9336299777542;513.8843760153973;529.7271904968944;627.7489004398232;657.7237560224669;657.9736810527573;12853.357332133355;13301.667458313523;13319.134256273312;13347.265900375476;13392.464301427932;12997.675058123528;13224.404901779348;13317.200967941955;13327.666808329772;13343.599692009158;12283.508659653604;12304.938475307656;12494.06294527914;12530.937345313307;12771.169873958162;4344.174606824693;4390.4511936008;4403.251447253974;4411.338299255105;4568.063546972862;95253;99904.50047749787;100330.49834750779;100748;100931.49534252282;9626;9753.902460975441;10124.190064794946;10340.482975851177;10482.266241363184;20264.392068237976;20277.29029483961;20665.89667051655;20675.793242067688;20712.275070372463;7664.34853037498;7850.646720897574;7871.645775940096;8067.072083671819;8105.189481051965;4058.6550143238155;4171.8957026074595;4362.563743625612;4523.525029871882;4559.658025648089;3276.4430046891607;3364.8223121907135;3364.966350336514;3375.628680845105;3378.5945686517775;619.5446346934999;634.552640388525;646.9935300647028;650.9283978762332;654.9672516374258;619.2816333053636;621.6425555305717;626.4503470873501;632.8576070384127;650.7364517370378;8498.915010849936;9127.680531181377;9146.679866204651;9214.308926830514;9259.444433334022;629.3832044596321;630.6279295215845;652.2303681655598;655.3184687924601;672.6737532296833;4362.345648152739;4381.9123617527475;4399;4411.426514553152;4440.111977604492;116781;119216.40391797986;119302.40348798201;119811;126868.7535310202;94533;96837;97958.02041979632;98272;101674;74478.25521744713;75499;76421;84408;84551.15448845433;9109.362344635812;9690.75773105678;9714.659986900424;9723.9027609723;9867.210623150275;41749.16501669889;48235.31176408809;50441.24338134858;52889.20666190087;57891;52396;53274.46725532695;63606.363936360045;71484.92772608311;74374.62812685902;12152.57465988686;12264.75470490603;12537.811932820832;12587;13053.412596432994;13526.59420217395;13738.793918091436;14613.123212607277;14614.41542338337;15210.08739475635;12530.812037819622;12884.835232860543;13292.601221963356;13660.316984150952;14079.859201407855;13058.934705326412;13390.196588204739;13798.241096739373;13990.650233744238;14001.369938353002;12349.135560511037;13233.139845909986;13235.933820330836;13273.469061237442;13494.595162144758;11247.381394023407;11350.772984540428;11648.88351116478;11698.824517632413;11877.703057423634;11183.776324473627;11361.856130087554;11500.82748758786;11549.711257218636;11582.478788454371 03:43:31 INFO - PROCESS | 6791 | __end_tp_report 03:43:31 INFO - PROCESS | 6791 | __start_cc_report 03:43:31 INFO - PROCESS | 6791 | _x_x_mozilla_cycle_collect,2818 03:43:31 INFO - PROCESS | 6791 | __end_cc_report 03:43:31 INFO - PROCESS | 6791 | __startTimestamp1479469411821__endTimestamp 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | ------- Summary: start ------- 03:43:31 INFO - PROCESS | 6791 | Number of tests: 6 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | [#0] dojo.html Cycles:165 Average:5233.36 Median:5366.62 stddev:538.56 (10.0%) stddev-sans-first:514.28 03:43:31 INFO - PROCESS | 6791 | Values: 3128.8 3140.3 3140.9 3148.2 3180.4 5206.4 5269.1 5282.3 5285.5 5293.9 5198.8 5219.5 5245.9 5263.9 5282.2 4815.3 4821.9 4844.3 4848.6 4893.0 4794.1 4799.0 4801.5 4840.4 4923.5 4805.2 4811.8 4824.8 4858.4 4890.3 4811.3 4838.9 4842.1 4868.2 4901.0 4739.6 4796.4 4817.7 4831.3 4849.1 5157.6 5158.7 5164.7 5169.5 5182.9 5590.1 5593.7 5633.9 5645.5 5673.6 5697.4 5715.0 5716.3 5744.9 5746.9 5183.8 5254.8 5270.2 5317.1 5332.8 5764.2 5771.2 5776.5 5780.5 5789.0 5437.4 5525.3 5525.5 5540.6 5547.4 5562.3 5596.8 5611.1 5623.2 5653.5 5553.1 5557.4 5565.7 5569.7 5584.7 5578.2 5584.4 5593.1 5593.1 5604.6 4444.9 4534.3 4537.7 4544.5 4570.9 4137.9 4163.4 4163.6 4193.9 4214.5 5242.9 5262.5 5282.0 5303.3 5313.4 5587.8 5642.0 5650.8 5678.3 5708.7 5214.2 5260.3 5331.9 5336.5 5337.3 4229.2 4960.7 5005.8 5022.4 5031.3 5771.2 5809.9 5813.5 5817.9 5843.8 5542.7 5600.0 5619.2 5632.5 5642.2 5754.7 5755.5 5761.5 5764.2 5765.7 5119.5 5486.6 5528.3 5552.7 5556.9 5130.6 5604.1 5608.7 5609.9 5610.4 5426.0 5438.9 5439.8 5450.5 5450.7 5415.9 5426.3 5438.5 5440.3 5445.5 5342.8 5354.0 5366.6 5369.1 5418.0 5477.7 5505.4 5518.3 5520.1 5558.6 5236.7 5330.1 5333.0 5395.4 5405.2 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | [#1] ext.html Cycles:180 Average:65030.93 Median:38739.21 stddev:135736.15 (350.4%) stddev-sans-first:136030.32 03:43:31 INFO - PROCESS | 6791 | Values: 452.3 457.0 511.1 511.6 525.2 29005.6 39249.0 40426.6 40629.8 42250.2 40899.4 41280.0 41315.2 41433.6 41451.8 8446.5 9260.2 9436.3 9443.2 9448.8 8519.1 10715.1 11070.4 11296.9 11480.3 9574.8 11215.6 11289.2 11306.8 11361.5 8541.6 11316.9 11394.7 11394.7 11404.0 8502.0 11016.4 11326.1 11343.5 11471.2 39349.0 39544.2 39613.8 39678.0 39703.4 3238.0 3238.2 3276.5 3280.0 3454.4 29143.9 29211.0 29935.4 30177.4 30847.8 3205.3 3206.7 3216.2 3256.2 3364.1 453183.0 456114.0 456997.0 457222.0 457339.0 59557.7 59781.0 59875.0 59911.0 60063.7 63980.0 64005.4 64105.0 64126.0 64221.7 15938.1 16370.3 16415.9 16450.8 16451.8 9390.5 11643.8 12535.4 12673.6 12760.7 15155.6 15451.5 16773.2 17282.3 17737.6 5820.3 5932.3 7026.6 7047.2 7093.3 829.4 941.3 941.5 965.9 1007.6 28329.4 29437.4 29625.6 30049.8 30211.4 1041.8 1044.4 1063.7 1067.1 1077.8 15409.5 15417.5 15438.8 15486.0 15547.8 586993.1 592772.0 592812.0 593114.0 594300.0 57332.1 57472.7 57518.1 57523.1 57572.7 1228.5 1268.3 1313.2 1370.7 1378.6 27923.0 27959.6 28700.3 30767.4 31247.8 49612.5 50204.2 50252.5 50332.5 50441.5 442704.0 473418.0 476484.0 478804.0 479634.0 25876.2 26143.7 26677.6 39857.4 40145.6 45798.8 46460.8 46635.5 46975.3 47252.3 36377.8 38194.2 39088.2 39797.0 40614.6 39005.6 39063.0 39184.6 39984.4 40409.0 38152.2 38478.8 38606.0 38717.4 38761.0 27381.7 33159.2 34840.3 34914.8 34963.3 25407.7 25446.9 25550.7 25920.6 25990.7 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | [#2] jquery.html Cycles:180 Average:34034.89 Median:3774.97 stddev:100413.66 (2660.0%) stddev-sans-first:100663.49 03:43:31 INFO - PROCESS | 6791 | Values: 349.3 368.9 372.0 380.1 400.7 14827.9 14849.4 15730.9 19524.5 20356.3 18888.5 19280.0 19720.3 20039.9 20269.1 5609.5 5898.4 5898.8 5900.6 5906.8 4651.9 5828.6 5899.0 5900.1 5909.9 5611.6 5851.7 5861.3 5862.2 5874.2 5708.2 5826.0 5826.2 5829.8 5835.6 4791.4 5044.3 5102.6 5111.8 5112.5 12713.2 13001.1 13005.0 13018.6 17901.7 2258.9 2456.6 2742.4 2837.0 2841.4 8987.3 9720.3 10218.7 10280.2 10286.0 1734.0 1754.2 1777.6 1783.9 1796.0 97061.5 112717.4 118557.0 121860.2 128939.0 8986.8 9328.9 9675.4 9680.4 9837.8 12105.5 12117.9 12144.9 12191.9 12242.9 6887.3 7672.1 7735.0 7868.4 8046.0 8313.3 8337.8 8355.8 8484.7 8491.6 3753.8 3769.4 3780.6 3834.8 3851.1 1584.7 1921.3 1921.9 1923.9 1948.8 821.5 882.9 890.3 900.3 931.0 19074.8 20592.7 20634.8 20638.9 20822.3 870.7 900.8 901.8 942.5 991.3 10230.0 10503.0 10715.1 10723.1 10744.3 588558.0 598729.0 608104.0 611669.0 611918.0 84286.6 87357.0 113922.0 114558.0 127311.0 979.9 988.9 1015.2 1016.5 1017.6 17677.6 17863.4 18276.2 18317.3 18544.6 55942.9 56227.7 56589.0 56656.9 57221.0 78462.6 80673.0 81637.2 84286.0 84316.6 7690.8 7839.8 7921.6 8280.6 8314.9 11731.5 12942.7 12998.0 13017.4 13502.2 10830.1 11180.6 11246.5 11406.9 11781.6 9619.9 11070.5 11178.6 11463.9 11498.6 11182.4 11509.5 11670.4 11784.3 11826.5 7977.2 8096.0 8168.6 8335.6 8486.8 9337.1 10036.1 10425.3 10461.5 10576.6 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | [#3] mootools.html Cycles:180 Average:5453.26 Median:5152.23 stddev:3010.42 (58.4%) stddev-sans-first:3002.75 03:43:31 INFO - PROCESS | 6791 | Values: 1308.8 1358.6 1451.0 1587.7 1810.4 7558.7 7956.0 8168.3 8398.5 8413.4 8297.8 8395.8 8398.9 8422.1 8427.3 2287.2 2307.0 2312.8 2321.6 2325.8 2301.0 2336.5 2341.2 2344.2 2350.3 2245.3 2245.4 2276.2 2296.2 2306.8 2258.2 2267.2 2301.6 2305.0 2307.9 2309.0 2318.7 2320.5 2320.5 2324.4 8265.9 8428.6 8441.7 8457.2 8473.3 4901.1 5077.5 5082.2 5247.9 5251.6 3522.9 3587.6 3588.6 3594.1 3598.1 1040.4 1064.7 1092.3 1093.2 1093.4 10950.8 10964.5 11034.2 11078.1 11098.9 4357.5 4458.2 4520.0 4520.7 4523.6 8686.3 8692.3 8695.9 8698.0 8699.0 5114.9 5150.3 5154.2 5158.1 5166.4 4108.8 4184.2 4717.1 4813.1 4823.4 3622.1 3651.4 3701.1 3734.6 3757.7 959.5 970.7 1058.5 1143.0 1155.3 761.7 861.1 862.3 879.7 933.8 5583.2 5761.5 5950.1 5957.8 6209.2 868.4 898.1 929.5 933.8 950.8 3939.4 3946.7 3970.2 4061.8 4114.4 9959.4 10124.8 10182.6 10317.2 10326.4 9985.9 10330.6 10346.0 10357.8 10401.9 7563.5 7674.8 7932.3 7946.6 8019.6 5655.6 5747.9 5849.2 5874.2 5958.6 9388.3 9392.3 9488.3 9577.8 9593.8 9583.9 9646.2 9737.5 9741.0 9844.3 6231.5 6305.2 7155.3 7226.8 7325.0 7919.2 8025.7 8042.2 8042.6 8075.5 6057.8 7037.4 7145.8 7162.2 7285.4 7004.2 7006.9 7020.8 7024.1 7094.9 6431.6 6538.6 6615.0 6620.0 6629.5 4849.6 4878.8 4897.5 4947.7 4983.9 3836.4 4799.7 4813.9 4999.3 5026.8 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | [#4] prototype.html Cycles:180 Average:4333.65 Median:4861.96 stddev:1365.66 (28.1%) stddev-sans-first:1334.77 03:43:31 INFO - PROCESS | 6791 | Values: 256.7 295.1 301.0 307.7 309.3 4381.5 4505.1 5132.6 5162.5 5175.2 4818.7 4917.7 4969.8 4982.7 5111.8 3268.5 3902.8 3937.1 3945.7 4014.0 3493.7 3920.6 3929.8 3952.0 3963.6 3262.5 3806.6 3810.5 3908.0 3958.0 3636.3 3911.3 3927.9 3970.3 3973.1 2890.5 2975.9 3425.9 3435.7 3459.2 4222.1 4732.8 4874.0 4900.1 4960.2 3943.4 4143.4 4395.7 4470.9 4508.0 5135.2 5317.5 5346.2 5384.9 5386.9 2902.2 3149.4 3696.5 3726.1 3747.2 4482.5 5387.9 5683.8 5734.6 5807.9 3768.4 3769.5 3780.6 3782.5 3784.7 3494.8 3547.4 3591.8 3650.7 3846.8 4124.0 4450.6 4476.2 4487.9 4507.9 5055.6 5407.5 5482.5 5537.0 5581.6 982.1 1091.6 1134.7 1182.3 1233.6 792.8 799.7 809.2 840.3 843.7 4122.7 4493.6 4791.6 4831.6 4841.5 4395.9 4849.9 4971.1 5141.7 5256.6 4729.5 4736.4 4741.5 5288.4 5373.6 2411.0 2507.5 2604.2 2693.8 2800.7 5028.7 5121.0 5422.1 5483.6 5525.8 5096.8 5306.8 5373.7 5765.5 5816.6 4920.0 4936.5 5838.5 5876.5 5882.0 4712.9 5405.7 5408.7 5710.4 5807.6 5511.3 5529.8 5541.2 5569.0 5578.8 4695.2 5776.7 5794.3 5813.5 5913.6 3088.4 3543.5 3580.9 3796.9 3822.3 4675.0 5171.9 5518.4 5628.5 5659.9 4666.0 4968.7 5371.0 5403.8 5465.9 4492.0 5321.4 5341.0 5407.6 5451.8 4973.3 5229.7 5297.3 5325.0 5350.3 4366.9 5331.9 5472.4 5479.8 5505.9 5094.1 5239.6 5341.6 5370.4 5373.9 03:43:31 INFO - PROCESS | 6791 | 03:43:31 INFO - PROCESS | 6791 | [#5] yui.html Cycles:180 Average:20805.87 Median:4401.13 stddev:30918.86 (702.5%) stddev-sans-first:30968.28 03:43:31 INFO - PROCESS | 6791 | Values: 574.5 698.8 750.2 760.3 775.3 14036.8 14535.1 14589.3 14730.1 15710.9 14979.7 15125.2 15188.8 15222.0 15243.3 789.4 819.8 848.7 851.5 851.9 669.6 696.1 701.9 803.5 832.9 858.6 861.5 884.3 886.5 888.6 882.6 883.0 883.6 884.0 884.9 513.9 529.7 627.7 657.7 658.0 12853.4 13301.7 13319.1 13347.3 13392.5 12997.7 13224.4 13317.2 13327.7 13343.6 12283.5 12304.9 12494.1 12530.9 12771.2 4344.2 4390.5 4403.3 4411.3 4568.1 95253.0 99904.5 100330.5 100748.0 100931.5 9626.0 9753.9 10124.2 10340.5 10482.3 20264.4 20277.3 20665.9 20675.8 20712.3 7664.3 7850.6 7871.6 8067.1 8105.2 4058.7 4171.9 4362.6 4523.5 4559.7 3276.4 3364.8 3365.0 3375.6 3378.6 619.5 634.6 647.0 650.9 655.0 619.3 621.6 626.5 632.9 650.7 8498.9 9127.7 9146.7 9214.3 9259.4 629.4 630.6 652.2 655.3 672.7 4362.3 4381.9 4399.0 4411.4 4440.1 116781.0 119216.4 119302.4 119811.0 126868.8 94533.0 96837.0 97958.0 98272.0 101674.0 74478.3 75499.0 76421.0 84408.0 84551.2 9109.4 9690.8 9714.7 9723.9 9867.2 41749.2 48235.3 50441.2 52889.2 57891.0 52396.0 53274.5 63606.4 71484.9 74374.6 12152.6 12264.8 12537.8 12587.0 13053.4 13526.6 13738.8 14613.1 14614.4 15210.1 12530.8 12884.8 13292.6 13660.3 14079.9 13058.9 13390.2 13798.2 13990.7 14001.4 12349.1 13233.1 13235.9 13273.5 13494.6 11247.4 11350.8 11648.9 11698.8 11877.7 11183.8 11361.9 11500.8 11549.7 11582.5 03:43:31 INFO - PROCESS | 6791 | -------- Summary: end -------- 03:43:31 INFO - PROCESS | 6791 | 03:43:32 INFO - TEST-INFO | 6791: exit 0 03:43:32 INFO - TEST-OK | dromaeo_css | took 1118052ms 03:43:32 INFO - TEST-START | kraken 03:43:32 INFO - operating with platform_type : mac_ 03:43:32 INFO - Initialising browser for kraken test... 03:43:32 INFO - TEST-INFO | started process 6816 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpOK9j6V/profile http://localhost:60188/getInfo.html) 03:43:34 ERROR - PROCESS | 6816 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 03:43:34 INFO - PROCESS | 6816 | __metrics Screen width/height:1600/1200 03:43:34 INFO - PROCESS | 6816 | colorDepth:24 03:43:34 INFO - PROCESS | 6816 | Browser inner width/height: 1024/689 03:43:34 INFO - PROCESS | 6816 | __metrics 03:43:35 INFO - PROCESS | 6816 | 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" 03:43:35 INFO - PROCESS | 6816 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:43:35 INFO - TEST-INFO | 6816: exit 0 03:43:35 INFO - Browser initialized. 03:43:35 INFO - Running cycle 1/1 for kraken test... 03:43:35 INFO - TEST-INFO | started process 6818 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpOK9j6V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:43:47 INFO - PROCESS | 6818 | RSS: Main: 216948736 03:43:47 INFO - PROCESS | 6818 | 03:43:50 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60188/tests/kraken/driver.html?testName=audio-beat-detection) 03:43:51 INFO - PROCESS | 6818 | RSS: Main: 266866688 03:43:51 INFO - PROCESS | 6818 | 03:43:54 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60188/tests/kraken/driver.html?testName=audio-dft) 03:43:54 INFO - PROCESS | 6818 | RSS: Main: 276684800 03:43:54 INFO - PROCESS | 6818 | 03:43:57 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60188/tests/kraken/driver.html?testName=audio-fft) 03:43:58 INFO - PROCESS | 6818 | RSS: Main: 375726080 03:43:58 INFO - PROCESS | 6818 | 03:44:00 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60188/tests/kraken/driver.html?testName=audio-oscillator) 03:44:01 INFO - PROCESS | 6818 | RSS: Main: 277151744 03:44:01 INFO - PROCESS | 6818 | 03:44:03 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60188/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:44:03 INFO - PROCESS | 6818 | RSS: Main: 279379968 03:44:03 INFO - PROCESS | 6818 | 03:44:06 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60188/tests/kraken/driver.html?testName=imaging-darkroom) 03:44:07 INFO - PROCESS | 6818 | RSS: Main: 321040384 03:44:07 INFO - PROCESS | 6818 | 03:44:10 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60188/tests/kraken/driver.html?testName=imaging-desaturate) 03:44:10 INFO - PROCESS | 6818 | RSS: Main: 320004096 03:44:10 INFO - PROCESS | 6818 | 03:44:13 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60188/tests/kraken/driver.html?testName=json-parse-financial) 03:44:14 INFO - PROCESS | 6818 | RSS: Main: 316641280 03:44:14 INFO - PROCESS | 6818 | 03:44:15 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60188/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:44:16 INFO - PROCESS | 6818 | RSS: Main: 295923712 03:44:16 INFO - PROCESS | 6818 | 03:44:18 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:44:19 INFO - PROCESS | 6818 | RSS: Main: 297263104 03:44:19 INFO - PROCESS | 6818 | 03:44:21 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:44:21 INFO - PROCESS | 6818 | RSS: Main: 307400704 03:44:21 INFO - PROCESS | 6818 | 03:44:24 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:44:25 INFO - PROCESS | 6818 | RSS: Main: 311275520 03:44:25 INFO - PROCESS | 6818 | 03:44:28 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:44:29 INFO - PROCESS | 6818 | RSS: Main: 311533568 03:44:29 INFO - PROCESS | 6818 | 03:44:30 INFO - PROCESS | 6818 | Cycle 1(1): loaded http://localhost:60188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60188/tests/kraken/driver.html?testName=ai-astar) 03:44:31 INFO - PROCESS | 6818 | RSS: Main: 355549184 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | __start_tp_report 03:44:31 INFO - PROCESS | 6818 | _x_x_mozilla_page_load 03:44:31 INFO - PROCESS | 6818 | _x_x_mozilla_page_load_details 03:44:31 INFO - PROCESS | 6818 | |i|pagename|runs| 03:44:31 INFO - PROCESS | 6818 | |0;ai-astar;117;115;113;114;114;114;113;114;115;114 03:44:31 INFO - PROCESS | 6818 | |1;audio-beat-detection;151;124;111;147;101;117;135;140;140;113 03:44:31 INFO - PROCESS | 6818 | |2;audio-dft;182;186;187;186;185;182;184;183;185;183 03:44:31 INFO - PROCESS | 6818 | |3;audio-fft;81;58;60;88;62;66;84;84;112;83 03:44:31 INFO - PROCESS | 6818 | |4;audio-oscillator;87;71;94;70;69;69;90;68;69;92 03:44:31 INFO - PROCESS | 6818 | |5;imaging-gaussian-blur;89;101;134;102;88;104;113;86;89;85 03:44:31 INFO - PROCESS | 6818 | |6;imaging-darkroom;93;95;94;94;94;117;151;101;94;95 03:44:31 INFO - PROCESS | 6818 | |7;imaging-desaturate;81;82;89;123;83;83;85;84;83;83 03:44:31 INFO - PROCESS | 6818 | |8;json-parse-financial;56;56;57;54;57;57;58;57;57;58 03:44:31 INFO - PROCESS | 6818 | |9;json-stringify-tinderbox;65;69;77;77;72;71;71;71;72;71 03:44:31 INFO - PROCESS | 6818 | |10;stanford-crypto-aes;82;88;86;88;83;87;86;90;85;92 03:44:31 INFO - PROCESS | 6818 | |11;stanford-crypto-ccm;148;150;160;162;150;155;151;148;186;158 03:44:31 INFO - PROCESS | 6818 | |12;stanford-crypto-pbkdf2;168;166;167;166;165;165;166;166;168;231 03:44:31 INFO - PROCESS | 6818 | |13;stanford-crypto-sha256-iterative;67;68;68;66;66;66;67;66;66;66 03:44:31 INFO - PROCESS | 6818 | __end_tp_report 03:44:31 INFO - PROCESS | 6818 | __start_cc_report 03:44:31 INFO - PROCESS | 6818 | _x_x_mozilla_cycle_collect,1997 03:44:31 INFO - PROCESS | 6818 | __end_cc_report 03:44:31 INFO - PROCESS | 6818 | __startTimestamp1479469471190__endTimestamp 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | ------- Summary: start ------- 03:44:31 INFO - PROCESS | 6818 | Number of tests: 14 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.00 stddev:1.16 (1.0%) stddev-sans-first:0.71 03:44:31 INFO - PROCESS | 6818 | Values: 117.0 115.0 113.0 114.0 114.0 114.0 113.0 114.0 115.0 114.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:137.50 stddev:17.02 (12.4%) stddev-sans-first:15.87 03:44:31 INFO - PROCESS | 6818 | Values: 151.0 124.0 111.0 147.0 101.0 117.0 135.0 140.0 140.0 113.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.00 stddev:1.77 (1.0%) stddev-sans-first:1.67 03:44:31 INFO - PROCESS | 6818 | Values: 182.0 186.0 187.0 186.0 185.0 182.0 184.0 183.0 185.0 183.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#3] audio-fft Cycles:10 Average:77.80 Median:82.00 stddev:16.62 (20.3%) stddev-sans-first:17.59 03:44:31 INFO - PROCESS | 6818 | Values: 81.0 58.0 60.0 88.0 62.0 66.0 84.0 84.0 112.0 83.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.00 stddev:11.22 (14.2%) stddev-sans-first:11.41 03:44:31 INFO - PROCESS | 6818 | Values: 87.0 71.0 94.0 70.0 69.0 69.0 90.0 68.0 69.0 92.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:85.50 stddev:15.44 (18.1%) stddev-sans-first:15.94 03:44:31 INFO - PROCESS | 6818 | Values: 89.0 101.0 134.0 102.0 88.0 104.0 113.0 86.0 89.0 85.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:94.00 stddev:18.43 (19.6%) stddev-sans-first:19.20 03:44:31 INFO - PROCESS | 6818 | Values: 93.0 95.0 94.0 94.0 94.0 117.0 151.0 101.0 94.0 95.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#7] imaging-desaturate Cycles:10 Average:87.60 Median:83.00 stddev:12.62 (15.2%) stddev-sans-first:13.16 03:44:31 INFO - PROCESS | 6818 | Values: 81.0 82.0 89.0 123.0 83.0 83.0 85.0 84.0 83.0 83.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.16 (2.0%) stddev-sans-first:1.20 03:44:31 INFO - PROCESS | 6818 | Values: 56.0 56.0 57.0 54.0 57.0 57.0 58.0 57.0 57.0 58.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:3.50 (4.9%) stddev-sans-first:2.78 03:44:31 INFO - PROCESS | 6818 | Values: 65.0 69.0 77.0 77.0 72.0 71.0 71.0 71.0 72.0 71.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:87.50 stddev:3.02 (3.5%) stddev-sans-first:2.68 03:44:31 INFO - PROCESS | 6818 | Values: 82.0 88.0 86.0 88.0 83.0 87.0 86.0 90.0 85.0 92.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#11] stanford-crypto-ccm Cycles:10 Average:156.80 Median:156.50 stddev:11.43 (7.3%) stddev-sans-first:11.67 03:44:31 INFO - PROCESS | 6818 | Values: 148.0 150.0 160.0 162.0 150.0 155.0 151.0 148.0 186.0 158.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:166.50 stddev:20.48 (12.3%) stddev-sans-first:21.64 03:44:31 INFO - PROCESS | 6818 | Values: 168.0 166.0 167.0 166.0 165.0 165.0 166.0 166.0 168.0 231.0 03:44:31 INFO - PROCESS | 6818 | 03:44:31 INFO - PROCESS | 6818 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 03:44:31 INFO - PROCESS | 6818 | Values: 67.0 68.0 68.0 66.0 66.0 66.0 67.0 66.0 66.0 66.0 03:44:31 INFO - PROCESS | 6818 | -------- Summary: end -------- 03:44:31 INFO - PROCESS | 6818 | 03:44:32 INFO - TEST-INFO | 6818: exit 0 03:44:32 INFO - TEST-OK | kraken | took 59607ms 03:44:32 INFO - SUITE-END | took 1177s 03:44:32 INFO - Completed test suite (00:19:37) 03:44:32 INFO - javascript benchmark 03:44:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.7966282191655, 3140.277736120692, 3140.874365025399, 3148.2286839724266, 3180.4275230458525, 5206.375234971804, 5269.130593452081, 5282.286891269678, 5285.497877701622, 5293.8941221175555, 5198.844034678957, 5219.45195754446, 5245.921311180326, 5263.921041184385, 5282.2076688496745, 4815.34992775976, 4821.855344339658, 4844.297576851354, 4848.587870031051, 4892.996935628205, 4794.065157294329, 4799.016201678665, 4801.5438533339275, 4840.443349014873, 4923.458419573844, 4805.159097158001, 4811.8075276988875, 4824.831130910419, 4858.368412106422, 4890.266460031006, 4811.254255590372, 4838.879028024292, 4842.055799119174, 4868.245421959602, 4900.950990490085, 4739.6208303336, 4796.400449943757, 4817.735024573647, 4831.251156070814, 4849.127157111719, 5157.638965272433, 5158.690478571298, 5164.664296820694, 5169.4572069932865, 5182.948170518284, 5590.133529302964, 5593.692346920918, 5633.887322253573, 5645.463680950304, 5673.631213971079, 5697.401772813836, 5714.971305165069, 5716.3426205986425, 5744.856378590527, 5746.913796293059, 5183.766730497099, 5254.763535640944, 5270.209468579744, 5317.069512835315, 5332.760025798849, 5764.164196191528, 5771.220885180531, 5776.451237132465, 5780.479756821822, 5789.044807606782, 5437.429069947678, 5525.254090697705, 5525.502704756591, 5540.556755459554, 5547.389787123413, 5562.3047119110115, 5596.776128954796, 5611.130274807411, 5623.240862483594, 5653.491185793298, 5553.111502159716, 5557.3886872444, 5565.693886836263, 5569.749361278752, 5584.720763961785, 5578.246936663499, 5584.385717571064, 5593.077142271561, 5593.133064374947, 5604.579656525777, 4444.888877778081, 4534.274516077412, 4537.682362234678, 4544.522825103382, 4570.862874113782, 4137.91724165515, 4163.437935878678, 4163.646090082317, 4193.937090943638, 4214.494260688675, 5242.947570524322, 5262.473752624707, 5282.049231138432, 5303.28405665238, 5313.442088580643, 5587.804426845041, 5642.0, 5650.802221922213, 5678.346990096109, 5708.65748055118, 5214.191800270964, 5260.263563101169, 5331.946680533222, 5336.46635336471, 5337.306150200527, 4229.175310814362, 4960.683573641864, 5005.774740136702, 5022.372203474566, 5031.345925029723, 5771.163181338598, 5809.941900581025, 5813.505852002627, 5817.88364232713, 5843.795467158714, 5542.667439953616, 5600.0479918414785, 5619.213310136666, 5632.464915832989, 5642.181883626769, 5754.7122643866805, 5755.453231942959, 5761.539076873756, 5764.164196191613, 5765.654060756368, 5119.48805119485, 5486.61593688438, 5528.308961379827, 5552.666839989613, 5556.9444305558045, 5130.589552835839, 5604.131359639263, 5608.7195640217005, 5609.915851262316, 5610.438956104357, 5425.969065877471, 5438.945610543844, 5439.782408703608, 5450.509454149145, 5450.727463626882, 5415.945840541544, 5426.348838139448, 5438.537724293479, 5440.292761940922, 5445.50990410865, 5342.839714808562, 5353.9732301338245, 5366.624336296578, 5369.1140961740875, 5418.0, 5477.698726570077, 5505.394406615189, 5518.310211223597, 5520.1167813149705, 5558.610897237317, 5236.659617124875, 5330.093884039645, 5333.0, 5395.433479484597, 5405.243265942692], "value": 5201.200784258676, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.3192595144307, 456.97486638234886, 511.0519985427034, 511.567725272145, 525.233159587003, 29005.564916526244, 39249.0, 40426.595734042654, 40629.7968510157, 42250.15499690012, 40899.386509202384, 41280.0, 41315.17369652613, 41433.58566414342, 41451.79274103625, 8446.493210407365, 9260.212881905047, 9436.292278079172, 9443.244540436748, 9448.81102377952, 8519.062903080656, 10715.142788576895, 11070.446477676125, 11296.887031129707, 11480.253783504088, 9574.760630984212, 11215.607453739121, 11289.15331350148, 11306.773864522705, 11361.488733006992, 8541.572921353969, 11316.886831131707, 11394.658160255205, 11394.71513212168, 11404.0, 8502.0, 11016.394098324588, 11326.093912486982, 11343.489542970587, 11471.19701620887, 39349.0, 39544.20911581781, 39613.80193099045, 39678.0, 39703.40444893329, 3237.983810080958, 3238.2066393733685, 3276.5249038889465, 3279.9836000820083, 3454.3954807908517, 29143.854280728672, 29211.0, 29935.401291974256, 30177.396452070836, 30847.845760771277, 3205.2627895584146, 3206.7113959743733, 3216.244182617083, 3256.202230453554, 3364.142143753327, 453183.0, 456114.0, 456997.0, 457221.9999999967, 457339.0, 59557.70221148953, 59781.0, 59875.0, 59911.0, 60063.699681502185, 63980.04029939554, 64005.35994640087, 64105.0, 64126.038109428395, 64221.67889160618, 15938.123403212934, 16370.263338149813, 16415.91792041032, 16450.848440609043, 16451.83548164527, 9390.483523406278, 11643.825342619868, 12535.43590538428, 12673.556425525063, 12760.6809829755, 15155.621109472351, 15451.459198927983, 16773.161341932853, 17282.308707651806, 17737.645247094984, 5820.30156381237, 5932.317783454958, 7026.578405295699, 7047.154341479055, 7093.290670932969, 829.4318391901567, 941.3457646935451, 941.4962994797751, 965.9275554333453, 1007.6070332570304, 28329.433411331658, 29437.411251774844, 29625.55671633574, 30049.849750751106, 30211.395772084434, 1041.8280983637615, 1044.368157264859, 1063.707480442869, 1067.1302888146136, 1077.7521170130915, 15409.460668876536, 15417.460388886335, 15438.845611543966, 15486.0, 15547.833912456612, 586993.0650346807, 592772.0, 592812.0, 593114.0, 594300.0, 57332.140017899765, 57472.71263643655, 57518.13722794162, 57523.13715294274, 57572.712136439884, 1228.5392977633387, 1268.2517314784507, 1313.1792629606496, 1370.6915943912738, 1378.5588611644177, 27923.0, 27959.58060629133, 28700.282492937007, 30767.38465230638, 31247.843760781052, 49612.50387496223, 50204.24693629672, 50252.49747502478, 50332.49667503424, 50441.49558504368, 442704.0, 473418.0, 476484.0, 478804.0, 479634.0, 25876.223713288633, 26143.73856261413, 26677.599836002864, 39857.40213896852, 40145.59854401419, 45798.77100614476, 46460.7676961613, 46635.53364466312, 46975.29537056878, 47252.29121563111, 36377.81811090928, 38194.236115278094, 39088.21823563456, 39797.00507487336, 40614.59385406108, 39005.609943901334, 39063.0, 39184.608153918096, 39984.4002339971, 40409.0, 38152.236955261294, 38478.80760596179, 38606.0, 38717.419238712006, 38761.0, 27381.72618273792, 33159.17102072371, 34840.30319393649, 34914.82542587271, 34963.300733984666, 25407.745922540536, 25446.872765636053, 25550.744492554837, 25920.611190831776, 25990.74009259959], "value": 19785.819163281918, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.28222502756824, 368.89855289795304, 371.9925601487972, 380.09537301223094, 400.6814582406988, 14827.925860370697, 14849.40602375905, 15730.921345393286, 19524.51188720283, 20356.287529936457, 18888.527786805338, 19280.0, 19720.30978915738, 20039.899800500978, 20269.087891044903, 5609.467100625444, 5898.439648233432, 5898.7640494380175, 5900.586958912878, 5906.822795316148, 4651.906961860761, 5828.591998560102, 5899.0, 5900.114982752579, 5909.881802363951, 5611.579131565129, 5851.736671849756, 5861.296644402656, 5862.208601838761, 5874.1776151338845, 5708.200851880719, 5826.038703613898, 5826.242588463472, 5829.766809327622, 5835.5915085944, 4791.448983366888, 5044.268581055763, 5102.591792656579, 5111.821086262, 5112.539871411554, 12713.237205767591, 13001.089923705344, 13005.0, 13018.60944171667, 17901.7314740279, 2258.9435264118365, 2456.570100232452, 2742.4103817679293, 2836.957445638317, 2841.4317136572563, 8987.32595055367, 9720.270979676552, 10218.744531386701, 10280.228982826318, 10286.022827831344, 1734.037609126934, 1754.2018381636324, 1777.5733823882304, 1783.9464816055538, 1796.0391190712905, 97061.54407683891, 112717.43641281905, 118557.0, 121860.1720974186, 128939.0, 8986.775330616787, 9328.860067098998, 9675.419474831533, 9680.419174849532, 9837.754056148511, 12105.515779368903, 12117.939410302892, 12144.878551214551, 12191.939040304918, 12242.877571224173, 6887.311268873173, 7672.079350477867, 7734.99445072137, 7868.370530357558, 8045.9597702011115, 8313.293370063613, 8337.833243335099, 8355.791105222297, 8484.745457636282, 8491.61787719554, 3753.8123093845197, 3769.359208934488, 3780.584135745066, 3834.827432765532, 3851.114243723959, 1584.698907207627, 1921.28912302449, 1921.9135138918502, 1923.884566925989, 1948.824605785486, 821.5194111444753, 882.9072947340566, 890.3366991591176, 900.342749792655, 930.9805762689844, 19074.80925190758, 20592.661477003945, 20634.79365206359, 20638.859819604506, 20822.293101896656, 870.6517393042834, 900.8288425199191, 901.8196360727881, 942.533445944262, 991.2664628175243, 10230.02814732614, 10503.0, 10715.142788577052, 10723.088537474401, 10744.30162039465, 588558.0, 598729.0063549654, 608104.0, 611669.0, 611918.0, 84286.578567108, 87357.0, 113922.0, 114558.0, 127311.0, 979.9461029643438, 988.906053924876, 1015.1979935850743, 1016.5171543516918, 1017.623479312659, 17677.55806104806, 17863.374781882572, 18276.177572009557, 18317.267309308012, 18544.629107418044, 55942.88114237774, 56227.71886140543, 56589.0, 56656.86686266334, 57221.0, 78462.6076869612, 80673.0, 81637.18362816295, 84286.0, 84316.57841710752, 7690.8077298065755, 7839.843203135792, 7921.603919803872, 8280.58597070156, 8314.875276870973, 11731.472083756422, 12942.741145176855, 12998.02514811374, 13017.414216360097, 13502.189868607522, 10830.079443247412, 11180.608678696206, 11246.493907774333, 11406.942965285121, 11781.587644432404, 9619.855702164614, 11070.501827417624, 11178.608748693756, 11463.885361146282, 11498.597549085742, 11182.384968826793, 11509.53961841517, 11670.416479175838, 11784.292942423483, 11826.46780894879, 7977.242161994601, 8095.959520202361, 8168.591570421337, 8335.583220838813, 8486.830263394573, 9337.112974267433, 10036.096751292418, 10425.270231083752, 10461.529231184462, 10576.629817956335], "value": 9087.133096307649, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.7775078236712, 1358.6195865157756, 1450.9564713058605, 1587.7221486239905, 1810.3754204799347, 7558.697652093916, 7956.0, 8168.346532277418, 8398.45410048348, 8413.411061225717, 8297.75106746797, 8395.83208335833, 8398.91601083989, 8422.11567785383, 8427.283680887116, 2287.1880482428746, 2306.9769302307013, 2312.8034117099983, 2321.6401457774073, 2325.848819826706, 2300.9769902301014, 2336.4509340305017, 2341.1571834139695, 2344.179537161997, 2350.2831636350907, 2245.3264020793695, 2245.4498647831306, 2276.248837883503, 2296.219285442949, 2306.8269879759087, 2258.153192552793, 2267.2178098555974, 2301.5972204864247, 2304.9654255186188, 2307.8730669813153, 2308.988455057731, 2318.7449380568123, 2320.5358928214255, 2320.5474932388192, 2324.395657129142, 8265.917340826574, 8428.578571071423, 8441.662333506652, 8457.238848503664, 8473.279771219453, 4901.09329773993, 5077.466865979056, 5082.23766435034, 5247.895042099137, 5251.55361794248, 3522.9295414091575, 3587.6053634100226, 3588.551431071116, 3594.11944073701, 3598.064503229179, 1040.4121671255791, 1064.7497838008062, 1092.30638544524, 1093.1801149138146, 1093.3767752381145, 10950.780984380348, 10964.451777411177, 11034.227604067719, 11078.11375089999, 11098.944505277423, 4357.4988876279, 4458.242098843204, 4520.0, 4520.728756274569, 4523.615492683159, 8686.348523860735, 8692.304615630734, 8695.913040869636, 8698.043215246318, 8699.0, 5114.872128196825, 5150.278960945396, 5154.175331946871, 5158.097332966716, 5166.405863325691, 4108.815103320298, 4184.204503617018, 4717.127331443694, 4813.085513752376, 4823.445303790038, 3622.1487950331652, 3651.434027725707, 3701.0932321581386, 3734.6452087051684, 3757.680597149218, 959.5058544849409, 970.6507953944024, 1058.4972138234277, 1142.954281828734, 1155.2664058323026, 761.744815486807, 861.1388611388611, 862.2886118951765, 879.6789171952191, 933.7852293972288, 5583.162525621108, 5761.5102716269585, 5950.107483877367, 5957.821265362046, 6209.161763162006, 868.4485351801651, 898.1063841477771, 929.5398777605129, 933.7618907178626, 950.8383574792302, 3939.369700847908, 3946.7039972002217, 3970.1861118470642, 4061.756294622332, 4114.382842573638, 9959.402435853872, 10124.797504050026, 10182.592696292066, 10317.226208034428, 10326.380417174994, 9985.850212246822, 10330.586776529006, 10346.017128372792, 10357.844632330522, 10401.94799026, 7563.508371955805, 7674.769756907302, 7932.325752311118, 7946.642401091849, 8019.558924259221, 5655.575831812713, 5747.879163563065, 5849.210356601974, 5874.17761513397, 5958.577694298167, 9388.342816002814, 9392.342536022414, 9488.288378371788, 9577.802774653168, 9593.760155996157, 9583.904160958302, 9646.228301735984, 9737.464439455898, 9741.0, 9844.310898237341, 6231.501479881689, 6305.243370795534, 7155.3202445767565, 7226.783196504113, 7324.974503569398, 7919.247671471201, 8025.678972841254, 8042.235987581171, 8042.638081286474, 8075.475094118863, 6057.818265452043, 7037.401820845081, 7145.785626431215, 7162.176349719849, 7285.417166626551, 7004.229534751275, 7006.859862802818, 7020.7542736004, 7024.086868707035, 7094.893576596252, 6431.581947173606, 6538.574992625464, 6614.966925165343, 6620.006998950196, 6629.46964242869, 4849.636277279147, 4878.756062196947, 4897.510248975074, 4947.6536642434685, 4983.8504844854715, 3836.405357169643, 4799.736014519235, 4813.927791083207, 4999.275105109811, 5026.824061157842], "value": 4397.550394923865, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.7419743158126, 295.11759838084123, 301.0051778870831, 307.7122890097759, 309.29172195671913, 4381.474223093225, 4505.144022635697, 5132.615053870961, 5162.489507135147, 5175.223716442535, 4818.710877347362, 4917.655764096515, 4969.782403311194, 4982.725950072744, 5111.7699703513335, 3268.50972354147, 3902.804859757015, 3937.133830557287, 3945.7237993340477, 4014.036631208518, 3493.7379696522735, 3920.627540383659, 3929.803509824512, 3951.9802400987955, 3963.564007959122, 3262.5106234064842, 3806.5812760596314, 3810.5133802570613, 3907.9804600976954, 3957.9604203958024, 3636.345457817592, 3911.2764138634443, 3927.8625248116177, 3970.3250447424007, 3973.132627664993, 2890.4508143452886, 2975.851207439641, 3425.8972230833115, 3435.690787829082, 3459.2043829919257, 4222.050038741289, 4732.83435079774, 4874.0, 4900.113456319307, 4960.16473904654, 3943.38877473992, 4143.4199212110325, 4395.7362558246605, 4470.865874023784, 4507.954920450787, 5135.204043373283, 5317.46825317469, 5346.1713434417725, 5384.93058956395, 5386.892262154735, 2902.2309088091674, 3149.4488464518845, 3696.465966623848, 3726.06848287928, 3747.220070466534, 4482.484514280867, 5387.865303367408, 5683.829485115453, 5734.598578099576, 5807.88384232313, 3768.3593789055926, 3769.5099637047556, 3780.5633859133354, 3782.4893639358884, 3784.6783023442763, 3494.790312581253, 3547.3614749345365, 3591.838367273479, 3650.7261955352924, 3846.788426636506, 4124.0, 4450.643948484114, 4476.171908196994, 4487.865364039084, 4507.909841803145, 5055.646104772631, 5407.486288802557, 5482.479164479368, 5537.0, 5581.637193582485, 982.062130665213, 1091.5906535049357, 1134.6837668304727, 1182.3260741377333, 1233.617578550652, 792.8226583523578, 799.6841247707123, 809.2029351089172, 840.3361344537747, 843.7089204224533, 4122.670186385142, 4493.562060140789, 4791.6406269529925, 4831.565159135695, 4841.467438581753, 4395.868004018049, 4849.927251091236, 4971.055499455092, 5141.665791723526, 5256.553192978563, 4729.527047295243, 4736.423999880069, 4741.478437371887, 5288.418273989858, 5373.596980226499, 2410.963835542468, 2507.5110353480895, 2604.2187343797164, 2693.7979651526216, 2800.739667149791, 5028.742814296426, 5121.0, 5422.1053526168525, 5483.643563168382, 5525.806596769094, 5096.833845615599, 5306.814261500829, 5373.704446255415, 5765.4522820332, 5816.621919575214, 4919.975400122977, 4936.48166942473, 5838.489449536655, 5876.471117599436, 5882.0, 4712.9293060604805, 5405.72971351423, 5408.675479471244, 5710.428957104256, 5807.622504537191, 5511.338639363142, 5529.778808847601, 5541.196526503554, 5568.972155139199, 5578.77684892616, 4695.1828902658135, 5776.653400795965, 5794.275715535558, 5813.505852002458, 5913.5564832636865, 3088.4132014917095, 3543.5216245806487, 3580.9462858056627, 3796.9430458543698, 3822.3119838429348, 4674.976625116853, 5171.896562068813, 5518.420565840529, 5628.465295796893, 5659.943400566106, 4666.0, 4968.652194346361, 5370.9731451342495, 5403.783848646168, 5465.890682186255, 4492.034212644297, 5321.414644389037, 5340.985212809554, 5407.567394608343, 5451.800603867143, 4973.278874563241, 5229.712365819764, 5297.284866542967, 5325.0, 5350.30446042012, 4366.934495982627, 5331.893362132813, 5472.445467850215, 5479.835604931858, 5505.889882202414, 5094.083065048327, 5239.554637855825, 5341.62608617393, 5370.409254982027, 5373.946260537345], "value": 3891.191268247194, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.4915749561638, 698.8252936765808, 750.189795021377, 760.3119177144682, 775.2828633513998, 14036.789448158273, 14535.127892326467, 14589.270536473174, 14730.116193028412, 15710.921445392785, 14979.700405991875, 15125.16811575363, 15188.84811151888, 15222.010569312986, 15243.31405086771, 789.3842802613971, 819.7540737778672, 848.6732607945954, 851.5188918261193, 851.8722191671268, 669.6015870557011, 696.0776970514064, 701.8947157926326, 803.5138741061661, 832.9458585191974, 858.5792961448876, 861.5218553702692, 884.3367474394204, 886.4814083761007, 888.6223355074068, 882.5895958379352, 882.9558522073935, 883.5891310540597, 883.9834190680705, 884.9336299777542, 513.8843760153973, 529.7271904968944, 627.7489004398232, 657.7237560224669, 657.9736810527573, 12853.357332133355, 13301.667458313523, 13319.134256273312, 13347.265900375476, 13392.464301427932, 12997.675058123528, 13224.404901779348, 13317.200967941955, 13327.666808329772, 13343.599692009158, 12283.508659653604, 12304.938475307656, 12494.06294527914, 12530.937345313307, 12771.169873958162, 4344.174606824693, 4390.4511936008, 4403.251447253974, 4411.338299255105, 4568.063546972862, 95253.0, 99904.50047749787, 100330.49834750779, 100748.0, 100931.49534252282, 9626.0, 9753.902460975441, 10124.190064794946, 10340.482975851177, 10482.266241363184, 20264.392068237976, 20277.29029483961, 20665.89667051655, 20675.793242067688, 20712.275070372463, 7664.34853037498, 7850.646720897574, 7871.645775940096, 8067.072083671819, 8105.189481051965, 4058.6550143238155, 4171.8957026074595, 4362.563743625612, 4523.525029871882, 4559.658025648089, 3276.4430046891607, 3364.8223121907135, 3364.966350336514, 3375.628680845105, 3378.5945686517775, 619.5446346934999, 634.552640388525, 646.9935300647028, 650.9283978762332, 654.9672516374258, 619.2816333053636, 621.6425555305717, 626.4503470873501, 632.8576070384127, 650.7364517370378, 8498.915010849936, 9127.680531181377, 9146.679866204651, 9214.308926830514, 9259.444433334022, 629.3832044596321, 630.6279295215845, 652.2303681655598, 655.3184687924601, 672.6737532296833, 4362.345648152739, 4381.9123617527475, 4399.0, 4411.426514553152, 4440.111977604492, 116781.0, 119216.40391797986, 119302.40348798201, 119811.0, 126868.7535310202, 94533.0, 96837.0, 97958.02041979632, 98272.0, 101674.0, 74478.25521744713, 75499.0, 76421.0, 84408.0, 84551.15448845433, 9109.362344635812, 9690.75773105678, 9714.659986900424, 9723.9027609723, 9867.210623150275, 41749.16501669889, 48235.31176408809, 50441.24338134858, 52889.20666190087, 57891.0, 52396.0, 53274.46725532695, 63606.363936360045, 71484.92772608311, 74374.62812685902, 12152.57465988686, 12264.75470490603, 12537.811932820832, 12587.0, 13053.412596432994, 13526.59420217395, 13738.793918091436, 14613.123212607277, 14614.41542338337, 15210.08739475635, 12530.812037819622, 12884.835232860543, 13292.601221963356, 13660.316984150952, 14079.859201407855, 13058.934705326412, 13390.196588204739, 13798.241096739373, 13990.650233744238, 14001.369938353002, 12349.135560511037, 13233.139845909986, 13235.933820330836, 13273.469061237442, 13494.595162144758, 11247.381394023407, 11350.772984540428, 11648.88351116478, 11698.824517632413, 11877.703057423634, 11183.776324473627, 11361.856130087554, 11500.82748758786, 11549.711257218636, 11582.478788454371], "value": 7376.314638102491, "unit": "score"}], "name": "dromaeo_css", "value": 7003.975773995923, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 111.0, 147.0, 101.0, 117.0, 135.0, 140.0, 140.0, 113.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 187.0, 186.0, 185.0, 182.0, 184.0, 183.0, 185.0, 183.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 88.0, 62.0, 66.0, 84.0, 84.0, 112.0, 83.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 94.0, 70.0, 69.0, 69.0, 90.0, 68.0, 69.0, 92.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 101.0, 134.0, 102.0, 88.0, 104.0, 113.0, 86.0, 89.0, 85.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 94.0, 94.0, 117.0, 151.0, 101.0, 94.0, 95.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 89.0, 123.0, 83.0, 83.0, 85.0, 84.0, 83.0, 83.0], "value": 87.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 54.0, 57.0, 57.0, 58.0, 57.0, 57.0, 58.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 77.0, 77.0, 72.0, 71.0, 71.0, 71.0, 72.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 86.0, 88.0, 83.0, 87.0, 86.0, 90.0, 85.0, 92.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 160.0, 162.0, 150.0, 155.0, 151.0, 148.0, 186.0, 158.0], "value": 156.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 167.0, 166.0, 165.0, 165.0, 166.0, 166.0, 168.0, 231.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1482.8999999999999, "alertThreshold": 2.0}]} 03:44:32 INFO - Return code: 0 03:44:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:44:32 ERROR - # TBPL SUCCESS # 03:44:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:44:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:44:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:44:32 INFO - 2016-11-18 03:44:32.512 screenresolution[6822:124997] starting screenresolution argv=screenresolution get 03:44:32 INFO - 2016-11-18 03:44:32.515 screenresolution[6822:124997] Display 0: 1600x1200x32@60 03:44:32 INFO - 2016-11-18 03:44:32.521 screenresolution[6823:125001] starting screenresolution argv=screenresolution list 03:44:32 INFO - Available Modes on Display 0 03:44:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:44:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:44:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:44:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:44:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:44:32 INFO - Intel Iris: 03:44:32 INFO - Chipset Model: Intel Iris 03:44:32 INFO - Type: GPU 03:44:32 INFO - Bus: Built-In 03:44:32 INFO - VRAM (Dynamic, Max): 1536 MB 03:44:32 INFO - Vendor: Intel (0x8086) 03:44:32 INFO - Device ID: 0x0a2e 03:44:32 INFO - Revision ID: 0x0009 03:44:32 INFO - Displays: 03:44:32 INFO - Display: 03:44:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:44:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:44:32 INFO - Display Serial Number: 1600x1200 60 03:44:32 INFO - Main Display: Yes 03:44:32 INFO - Mirror: Off 03:44:32 INFO - Online: Yes 03:44:32 INFO - Rotation: Supported 03:44:32 INFO - Return code: 0 03:44:32 INFO - Running post-action listener: _resource_record_post_action 03:44:32 INFO - [mozharness: 2016-11-18 11:44:32.752740Z] Finished run-tests step (success) 03:44:32 INFO - Running post-run listener: _resource_record_post_run 03:44:32 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 901632; Write bytes: 653087232; Read time: 11; Write time: 1949 03:44:32 INFO - TinderboxPrint: CPU usage
53.6% 03:44:32 INFO - TinderboxPrint: I/O read bytes / time
901,632 / 11 03:44:32 INFO - TinderboxPrint: I/O write bytes / time
653,087,232 / 1,949 03:44:32 INFO - TinderboxPrint: CPU idle
2,247.8 (46.4%) 03:44:32 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 03:44:32 INFO - TinderboxPrint: CPU user
1,620.8 (33.5%) 03:44:32 INFO - TinderboxPrint: Swap in / out
353,599,488 / 0 03:44:32 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 350257664; Write bytes: 346683392; Read time: 27231; Write time: 702 03:44:32 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 851968; Write bytes: 295234048; Read time: 9; Write time: 1209 03:44:33 INFO - Running post-run listener: _upload_blobber_files 03:44:33 INFO - Blob upload gear active. 03:44:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:44:33 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 03:44:33 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'] 03:44:33 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 03:44:33 INFO - (blobuploader) - INFO - Open directory for files ... 03:44:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:44:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:44:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:34 INFO - (blobuploader) - INFO - Done attempting. 03:44:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:44:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:44:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:35 INFO - (blobuploader) - INFO - Done attempting. 03:44:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:44:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:44:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:38 INFO - (blobuploader) - INFO - Done attempting. 03:44:38 INFO - (blobuploader) - INFO - Iteration through files over. 03:44:38 INFO - Return code: 0 03:44:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:44:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:44:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aed16a24714ee43c9bf295a2873ee25930ae64821d85265b98f49dd79318527f1bd6b36ea1ad834aeaec9982999b28b534486ed9637f72e1ecf78a1ed2fef360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae81797366093de64b6bf1a2482b9a78ad358ad26fbf489f4ff5452542afa36ed8840bf5fb207d31bc3115f57a5faccfcf49272811d3515a4a6b81e0566fb079", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55d6f522b31bfef5991a2303c199b1b474eae59ced10ce21824b851fbdbd7e1f4f2c8c258de0596e81e9ea9f1c240ddfa5004168c34741aaa50139c68e22c075"} 03:44:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:44:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:44:38 INFO - Contents: 03:44:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aed16a24714ee43c9bf295a2873ee25930ae64821d85265b98f49dd79318527f1bd6b36ea1ad834aeaec9982999b28b534486ed9637f72e1ecf78a1ed2fef360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae81797366093de64b6bf1a2482b9a78ad358ad26fbf489f4ff5452542afa36ed8840bf5fb207d31bc3115f57a5faccfcf49272811d3515a4a6b81e0566fb079", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55d6f522b31bfef5991a2303c199b1b474eae59ced10ce21824b851fbdbd7e1f4f2c8c258de0596e81e9ea9f1c240ddfa5004168c34741aaa50139c68e22c075"} 03:44:38 INFO - Running post-run listener: copy_logs_to_upload_dir 03:44:38 INFO - Copying logs to upload dir... 03:44:38 INFO - mkdir: /builds/slave/test/build/upload/logs 03:44:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.091111 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-18 03:44:38.266064) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 03:44:38.273298) ========= 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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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/aed16a24714ee43c9bf295a2873ee25930ae64821d85265b98f49dd79318527f1bd6b36ea1ad834aeaec9982999b28b534486ed9637f72e1ecf78a1ed2fef360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae81797366093de64b6bf1a2482b9a78ad358ad26fbf489f4ff5452542afa36ed8840bf5fb207d31bc3115f57a5faccfcf49272811d3515a4a6b81e0566fb079", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55d6f522b31bfef5991a2303c199b1b474eae59ced10ce21824b851fbdbd7e1f4f2c8c258de0596e81e9ea9f1c240ddfa5004168c34741aaa50139c68e22c075"} build_url:https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011477 build_url: 'https://queue.taskcluster.net/v1/task/Vdr3onKHRyWoW_ji7uSIUw/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/aed16a24714ee43c9bf295a2873ee25930ae64821d85265b98f49dd79318527f1bd6b36ea1ad834aeaec9982999b28b534486ed9637f72e1ecf78a1ed2fef360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae81797366093de64b6bf1a2482b9a78ad358ad26fbf489f4ff5452542afa36ed8840bf5fb207d31bc3115f57a5faccfcf49272811d3515a4a6b81e0566fb079", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55d6f522b31bfef5991a2303c199b1b474eae59ced10ce21824b851fbdbd7e1f4f2c8c258de0596e81e9ea9f1c240ddfa5004168c34741aaa50139c68e22c075"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 03:44:38.311783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:44:38.312243) ========= 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.YlHb0FRWvX/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.sIi9Yh5YQF/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.004763 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:44:38.363429) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 03:44:38.364167) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 03:44:38.364900) ========= ========= Total master_lag: 0.19 =========