builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0124 starttime: 1479898863.1 results: success (0) buildid: 20161123004021 builduid: 5638487f839f496b8402a9660530a86a revision: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.097738) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.098132) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.098411) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.126891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.127161) ========= 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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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-23 03:01:03-- 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% 153M=0s 2016-11-23 03:01:03 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153376 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.304194) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.304488) ========= 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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.027165 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:03.356285) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 03:01:03.356675) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee --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 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HhvP7FHQjF/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.6tMjEL1Oah/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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-23 03:01:03,456 truncating revision to first 12 chars 2016-11-23 03:01:03,456 Setting DEBUG logging. 2016-11-23 03:01:03,456 attempt 1/10 2016-11-23 03:01:03,456 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5468cd9ed8e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 03:01:04,999 unpacking tar archive at: mozilla-aurora-5468cd9ed8e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.815849 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 03:01:05.198645) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:05.198919) ========= script_repo_revision: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:05.202299) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:05.202559) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 03:01:05.221672) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-23 03:01:05.221938) ========= /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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:05 INFO - MultiFileLogger online at 20161123 03:01:05 in /builds/slave/test 03:01:05 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:01:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:01:05 INFO - {'append_to_log': False, 03:01:05 INFO - 'base_work_dir': '/builds/slave/test', 03:01:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:01:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:01:05 INFO - 'branch': 'Mozilla-Aurora', 03:01:05 INFO - 'buildbot_json_path': 'buildprops.json', 03:01:05 INFO - 'config_files': ('talos/mac_config.py',), 03:01:05 INFO - 'default_actions': ('clobber', 03:01:05 INFO - 'read-buildbot-config', 03:01:05 INFO - 'download-and-extract', 03:01:05 INFO - 'populate-webroot', 03:01:05 INFO - 'create-virtualenv', 03:01:05 INFO - 'install', 03:01:05 INFO - 'run-tests'), 03:01:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:01:05 INFO - 'download_minidump_stackwalk': True, 03:01:05 INFO - 'download_symbols': 'ondemand', 03:01:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:01:05 INFO - 'tooltool.py': '/tools/tooltool.py', 03:01:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:01:05 INFO - '/tools/misc-python/virtualenv.py')}, 03:01:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:01:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:01:05 INFO - 'installer_path': 'installer.exe', 03:01:05 INFO - 'log_level': 'info', 03:01:05 INFO - 'log_name': 'talos', 03:01:05 INFO - 'log_to_console': True, 03:01:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:01:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:01:05 INFO - 'opt_config_files': (), 03:01:05 INFO - 'pip_index': False, 03:01:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:01:05 INFO - 'cmd': ('bash', 03:01:05 INFO - '-c', 03:01:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:01:05 INFO - 'enabled': True, 03:01:05 INFO - 'halt_on_failure': False, 03:01:05 INFO - 'name': 'check_screen_resolution'},), 03:01:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:01:05 INFO - 'cmd': ('bash', 03:01:05 INFO - '-c', 03:01:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:01:05 INFO - 'enabled': True, 03:01:05 INFO - 'halt_on_failure': False, 03:01:05 INFO - 'name': 'check_screen_resolution'},), 03:01:05 INFO - 'run_cmd_checks_enabled': True, 03:01:05 INFO - 'sps_profile': False, 03:01:05 INFO - 'sps_profile_interval': 0, 03:01:05 INFO - 'suite': 'dromaeojs', 03:01:05 INFO - 'system_bits': '32', 03:01:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:01:05 INFO - 'title': 't-yosemite-r7-0124', 03:01:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:01:05 INFO - 'use_talos_json': True, 03:01:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:01:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:01:05 INFO - 'work_dir': 'build'} 03:01:05 INFO - [mozharness: 2016-11-23 11:01:05.432263Z] Running clobber step. 03:01:05 INFO - Running pre-action listener: _resource_record_pre_action 03:01:05 INFO - Running main action method: clobber 03:01:05 INFO - rmtree: /builds/slave/test/build 03:01:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:01:07 INFO - Running post-action listener: _resource_record_post_action 03:01:07 INFO - [mozharness: 2016-11-23 11:01:07.769242Z] Finished clobber step (success) 03:01:07 INFO - [mozharness: 2016-11-23 11:01:07.769400Z] Running read-buildbot-config step. 03:01:07 INFO - Running pre-action listener: _resource_record_pre_action 03:01:07 INFO - Running main action method: read_buildbot_config 03:01:07 INFO - Using buildbot properties: 03:01:07 INFO - { 03:01:07 INFO - "project": "", 03:01:07 INFO - "product": "firefox", 03:01:07 INFO - "script_repo_revision": "production", 03:01:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:01:07 INFO - "repository": "", 03:01:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:01:07 INFO - "buildid": "20161123004021", 03:01:07 INFO - "stage_platform": "macosx64", 03:01:07 INFO - "basedir": "/builds/slave/test", 03:01:07 INFO - "nightly_build": "True", 03:01:07 INFO - "buildnumber": 2, 03:01:07 INFO - "slavename": "t-yosemite-r7-0124", 03:01:07 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:01:07 INFO - "platform": "yosemite_r7", 03:01:07 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:01:07 INFO - "branch": "mozilla-aurora", 03:01:07 INFO - "repo_path": "releases/mozilla-aurora", 03:01:07 INFO - "revision": "5468cd9ed8e4a4cc32d37135842c04a7df8a58ee", 03:01:07 INFO - "pgo_build": "False", 03:01:07 INFO - "builduid": "5638487f839f496b8402a9660530a86a", 03:01:07 INFO - "slavebuilddir": "test" 03:01:07 INFO - } 03:01:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:01:07 INFO - Running post-action listener: _resource_record_post_action 03:01:07 INFO - [mozharness: 2016-11-23 11:01:07.773000Z] Finished read-buildbot-config step (success) 03:01:07 INFO - [mozharness: 2016-11-23 11:01:07.773134Z] Running download-and-extract step. 03:01:07 INFO - Running pre-action listener: _resource_record_pre_action 03:01:07 INFO - Running main action method: download_and_extract 03:01:07 INFO - mkdir: /builds/slave/test/build/tests 03:01:07 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:01:07 INFO - trying https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:01:07 INFO - Downloading https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/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:01:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/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:01:09 INFO - Downloaded 1199 bytes. 03:01:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:01:09 INFO - Using the following test package requirements: 03:01:09 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:01:09 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:01:09 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:01:09 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'jsshell-mac.zip'], 03:01:09 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:01:09 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:01:09 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:01:09 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:01:09 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:01:09 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:01:09 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:01:09 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:01:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:01:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:01:09 INFO - Fetch https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:01:09 INFO - Http code: 200 03:01:09 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 03:01:09 INFO - x-amz-cf-id: mzNnLrqmoQDDx6QHua11-gcSQS_u3Z5UTuXZo0UmQPT4HETGyBsbAw== 03:01:09 INFO - x-amz-version-id: 3QzNzI9M71rRt_OL7t5xY3I3ByRS5.63 03:01:10 INFO - Expected file size: 16277594 03:01:10 INFO - Obtained file size: 16277594 03:01:16 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:01:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:01:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:01:16 INFO - Fetch https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:01:17 INFO - Http code: 200 03:01:17 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 03:01:17 INFO - x-amz-cf-id: Dwwtp1r_NrK4mxDsT0fkwkUjD1QcOEA3oWS_3ee7I7dKVEksDv76Qw== 03:01:17 INFO - x-amz-version-id: 3QzNzI9M71rRt_OL7t5xY3I3ByRS5.63 03:01:17 INFO - Expected file size: 16277594 03:01:17 INFO - Obtained file size: 16277594 03:01:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:01:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:01:24 INFO - Fetch https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:01:25 INFO - Http code: 200 03:01:25 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 03:01:25 INFO - x-amz-cf-id: aptcxeB70YmbuDu-fM_XF-5Ey6ja2kAv-wl873Jd-oshXvliFYhBQw== 03:01:25 INFO - x-amz-version-id: ovlC7IYpc3uV1MkSGU.BGB00oHMOnsri 03:01:25 INFO - Expected file size: 14409038 03:01:25 INFO - Obtained file size: 14409038 03:01:26 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:01:26 INFO - trying https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:26 INFO - Downloading https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:01:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/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:01:30 INFO - Downloaded 109717443 bytes. 03:01:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:30 INFO - mkdir: /builds/slave/test/properties 03:01:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:01:30 INFO - Writing to file /builds/slave/test/properties/build_url 03:01:30 INFO - Contents: 03:01:30 INFO - build_url:https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:31 INFO - Running post-action listener: _resource_record_post_action 03:01:31 INFO - Running post-action listener: set_extra_try_arguments 03:01:31 INFO - [mozharness: 2016-11-23 11:01:31.630705Z] Finished download-and-extract step (success) 03:01:31 INFO - [mozharness: 2016-11-23 11:01:31.630835Z] Running populate-webroot step. 03:01:31 INFO - Running pre-action listener: _resource_record_pre_action 03:01:31 INFO - Running main action method: populate_webroot 03:01:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:01:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:01:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:01:31 INFO - '--tppagecycles', 03:01:31 INFO - '7'], 03:01:31 INFO - 'tests': ['tsvgm']}}, 03:01:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:01:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:01:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:01:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:01:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['damp', 'tps']}, 03:01:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'tests': ['damp', 'tps']}, 03:01:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['dromaeo_dom']}, 03:01:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:01:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['basic_compositor_video']}, 03:01:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:01:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['a11yr', 03:01:31 INFO - 'ts_paint', 03:01:31 INFO - 'tpaint', 03:01:31 INFO - 'sessionrestore', 03:01:31 INFO - 'sessionrestore_no_auto_restore', 03:01:31 INFO - 'tabpaint']}, 03:01:31 INFO - 'other-e10s': {'tests': ['a11yr', 03:01:31 INFO - 'ts_paint', 03:01:31 INFO - 'tpaint', 03:01:31 INFO - 'sessionrestore', 03:01:31 INFO - 'sessionrestore_no_auto_restore', 03:01:31 INFO - 'tabpaint']}, 03:01:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:01:31 INFO - 'ts_paint', 03:01:31 INFO - 'tpaint', 03:01:31 INFO - 'sessionrestore', 03:01:31 INFO - 'sessionrestore_no_auto_restore', 03:01:31 INFO - 'tabpaint']}, 03:01:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:01:31 INFO - 'ts_paint', 03:01:31 INFO - 'tpaint', 03:01:31 INFO - 'sessionrestore', 03:01:31 INFO - 'sessionrestore_no_auto_restore', 03:01:31 INFO - 'tabpaint']}, 03:01:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['a11yr', 03:01:31 INFO - 'ts_paint', 03:01:31 INFO - 'tpaint', 03:01:31 INFO - 'sessionrestore', 03:01:31 INFO - 'sessionrestore_no_auto_restore', 03:01:31 INFO - 'tabpaint']}, 03:01:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['a11yr', 03:01:31 INFO - 'ts_paint', 03:01:31 INFO - 'tpaint', 03:01:31 INFO - 'sessionrestore', 03:01:31 INFO - 'sessionrestore_no_auto_restore', 03:01:31 INFO - 'tabpaint']}, 03:01:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['tsvgx', 03:01:31 INFO - 'tsvgr_opacity', 03:01:31 INFO - 'tart', 03:01:31 INFO - 'tscrollx', 03:01:31 INFO - 'cart']}, 03:01:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:01:31 INFO - 'tsvgr_opacity', 03:01:31 INFO - 'tart', 03:01:31 INFO - 'tscrollx', 03:01:31 INFO - 'cart']}, 03:01:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'talos_options': ['--disable-e10s'], 03:01:31 INFO - 'tests': ['tp5o']}, 03:01:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'tests': ['tp5o']}, 03:01:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'talos_options': ['--disable-e10s', 03:01:31 INFO - '--xperf_path', 03:01:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:31 INFO - 'tests': ['tp5n']}, 03:01:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:31 INFO - 'talos_options': ['--xperf_path', 03:01:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:31 INFO - 'tests': ['tp5n']}}, 03:01:31 INFO - 'talos.zip': {'path': '', 03:01:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:01:31 INFO - Running post-action listener: _resource_record_post_action 03:01:31 INFO - [mozharness: 2016-11-23 11:01:31.649674Z] Finished populate-webroot step (success) 03:01:31 INFO - [mozharness: 2016-11-23 11:01:31.649805Z] Running create-virtualenv step. 03:01:31 INFO - Running pre-action listener: _resource_record_pre_action 03:01:31 INFO - Running main action method: create_virtualenv 03:01:31 INFO - Creating virtualenv /builds/slave/test/build/venv 03:01:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:01:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:01:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:01:31 INFO - Using real prefix '/tools/python27' 03:01:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:01:32 INFO - Installing distribute.............................................................................................................................................................................................done. 03:01:35 INFO - Installing pip.................done. 03:01:35 INFO - Return code: 0 03:01:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:01:35 INFO - Reading from file tmpfile_stdout 03:01:35 INFO - Output received: 03:01:35 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:01:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:01:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:35 INFO - 'HOME': '/Users/cltbld', 03:01:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:35 INFO - 'LOGNAME': 'cltbld', 03:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:35 INFO - 'MOZ_NO_REMOTE': '1', 03:01:35 INFO - 'NO_EM_RESTART': '1', 03:01:35 INFO - 'PAGER': '/bin/cat', 03:01:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:35 INFO - 'PWD': '/builds/slave/test', 03:01:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:35 INFO - 'SHELL': '/bin/bash', 03:01:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:35 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:35 INFO - 'USER': 'cltbld', 03:01:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:35 INFO - 'XPC_FLAGS': '0x0', 03:01:35 INFO - 'XPC_SERVICE_NAME': '0', 03:01:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:01:35 INFO - Cleaning up... 03:01:35 INFO - Return code: 0 03:01:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:01:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:35 INFO - 'HOME': '/Users/cltbld', 03:01:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:35 INFO - 'LOGNAME': 'cltbld', 03:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:35 INFO - 'MOZ_NO_REMOTE': '1', 03:01:35 INFO - 'NO_EM_RESTART': '1', 03:01:35 INFO - 'PAGER': '/bin/cat', 03:01:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:35 INFO - 'PWD': '/builds/slave/test', 03:01:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:35 INFO - 'SHELL': '/bin/bash', 03:01:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:35 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:35 INFO - 'USER': 'cltbld', 03:01:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:35 INFO - 'XPC_FLAGS': '0x0', 03:01:35 INFO - 'XPC_SERVICE_NAME': '0', 03:01:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:36 INFO - Downloading/unpacking psutil>=3.1.1 03:01:36 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:01:36 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:01:36 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:01:36 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:01:36 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:01:36 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:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:39 INFO - Installing collected packages: psutil 03:01:39 INFO - Running setup.py install for psutil 03:01:39 INFO - building 'psutil._psutil_osx' extension 03:01:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:01:40 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:01:40 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:01:40 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:01:40 INFO - building 'psutil._psutil_posix' extension 03:01:40 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:01:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:01:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:01:40 INFO - ^ 03:01:40 INFO - 1 warning generated. 03:01:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:01:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:40 INFO - Successfully installed psutil 03:01:40 INFO - Cleaning up... 03:01:40 INFO - Return code: 0 03:01:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:01:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:40 INFO - 'HOME': '/Users/cltbld', 03:01:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:40 INFO - 'LOGNAME': 'cltbld', 03:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:40 INFO - 'MOZ_NO_REMOTE': '1', 03:01:40 INFO - 'NO_EM_RESTART': '1', 03:01:40 INFO - 'PAGER': '/bin/cat', 03:01:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:40 INFO - 'PWD': '/builds/slave/test', 03:01:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:40 INFO - 'SHELL': '/bin/bash', 03:01:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:40 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:40 INFO - 'USER': 'cltbld', 03:01:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:40 INFO - 'XPC_FLAGS': '0x0', 03:01:40 INFO - 'XPC_SERVICE_NAME': '0', 03:01:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:01:43 INFO - Installing collected packages: mozsystemmonitor 03:01:43 INFO - Running setup.py install for mozsystemmonitor 03:01:43 INFO - Successfully installed mozsystemmonitor 03:01:43 INFO - Cleaning up... 03:01:43 INFO - Return code: 0 03:01:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:01:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:43 INFO - 'HOME': '/Users/cltbld', 03:01:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:43 INFO - 'LOGNAME': 'cltbld', 03:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:43 INFO - 'MOZ_NO_REMOTE': '1', 03:01:43 INFO - 'NO_EM_RESTART': '1', 03:01:43 INFO - 'PAGER': '/bin/cat', 03:01:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:43 INFO - 'PWD': '/builds/slave/test', 03:01:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:43 INFO - 'SHELL': '/bin/bash', 03:01:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:43 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:43 INFO - 'USER': 'cltbld', 03:01:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:43 INFO - 'XPC_FLAGS': '0x0', 03:01:43 INFO - 'XPC_SERVICE_NAME': '0', 03:01:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:43 INFO - Downloading/unpacking jsonschema==2.5.1 03:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:46 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:46 INFO - warning: no files found matching '*.txt' 03:01:46 INFO - no previously-included directories found matching 'build' 03:01:46 INFO - no previously-included directories found matching 'dist' 03:01:46 INFO - no previously-included directories found matching '.git*' 03:01:46 INFO - Installing collected packages: jsonschema, functools32 03:01:46 INFO - Running setup.py install for functools32 03:01:47 INFO - warning: no files found matching '*.txt' 03:01:47 INFO - no previously-included directories found matching 'build' 03:01:47 INFO - no previously-included directories found matching 'dist' 03:01:47 INFO - no previously-included directories found matching '.git*' 03:01:47 INFO - Successfully installed jsonschema functools32 03:01:47 INFO - Cleaning up... 03:01:47 INFO - Return code: 0 03:01:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:47 INFO - 'HOME': '/Users/cltbld', 03:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:47 INFO - 'LOGNAME': 'cltbld', 03:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:47 INFO - 'MOZ_NO_REMOTE': '1', 03:01:47 INFO - 'NO_EM_RESTART': '1', 03:01:47 INFO - 'PAGER': '/bin/cat', 03:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:47 INFO - 'PWD': '/builds/slave/test', 03:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:47 INFO - 'SHELL': '/bin/bash', 03:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:47 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:47 INFO - 'USER': 'cltbld', 03:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:47 INFO - 'XPC_FLAGS': '0x0', 03:01:47 INFO - 'XPC_SERVICE_NAME': '0', 03:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:01:47 INFO - Cleaning up... 03:01:47 INFO - Return code: 0 03:01:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:47 INFO - 'HOME': '/Users/cltbld', 03:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:47 INFO - 'LOGNAME': 'cltbld', 03:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:47 INFO - 'MOZ_NO_REMOTE': '1', 03:01:47 INFO - 'NO_EM_RESTART': '1', 03:01:47 INFO - 'PAGER': '/bin/cat', 03:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:47 INFO - 'PWD': '/builds/slave/test', 03:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:47 INFO - 'SHELL': '/bin/bash', 03:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:47 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:47 INFO - 'USER': 'cltbld', 03:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:47 INFO - 'XPC_FLAGS': '0x0', 03:01:47 INFO - 'XPC_SERVICE_NAME': '0', 03:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:47 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:50 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:01:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:51 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:01:51 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:01:51 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:01:51 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:01:51 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:01:51 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:01:51 INFO - Downloading docopt-0.6.1.tar.gz 03:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:51 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:51 INFO - Running setup.py install for blobuploader 03:01:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:51 INFO - Running setup.py install for requests 03:01:52 INFO - Running setup.py install for docopt 03:01:52 INFO - Successfully installed blobuploader requests docopt 03:01:52 INFO - Cleaning up... 03:01:52 INFO - Return code: 0 03:01:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01: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:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:52 INFO - 'HOME': '/Users/cltbld', 03:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:52 INFO - 'LOGNAME': 'cltbld', 03:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:52 INFO - 'MOZ_NO_REMOTE': '1', 03:01:52 INFO - 'NO_EM_RESTART': '1', 03:01:52 INFO - 'PAGER': '/bin/cat', 03:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:52 INFO - 'PWD': '/builds/slave/test', 03:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:52 INFO - 'SHELL': '/bin/bash', 03:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:52 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:52 INFO - 'USER': 'cltbld', 03:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:52 INFO - 'XPC_FLAGS': '0x0', 03:01:52 INFO - 'XPC_SERVICE_NAME': '0', 03:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:52 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-3gVIK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:52 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-2VM8VV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:52 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-0fDSqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-MfZNmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-I0754A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-EKqSN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-9cLM2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-vh0FOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-192tT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-bOxZBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-zFIT6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:53 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-zMqIIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:54 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-NlxLwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:54 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ysXWZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:54 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-8Ke_kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:54 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-zu4HeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:54 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-F2vJjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:54 INFO - Running setup.py install for manifestparser 03:01:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:54 INFO - Running setup.py install for mozcrash 03:01:54 INFO - Running setup.py install for mozdebug 03:01:54 INFO - Running setup.py install for mozdevice 03:01:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Running setup.py install for mozfile 03:01:55 INFO - Running setup.py install for mozhttpd 03:01:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Running setup.py install for mozinfo 03:01:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Running setup.py install for mozInstall 03:01:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:55 INFO - Running setup.py install for mozleak 03:01:55 INFO - Running setup.py install for mozlog 03:01:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:56 INFO - Running setup.py install for moznetwork 03:01:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:56 INFO - Running setup.py install for mozprocess 03:01:56 INFO - Running setup.py install for mozprofile 03:01:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:56 INFO - Running setup.py install for mozrunner 03:01:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:56 INFO - Running setup.py install for mozscreenshot 03:01:56 INFO - Running setup.py install for moztest 03:01:56 INFO - Running setup.py install for mozversion 03:01:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:57 INFO - Cleaning up... 03:01:57 INFO - Return code: 0 03:01:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01: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:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:57 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 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:01:57 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:01:57 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:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:57 INFO - 'HOME': '/Users/cltbld', 03:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:57 INFO - 'LOGNAME': 'cltbld', 03:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:57 INFO - 'MOZ_NO_REMOTE': '1', 03:01:57 INFO - 'NO_EM_RESTART': '1', 03:01:57 INFO - 'PAGER': '/bin/cat', 03:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:57 INFO - 'PWD': '/builds/slave/test', 03:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:57 INFO - 'SHELL': '/bin/bash', 03:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:01:57 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:57 INFO - 'USER': 'cltbld', 03:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:57 INFO - 'XPC_FLAGS': '0x0', 03:01:57 INFO - 'XPC_SERVICE_NAME': '0', 03:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-snnXDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:58 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:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-RpYGQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:58 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:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Q2yRcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:58 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:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-qn8r3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:58 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:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-JxEE_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:58 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:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-oUYfek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:58 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:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-mnYOjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:58 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:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:58 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-OhUOZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Sjgjuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-tmU2CP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-FTvrow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ZJTD9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-U3KtrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-dZLxuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-PuAM1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Xok1ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:59 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:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:59 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-dCvu14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:02:00 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:02:00 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:02:00 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:02:00 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:02:00 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:02:00 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:02:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:02: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:02: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:02: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:02: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:02: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:02: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:02:02 INFO - Downloading blessings-1.6.tar.gz 03:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:02:03 INFO - Installing collected packages: blessings 03:02:03 INFO - Running setup.py install for blessings 03:02:03 INFO - Successfully installed blessings 03:02:03 INFO - Cleaning up... 03:02:03 INFO - Return code: 0 03:02:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:02:03 INFO - Reading from file tmpfile_stdout 03:02:03 INFO - Current package versions: 03:02:03 INFO - blessings == 1.6 03:02:03 INFO - blobuploader == 1.2.4 03:02:03 INFO - docopt == 0.6.1 03:02:03 INFO - functools32 == 3.2.3-2 03:02:03 INFO - jsonschema == 2.5.1 03:02:03 INFO - manifestparser == 1.1 03:02:03 INFO - mozInstall == 1.12 03:02:03 INFO - mozcrash == 1.0 03:02:03 INFO - mozdebug == 0.1 03:02:03 INFO - mozdevice == 0.48 03:02:03 INFO - mozfile == 1.2 03:02:03 INFO - mozhttpd == 0.7 03:02:03 INFO - mozinfo == 0.9 03:02:03 INFO - mozleak == 0.1 03:02:03 INFO - mozlog == 3.4 03:02:03 INFO - moznetwork == 0.27 03:02:03 INFO - mozprocess == 0.23 03:02:03 INFO - mozprofile == 0.28 03:02:03 INFO - mozrunner == 6.13 03:02:03 INFO - mozscreenshot == 0.1 03:02:03 INFO - mozsystemmonitor == 0.3 03:02:03 INFO - moztest == 0.7 03:02:03 INFO - mozversion == 1.4 03:02:03 INFO - psutil == 3.1.1 03:02:03 INFO - requests == 1.2.3 03:02:03 INFO - wsgiref == 0.1.2 03:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02: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:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:03 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 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:02:03 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:02:03 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:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:03 INFO - 'HOME': '/Users/cltbld', 03:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:03 INFO - 'LOGNAME': 'cltbld', 03:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:03 INFO - 'MOZ_NO_REMOTE': '1', 03:02:03 INFO - 'NO_EM_RESTART': '1', 03:02:03 INFO - 'PAGER': '/bin/cat', 03:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:03 INFO - 'PWD': '/builds/slave/test', 03:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:03 INFO - 'SHELL': '/bin/bash', 03:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:02:03 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:03 INFO - 'USER': 'cltbld', 03:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:03 INFO - 'XPC_FLAGS': '0x0', 03:02:03 INFO - 'XPC_SERVICE_NAME': '0', 03:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:03 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:02:03 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:02:03 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:02:03 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:02:03 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:02:03 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:02:03 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:02:03 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:02:03 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:02:03 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:02:03 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:02:03 INFO - Cleaning up... 03:02:03 INFO - Return code: 0 03:02:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:02: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:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:03 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 0x10cf11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe190d7be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, '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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:02:03 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:02:03 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:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:03 INFO - 'HOME': '/Users/cltbld', 03:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:03 INFO - 'LOGNAME': 'cltbld', 03:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:03 INFO - 'MOZ_NO_REMOTE': '1', 03:02:03 INFO - 'NO_EM_RESTART': '1', 03:02:03 INFO - 'PAGER': '/bin/cat', 03:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:03 INFO - 'PWD': '/builds/slave/test', 03:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:03 INFO - 'SHELL': '/bin/bash', 03:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:02:03 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:03 INFO - 'USER': 'cltbld', 03:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:03 INFO - 'XPC_FLAGS': '0x0', 03:02:03 INFO - 'XPC_SERVICE_NAME': '0', 03:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:02:04 INFO - Cleaning up... 03:02:04 INFO - Return code: 0 03:02:04 INFO - Running post-action listener: _resource_record_post_action 03:02:04 INFO - Running post-action listener: _start_resource_monitoring 03:02:04 INFO - Starting resource monitoring. 03:02:04 INFO - [mozharness: 2016-11-23 11:02:04.162637Z] Finished create-virtualenv step (success) 03:02:04 INFO - [mozharness: 2016-11-23 11:02:04.163620Z] Running install step. 03:02:04 INFO - Running pre-action listener: _resource_record_pre_action 03:02:04 INFO - Running main action method: install 03:02:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:02:04 INFO - Reading from file tmpfile_stdout 03:02:04 INFO - Detecting whether we're running mozinstall >=1.0... 03:02:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:02:04 INFO - Reading from file tmpfile_stdout 03:02:04 INFO - Output received: 03:02:04 INFO - Usage: mozinstall [options] installer 03:02:04 INFO - Options: 03:02:04 INFO - -h, --help show this help message and exit 03:02:04 INFO - -d DEST, --destination=DEST 03:02:04 INFO - Directory to install application into. [default: 03:02:04 INFO - "/builds/slave/test"] 03:02:04 INFO - --app=APP Application being installed. [default: firefox] 03:02:04 INFO - mkdir: /builds/slave/test/build/application 03:02:04 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:02:04 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:02:35 INFO - Reading from file tmpfile_stdout 03:02:35 INFO - Output received: 03:02:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:02:35 INFO - Running post-action listener: _resource_record_post_action 03:02:35 INFO - [mozharness: 2016-11-23 11:02:35.298224Z] Finished install step (success) 03:02:35 INFO - [mozharness: 2016-11-23 11:02:35.298362Z] Running run-tests step. 03:02:35 INFO - Running pre-action listener: _resource_record_pre_action 03:02:35 INFO - Running main action method: run_tests 03:02:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:35 INFO - 2016-11-23 03:02:35.310 screenresolution[1623:7427] starting screenresolution argv=screenresolution get 03:02:35 INFO - 2016-11-23 03:02:35.313 screenresolution[1623:7427] Display 0: 1600x1200x32@60 03:02:35 INFO - 2016-11-23 03:02:35.318 screenresolution[1624:7430] starting screenresolution argv=screenresolution list 03:02:35 INFO - Available Modes on Display 0 03:02:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:35 INFO - Intel Iris: 03:02:35 INFO - Chipset Model: Intel Iris 03:02:35 INFO - Type: GPU 03:02:35 INFO - Bus: Built-In 03:02:35 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:35 INFO - Vendor: Intel (0x8086) 03:02:35 INFO - Device ID: 0x0a2e 03:02:35 INFO - Revision ID: 0x0009 03:02:35 INFO - Displays: 03:02:35 INFO - Display: 03:02:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:35 INFO - Display Serial Number: 1600x1200 60 03:02:35 INFO - Main Display: Yes 03:02:35 INFO - Mirror: Off 03:02:35 INFO - Online: Yes 03:02:35 INFO - Rotation: Supported 03:02:35 INFO - Return code: 0 03:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:02:35 INFO - Python 2.7.3 03:02:35 INFO - Return code: 0 03:02:35 INFO - grabbing minidump binary from tooltool 03:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:35 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 0x10cd25d30>, '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 0x10cb8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:02:35 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:02:35 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:02:35 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:02:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:02:35 INFO - Return code: 0 03:02:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:02:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:02:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:02:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:02:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:02:35 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/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--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:02:35 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/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0124 --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:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HhvP7FHQjF/Render', 03:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:35 INFO - 'HOME': '/Users/cltbld', 03:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:35 INFO - 'LOGNAME': 'cltbld', 03:02:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:02:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:35 INFO - 'MOZ_NO_REMOTE': '1', 03:02:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:02:35 INFO - 'NO_EM_RESTART': '1', 03:02:35 INFO - 'PAGER': '/bin/cat', 03:02:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:35 INFO - 'PWD': '/builds/slave/test', 03:02:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:35 INFO - 'SHELL': '/bin/bash', 03:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6tMjEL1Oah/Listeners', 03:02:35 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:35 INFO - 'USER': 'cltbld', 03:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:35 INFO - 'XPC_FLAGS': '0x0', 03:02:35 INFO - 'XPC_SERVICE_NAME': '0', 03:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:35 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/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--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:02:35 INFO - mozversion application_buildid: 20161123004021 03:02:35 INFO - mozversion application_changeset: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee 03:02:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:02:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:02:35 INFO - mozversion application_name: Firefox 03:02:35 INFO - mozversion application_remotingname: firefox-dev 03:02:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:35 INFO - mozversion application_vendor: Mozilla 03:02:35 INFO - mozversion application_version: 52.0a2 03:02:35 INFO - mozversion platform_buildid: 20161123004021 03:02:35 INFO - mozversion platform_changeset: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee 03:02:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:35 INFO - mozversion platform_version: 52.0a2 03:02:35 INFO - using testdate: 1479898955 03:02:35 INFO - actual date: 1479898955 03:02:35 INFO - starting webserver on 'localhost:49243' 03:02:35 INFO - SUITE-START | Running 2 tests 03:02:35 INFO - TEST-START | dromaeo_css 03:02:35 INFO - operating with platform_type : mac_ 03:02:35 INFO - Initialising browser for dromaeo_css test... 03:02:35 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpJpdgMm/profile http://localhost:49243/getInfo.html) 03:02:38 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 03:02:38 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 03:02:38 INFO - PROCESS | 1634 | colorDepth:24 03:02:38 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 03:02:38 INFO - PROCESS | 1634 | __metrics 03:02:38 INFO - PROCESS | 1634 | 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:02:38 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:02:39 INFO - TEST-INFO | 1634: exit 0 03:02:39 INFO - Browser initialized. 03:02:39 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:39 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpJpdgMm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:51 INFO - PROCESS | 1637 | RSS: Main: 222990336 03:02:51 INFO - PROCESS | 1637 | 03:03:43 INFO - PROCESS | 1637 | 1479899023695 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:05:54 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 03:05:58 INFO - PROCESS | 1637 | RSS: Main: 492527616 03:05:58 INFO - PROCESS | 1637 | 03:09:01 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 03:09:02 INFO - PROCESS | 1637 | RSS: Main: 347586560 03:09:02 INFO - PROCESS | 1637 | 03:12:04 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 03:12:05 INFO - PROCESS | 1637 | RSS: Main: 348377088 03:12:05 INFO - PROCESS | 1637 | 03:15:07 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 03:15:08 INFO - PROCESS | 1637 | RSS: Main: 329400320 03:15:08 INFO - PROCESS | 1637 | 03:18:12 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 03:18:12 INFO - PROCESS | 1637 | RSS: Main: 320647168 03:18:12 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 03:21:15 INFO - PROCESS | 1637 | RSS: Main: 372666368 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | __start_tp_report 03:21:15 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 03:21:15 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 03:21:15 INFO - PROCESS | 1637 | |i|pagename|runs| 03:21:15 INFO - PROCESS | 1637 | |0;dojo.html;3162.3359094590137;3255.2024753935284;3288.7697861149713;3293.9294729212984;3351.8994430167104;5526.640768350059;5586.944130558698;5598.496135347828;5644.689542075184;5671.546276297891;5555.583331250163;5618.12918997555;5627.986962346776;5629.408912064226;5639.2668953036155;5066.594672426207;5085.389753229599;5098.770555325019;5143.922841157386;5187.247849061884;5029.0444815485125;5067.6452648314635;5078.974605126988;5105.846824595268;5167.689938603696;5071.98560287942;5153.2785410042625;5168;5181.3264275644115;5191.325127733408;5155.819546315861;5159.22611608258;5159.896802063975;5179.171332586768;5210.478952104797;4961.082199793013;4985.651004429691;5107.106256405114;5114.207297868837;5115.974420127913;4559.886002849956;5517.751701173457;5535.169724541311;5546.611737178399;5575.944240557583;6042.486388656926;6082.695865206766;6085.300190478063;6096.237970253718;6113.144159817629;6205.317415084337;6212.378762123797;6213.440790328892;6219.222597175353;6232.065190221458;5565.471280228372;5566.359868615121;5608.46719561641;5619.831405057855;5756.42435756421;6260.968695156495;6305.842353941188;6309.495240380758;6310.810675679737;6323.557350985387;5997.970010149921;6001.1898393717165;6002.519798416116;6023.337432882379;6029.517638588903;6018.759249630053;6074;6083.178770865877;6108.570292304616;6150.907736383958;5977.731002104916;6022.036474164112;6027.849303767353;6051.334353221142;6062.090686396988;5926.940730592725;6055.8183254501555;6078.149059131725;6078.756849725962;6112;4868.294097355863;4871.805127794849;4887.315775791392;4889.511048895153;4904.166291730415;4388.363687265329;4390.934135987963;4405;4407.757573333433;4433;4961.206207006861;5489.39616642169;5530.751116199781;5562.082256427725;5674.602777805514;6095.542834287447;6117.309816958451;6117.663528505975;6143.082036032938;6183.134361189437;5590.496855282962;5605.215269862222;5654.773809047674;5690.975624387567;5723.084306511021;5337.2527846101575;5347.81282655113;5348.786048558092;5357.249985002103;5358.651687640291;5163.974180129076;6133.151048553184;6181.629102253879;6200.81397558074;6203.844903877439;4975.726335051607;6179.3202747698615;6196.008638617799;6202.81391558254;6206.565540412128;6260.7495700171485;6281.089242059968;6285.43431091204;6307.24313082433;6308.684565771601;5875.383084776208;5962.403759624003;6039.818805435844;6089.786857459968;6119.755209791737;6073.726682299219;6098.878022439615;6100.603460775035;6103.450689437972;6105.297890742621;5912.940870591239;5941.7623295067715;5944;5944.851378715567;5950.642961422329;5064.924026139684;5441.564674825925;5914.467697907209;5931.733072011683;5933.703314834154;5674;5800.390958949419;5806.68063256525;5808.680522571299;5823.417658234142;5875.73559189846;5920.993431116634;5921.615095018982;5926.081457374114;5926.259217597801;5681.431856814451;5732.283464566929;5773.971130144322;5786.739596718242;5789.768409263583 03:21:15 INFO - PROCESS | 1637 | |1;ext.html;461.1975163216004;474;519.4934938435025;527.5542166869;547.1819629653669;30394.848025759868;39065.80467097664;39945.400818987735;40009.399859002064;42029.78985105078;33620.66379336206;40902.38646420306;41318.79340603293;41341.7932910335;41636.583634163646;9181.862272065908;9315.02192269812;9317.254619630414;9330.160285574297;9333.579988900481;6883.414909732679;8596.355273354493;10906.454677266105;11154.888451115507;11201.271917325388;11039.337639741601;11094.223404361697;11105.55577776888;11123.55505779772;11183.44082795861;8438.535880526568;11121.22151449399;11183.273087249303;11201.831972520418;11212.271202371861;8485.193906578865;10835.404052777094;11132.666020019331;11142.387168705718;11289.983901448826;39452.80273598642;39646.80176599127;39700;39779.602203977876;39867.40198897019;3259.5925509311337;3271.7219036381935;3286.211309285778;3603.351396748584;3605.13476765577;27347.589786153225;27522.76147573364;28386.29034274139;28450.715492845015;29221.707782922113;3132.98433507831;3234.8059116453087;3321.053499752583;3333.3000069985333;3333.3499967506345;492445.9999999964;492471;492742.53628731985;493342;496334;60453.69773151106;60765.39234607686;60868;60896;61023.694881525305;64563.67718161473;64689;64819.35180648133;64821.67589162024;64830;16104.03375797456;16343.673126537402;16364.672706545802;16367.017978921303;16378.590535236475;11268.830967535494;11565.95906368431;12306.876931230752;12561.120721549403;12676.556320528738;15520.223988800515;17492.562685932953;17883.28466861311;17902;18011.549711257063;5422.593305502103;5602.831915042555;5724.885502289931;6251.718672659741;6536.346365363521;887.4941283468361;925.7639301977953;950.6672664567346;1079.5897558927556;1106.833974903771;28456.288592785346;28787.28031799222;29083.201480729516;29237;29494;879.1867522541623;1009.9949500252452;1107.2636696596835;1157.427073598574;1171.6602185366319;14605.634859128606;15054.172020538976;15058.472953446795;15366.385344586315;15418.074915505105;642037.7898110573;647248;658311;658790;807791.8052048746;48683.756581217574;49627;49702.25446618304;55634;55819.72090139523;1334.6796768775423;1351.067763243359;1362.8364596248514;1364.1133263378883;1367.7332920080887;25236.873815630803;28936.421271574873;29524.852375738843;30091.849540752155;31436.742530299445;49992.25011624756;50466.24300635567;50544;50649;50678.74660626673;535052;647741;648133;673326;688020;25404.745952540237;25557.872210639573;25561.105361312264;25579.87210063938;25638.615420769078;44146;44412;44453.55546444623;44501.10997779961;44544.331835021854;36529.086772830895;36991.445128323634;37339.25321493501;38413.80793096017;38507.42238866475;36866;37096;37409;37876.62123378841;38331;35283.647163529065;35834.462483062256;36304.81847590745;36573.63426365809;36630.63369366379;31914.68085319117;31979.6802031986;33104.17239568934;33207.501887471226;33478;23524.8823755887;24350.87824560937;24424.755752442958;25040;25290.36774080663 03:21:15 INFO - PROCESS | 1637 | |2;jquery.html;335.59219075976273;356.12571137856565;375.9436084587313;388.71623714688275;406.81286608160246;14728.705425891476;15047.247637618117;15852.841471585281;19835.404937851887;20407.693884591743;17144.05707686077;19259.422217333504;20244.89877550614;20378.777273363572;20637.277695280667;5579.246801681782;5725.599208055438;5763.884722305552;5831.067029275317;5834.912476312858;5123.385193776733;5472.671639701611;5688.687122208277;5747.885042299152;5747.942520574804;5618.494335509803;5687.886242275152;5690.459406356389;5707.543396528268;5713.8571535711735;5585.58108141891;5868.6478811271245;5926.525877929756;5936.198613187251;5960.433758792908;5529.30883639545;5726.541876649858;5753.424657534255;5776.913346299809;5779.248697669276;10993.505292261867;13083.803742943863;13091.86908130916;13271.3364331784;13285.269310187936;2135.903884325209;2160.459885028743;2218.0684112672716;2415.7825795678295;2714.6335244741904;9686.370385924964;9827.066428689262;10083.840358358735;10107.938666439992;10377.896221037769;1766.7968183658786;1800.9369672061546;1805.2417984446563;1830.789459212181;1836.4857839804877;96524;110192.4490377543;111518;111713.44143279232;119710;11281.492332845042;11422.543098276143;11573.537058517733;11586.420678966018;11707.063434925185;14210.573682789533;14225.430982760574;14435.989480736247;14477.420903163966;14522.49171279;8341.290990265774;8450.830983380298;8468.110848360957;8474.025487069066;8595.613197406006;6905.274946130584;7456.32893039629;7720.382369410434;7720.42096842739;8236.299914507214;4126.442930204444;4166.958330416717;4230.196262710137;4278.679099067583;4556.681032327771;1830.20386132033;1885.6700077486385;1887.8489720822301;1887.905604719786;1888.1975160556708;803.2971150243537;890.7618410409536;917.2891009467716;936.1761649748314;953.5661274120258;19194.424167275003;19943.900280498794;20614.969251537364;20642;20651.69022464664;920.8664743612272;924.8427767279578;945.0691069296737;947.209080417845;969.3214749675255;10415.42715150674;11072.833907491395;11077.390743509184;11080.778384432428;11123.054540363997;611560;613015;619307;627389;629603;81251.5937420309;110356;114061;127669;134156;848.2568177311714;878.1043335797451;894.5840184314455;909.3452714046009;917.4311926605548;19126.808731912504;19140.23439062422;19668.90165549163;19684.409467715992;19689.015549222768;54546.999999998414;54814.72592637011;55758.72120639371;56833.71583142058;58459;80187.59906200432;81371.5931420339;81656.59171704104;82932;84839.5758021206;7610.505317154589;7789.961050194939;7946.7615971520945;8084.070331911906;8199.754007379788;9133.497657628894;12299.323537205182;12718.51988862163;12806.743865122458;13055.281959492318;10331.276810623183;10875.456227188452;11022.834657480305;11400.828987565028;11424.314541127558;10799.838002429813;11022.779544408906;11096.223264371416;11245.887541124484;11455.427228638702;5788.21859049023;6504.902426463702;11027.558897644005;11100.446897213847;11812.82280765772;7910.841783164419;7935.253264927811;8034.276915077671;8295.502269863828;8310.584470776557;10904.127669786556;11011.119110470983;11177.72055698614;11321.094312455149;11332.546698131982 03:21:15 INFO - PROCESS | 1637 | |3;mootools.html;1288.7615791078651;1468.7356275870343;1593.7131316363057;1744.624905645286;1816.9454916352513;8308.2107199816;8728.694495692653;8742.300615950724;8745.956270218656;8748.168923952222;7664.1952594977565;7938.603069846514;7943.165967573408;8432.789180270496;8599.22606965373;2342.543204075206;2368.46709490365;2373.620220764678;2376.9286921392386;2384.0225507541822;2018.6568283391784;2364.609839376501;2402.039184326266;2404.4589967257316;2412.264259400882;2346.2609278077434;2355.811535077198;2382.404398900275;2385.630227314769;2387.307680772574;2338.088145623208;2340.0873659272897;2364.810815134785;2368.6683864259016;2371.988140059306;2358.7995020423286;2371.1226846066893;2390.8685022323766;2391.9402014949765;2403.687520622308;8604.35467339952;8605.655773769042;8682;8698.826023479494;8707.25988290996;5141.820036298712;5305.522502974751;5311.415744268166;5318.095923692981;5321.255024296602;3501.8294622971866;3574.910627234314;3612.223371975038;3648.1244501319757;3648.5804132524836;1060.9204309676804;1077.536659236528;1109.9278546894507;1118.4184224203368;1122.4892673833385;11381.544738210461;11400.201985860993;11409.48657310423;11419.771604567863;11419.828702569468;4501.392312037899;4664.416947881515;4756.524347565285;4761.428628564595;4811.230203167476;8122.553259570662;9674.613015479303;9678.370905891095;9689.41863488179;9696.903030969741;5315.468453154731;5321.680699158062;5323.707196104174;5329.413764485904;5333.1466965286145;4298.173276357598;4374.8906277342685;4835.081334546495;4872.19608764557;4891.997140586172;3201.439748044083;3772.981135094307;3855.9228815423535;3889.202713443737;3914.8825535233987;1050.3802756373775;1256.8491780986158;1262.3435813376993;1271.3389037700344;1276.2087505746497;779.8791187365854;820.6307161777224;903.3134817538587;922.4142669404977;924.0251534630894;5605.971970140124;5772.567057470623;5825.79609713658;5883.94116058834;6058.57589968698;444.38896517288725;445.7793392270782;454.23688203817244;455.9840405585855;463.95824375805665;3900.0249937515623;3929.587393323717;3930.6462418382484;3943.6450719434815;3999.5402827261023;9169;10428.5828566858;10521.3687178768;10529.526171322155;10529.789404211873;10143.442110683984;10414;10464;10485.68542943713;10513.84229236562;8030.839383212303;8141;8174.468659537111;8178.345732341399;8188.754337369888;5602.943970560243;5624.353199382123;5712.200291959046;5779.479846813807;5809.50619197373;9567.569459374206;9714.271429642664;9732.562034708597;9737.853932190881;9752.268579856398;9447.433154010507;9623.374480658735;9794.21646268311;9917.65288214909;9969.252306077218;6659.7003134860015;6863.656817159222;6869.896951545631;7180.640967951686;7369.631518424165;7890.447668663368;8141.104478507241;8156.184381561797;8254.092049874389;8289.378296627656;7275.781726548212;7410.96294518524;7445.888311675438;7519.210482899217;7564.319211271012;7053.259407762111;7188.38898693596;7197.1363436387965;7217.206107328085;7361.5583065014125;6804.625745583842;6918.204406493318;6925.376716095576;6929.826754331182;6930.480213984072;4802.327674125555;4925.901481970269;4931.827386041471;4986.426560945538;4990.426100998432;4905.288733133604;4961.231009193581;5006.173981293192;5063.493650635054;5137.023965446702 03:21:15 INFO - PROCESS | 1637 | |4;prototype.html;288.34545581529926;290.88655424384496;299.0415717625011;301.763115953976;303.9422509723154;4756.738379389132;4910.459849416561;5108.744562771865;5258.947410525893;5278.604104692145;4427.1367083418745;4787.54518320759;5008.649394542384;5093.363329583802;5100.515451032155;3746.4193050077283;3898.5906479819705;3952.841886324544;3959.722819402643;3972.423998520213;3540.628234035415;3824.2351529694033;3921.980075341985;3944.842206311758;3966.4446977423186;3403.5235067090625;3486.378833842263;3859.2667393195343;3918.960810391902;3962;3069.785115041948;3309.9739080885;3846.0961674006585;3939.9315359436237;3943.2310699413624;3436.261203841161;3445.84564171003;3453.9654603453896;3466.3587236361354;3480.990512751299;3991.0244429007826;4205.201011807747;4326.8269269229195;4611.631069514431;4932.334134891816;4272.893177670583;4411.757353345564;4436.290193569013;4453.242948698697;4458.687891847572;4743.335932969387;5215.322008138917;5245.26566280721;5263.815766448194;5475.767952210721;3086.79935804172;3384.238546327081;3581.3553560359123;3657.3782456982376;3664.3221004114325;5508.3114610673665;5621.4659607337235;5664.065429204176;5703.857403564903;5735.598508104435;3390.456972463732;3727.552693676776;3730.8880733578035;3739.6447337502896;3800.8289626966853;3660.6888414485065;3796.924061518754;3803.54357477099;3827.885163445101;3829.9234015320094;3522.771019883751;4406.5593440655675;4417.9396944733035;4441.467023957081;4449.599536041707;5358.142697168434;5358.3837858645975;5377.569794416438;5385.299911011543;5413.9729301353245;1015.8679371681634;1137.1016896651552;1165.830954511608;1181.639599922015;1207.3600991474532;808.6482380164647;834.2741814621319;837.24647816964;838.3586556284447;838.4256784102909;4458.041521072985;4665.883352916137;4749.097671442415;4817.470078291385;4910.288008238786;4908.840110351434;4947.406311242674;5078.314427552233;5293.814716484904;5348.251244825728;4231.555686652857;4476.477997480873;4564;4730.337752714623;4952.975235123801;2465.211132437603;2526.595744680879;2534.809889258313;2695.299222202202;2729.5905614158037;4576.039031803359;4895.853124406273;5322.840314790563;5335.573154147659;5352.892942141136;4939.801788204215;4965.3048573199785;5704.8859022819315;5864.765409383577;5882.676452795052;4879.438579654477;5408.78364865401;5433.424306951019;5887.676177810176;5901.967155747727;5433.945660543344;5687.985367755096;5700.862176683743;5723.8855222896145;5727.570432217517;5642.830715078554;5655.688937108499;5677.886442271214;5679.870048731726;5685.914711279417;5125.974370128126;5808.796692115756;5870.823875283749;5932.199153114274;6011.6635267427;3395.2700169463683;3473.305338932173;3644.1982763791925;3764.642358975893;3785.1580645644144;4867.975660121819;5140.43455219933;5644.951726068041;5663.943360566342;5673.234113394804;5220.1647736362;5426.616212865694;5484.396716361277;5502.339719233718;5533.750981205775;4665.720056796603;5083.213006310322;5429.375621803385;5488.341399032141;5549.528290095387;4657.323363589173;5163.974180129076;5300.5759539236;5342.519173274424;5460.25271912984;4609.400777898852;5461.617686761889;5481.479259470344;5548.725022740657;5577.27495425592;5143.537081662668;5319.46805319465;5320.44135365781;5404.756785944721;5431.6740995540395 03:21:15 INFO - PROCESS | 1637 | |5;yui.html;693.8196069022055;741.8182545276404;753.9886901696475;767.393758930445;814.8818421328906;13857.376418061187;14160.362783674733;14498.927505362471;14681.412743490248;14919.254037298118;12585.244885306887;13232.073754837165;13392.129511581765;13396.129251598664;13404.463821447154;705.8270723672707;825.3026192867018;836.3434703757551;838.9370797190205;841.2344766262702;831.6798032757372;835.9540225287607;837.8198687282234;838.4172999765166;841.1672444280149;887.5562218890555;890.4568213389826;894.0657013420958;895.3598177303227;896.2068569316169;882.0561998661467;882.7837179890968;884.1158841158841;884.3323290915382;886.570013543431;650.6291413894082;651.9869602607921;653.7515744017243;655.9508036897251;655.9704813283414;12064.758704825943;12221.572244971383;12360;12411.75176496465;12417.317047562381;11628;11699.18105732599;11712.063034957184;11713.238639488405;11928;10105.646302379455;10148.695539133763;10372.273940824145;10402.687919362432;10403.583856645726;3497.6502349765074;3512.771669841452;3516.472529120627;3548.3258180945536;3595.94606080909;84200.4219620231;86549.56725216334;87726.5613671934;90304.09695903088;90981;8435.831283374298;8689.522076285739;8761.38670293086;8771.561421928878;9102.408343457653;18303.45089647313;18405.355812546495;18437.1703273353;18639;18723.625527489374;7403.851922961619;7458.477906546598;7572.20491848348;7582.924170758332;7690.46166768332;4122.628963393249;4137.8965525861495;4201.68487363449;4335.588119128741;4353.064091220403;3354.563906692163;3500.964990350115;3503.9299214015577;3512.7014203792965;3518.7888726676483;586.4253032028636;593.9554533410012;604.9909251361233;616.7872084130968;617.8548041210312;610.6397225636807;612.1919066831829;636.9840753981188;659.6701649175412;660.5475249454139;8539.359548033923;9083.36416450842;9199.816003679889;9242.86135707965;9251.953740231256;553.532265235875;583.8306891001562;605.1890466774544;609.9725512351955;637.0953246390137;4260.148841376957;4492.460904691458;4493.550644935481;4529.818807247673;4538.478075021349;94262.6434339147;106506;107260;111503;117672;63166;88662.55668721616;100935;102165.84839061194;108735;85109.99999999876;85520.57239713887;85760.57119714361;88157.11842881617;90287;8946.821063578822;9649.800790852869;9694.011937361236;9720.416774993522;9726.124648781644;54107.72946135244;54535.18197226965;56407.43592564022;56657.583560411316;57626.4237357621;52819.94360112699;53183.73408132935;73909;79509.40981180228;82260.58869705853;10567.260291779501;10615;10744.785104297714;10756.462176890967;10842.132629389787;11843.644690659294;11986.700332491757;12172.939135304267;12253.081018923438;13572.728545429234;12579;12652.557160499338;13000;13134.802977955147;13460.12509186939;12813.8077928833;12933.224006559636;13074.673133171365;13089.280089595164;13313;12811.807822882478;12917.07550318033;12964.740705185655;13079.476820927437;13196.14225075367;10852.403117828595;10860.67417977462;11324.37715925599;11504.194706370474;11507.194496385173;11247.268927519684;11315.943420282845;11491.712707182387;11646.126540509325;11649.242799218362 03:21:15 INFO - PROCESS | 1637 | __end_tp_report 03:21:15 INFO - PROCESS | 1637 | __start_cc_report 03:21:15 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,3718 03:21:15 INFO - PROCESS | 1637 | __end_cc_report 03:21:15 INFO - PROCESS | 1637 | __startTimestamp1479900075316__endTimestamp 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | ------- Summary: start ------- 03:21:15 INFO - PROCESS | 1637 | Number of tests: 6 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5608.27 Median:5773.97 stddev:631.98 (10.9%) stddev-sans-first:604.08 03:21:15 INFO - PROCESS | 1637 | Values: 3162.3 3255.2 3288.8 3293.9 3351.9 5526.6 5586.9 5598.5 5644.7 5671.5 5555.6 5618.1 5628.0 5629.4 5639.3 5066.6 5085.4 5098.8 5143.9 5187.2 5029.0 5067.6 5079.0 5105.8 5167.7 5072.0 5153.3 5168.0 5181.3 5191.3 5155.8 5159.2 5159.9 5179.2 5210.5 4961.1 4985.7 5107.1 5114.2 5116.0 4559.9 5517.8 5535.2 5546.6 5575.9 6042.5 6082.7 6085.3 6096.2 6113.1 6205.3 6212.4 6213.4 6219.2 6232.1 5565.5 5566.4 5608.5 5619.8 5756.4 6261.0 6305.8 6309.5 6310.8 6323.6 5998.0 6001.2 6002.5 6023.3 6029.5 6018.8 6074.0 6083.2 6108.6 6150.9 5977.7 6022.0 6027.8 6051.3 6062.1 5926.9 6055.8 6078.1 6078.8 6112.0 4868.3 4871.8 4887.3 4889.5 4904.2 4388.4 4390.9 4405.0 4407.8 4433.0 4961.2 5489.4 5530.8 5562.1 5674.6 6095.5 6117.3 6117.7 6143.1 6183.1 5590.5 5605.2 5654.8 5691.0 5723.1 5337.3 5347.8 5348.8 5357.2 5358.7 5164.0 6133.2 6181.6 6200.8 6203.8 4975.7 6179.3 6196.0 6202.8 6206.6 6260.7 6281.1 6285.4 6307.2 6308.7 5875.4 5962.4 6039.8 6089.8 6119.8 6073.7 6098.9 6100.6 6103.5 6105.3 5912.9 5941.8 5944.0 5944.9 5950.6 5064.9 5441.6 5914.5 5931.7 5933.7 5674.0 5800.4 5806.7 5808.7 5823.4 5875.7 5921.0 5921.6 5926.1 5926.3 5681.4 5732.3 5774.0 5786.7 5789.8 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:72605.03 Median:19954.98 stddev:164156.47 (822.6%) stddev-sans-first:164527.61 03:21:15 INFO - PROCESS | 1637 | Values: 461.2 474.0 519.5 527.6 547.2 30394.8 39065.8 39945.4 40009.4 42029.8 33620.7 40902.4 41318.8 41341.8 41636.6 9181.9 9315.0 9317.3 9330.2 9333.6 6883.4 8596.4 10906.5 11154.9 11201.3 11039.3 11094.2 11105.6 11123.6 11183.4 8438.5 11121.2 11183.3 11201.8 11212.3 8485.2 10835.4 11132.7 11142.4 11290.0 39452.8 39646.8 39700.0 39779.6 39867.4 3259.6 3271.7 3286.2 3603.4 3605.1 27347.6 27522.8 28386.3 28450.7 29221.7 3133.0 3234.8 3321.1 3333.3 3333.3 492446.0 492471.0 492742.5 493342.0 496334.0 60453.7 60765.4 60868.0 60896.0 61023.7 64563.7 64689.0 64819.4 64821.7 64830.0 16104.0 16343.7 16364.7 16367.0 16378.6 11268.8 11566.0 12306.9 12561.1 12676.6 15520.2 17492.6 17883.3 17902.0 18011.5 5422.6 5602.8 5724.9 6251.7 6536.3 887.5 925.8 950.7 1079.6 1106.8 28456.3 28787.3 29083.2 29237.0 29494.0 879.2 1010.0 1107.3 1157.4 1171.7 14605.6 15054.2 15058.5 15366.4 15418.1 642037.8 647248.0 658311.0 658790.0 807791.8 48683.8 49627.0 49702.3 55634.0 55819.7 1334.7 1351.1 1362.8 1364.1 1367.7 25236.9 28936.4 29524.9 30091.8 31436.7 49992.3 50466.2 50544.0 50649.0 50678.7 535052.0 647741.0 648133.0 673326.0 688020.0 25404.7 25557.9 25561.1 25579.9 25638.6 44146.0 44412.0 44453.6 44501.1 44544.3 36529.1 36991.4 37339.3 38413.8 38507.4 36866.0 37096.0 37409.0 37876.6 38331.0 35283.6 35834.5 36304.8 36573.6 36630.6 31914.7 31979.7 33104.2 33207.5 33478.0 23524.9 24350.9 24424.8 25040.0 25290.4 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:34623.41 Median:382.33 stddev:103031.69 (26948.4%) stddev-sans-first:103288.55 03:21:15 INFO - PROCESS | 1637 | Values: 335.6 356.1 375.9 388.7 406.8 14728.7 15047.2 15852.8 19835.4 20407.7 17144.1 19259.4 20244.9 20378.8 20637.3 5579.2 5725.6 5763.9 5831.1 5834.9 5123.4 5472.7 5688.7 5747.9 5747.9 5618.5 5687.9 5690.5 5707.5 5713.9 5585.6 5868.6 5926.5 5936.2 5960.4 5529.3 5726.5 5753.4 5776.9 5779.2 10993.5 13083.8 13091.9 13271.3 13285.3 2135.9 2160.5 2218.1 2415.8 2714.6 9686.4 9827.1 10083.8 10107.9 10377.9 1766.8 1800.9 1805.2 1830.8 1836.5 96524.0 110192.4 111518.0 111713.4 119710.0 11281.5 11422.5 11573.5 11586.4 11707.1 14210.6 14225.4 14436.0 14477.4 14522.5 8341.3 8450.8 8468.1 8474.0 8595.6 6905.3 7456.3 7720.4 7720.4 8236.3 4126.4 4167.0 4230.2 4278.7 4556.7 1830.2 1885.7 1887.8 1887.9 1888.2 803.3 890.8 917.3 936.2 953.6 19194.4 19943.9 20615.0 20642.0 20651.7 920.9 924.8 945.1 947.2 969.3 10415.4 11072.8 11077.4 11080.8 11123.1 611560.0 613015.0 619307.0 627389.0 629603.0 81251.6 110356.0 114061.0 127669.0 134156.0 848.3 878.1 894.6 909.3 917.4 19126.8 19140.2 19668.9 19684.4 19689.0 54547.0 54814.7 55758.7 56833.7 58459.0 80187.6 81371.6 81656.6 82932.0 84839.6 7610.5 7790.0 7946.8 8084.1 8199.8 9133.5 12299.3 12718.5 12806.7 13055.3 10331.3 10875.5 11022.8 11400.8 11424.3 10799.8 11022.8 11096.2 11245.9 11455.4 5788.2 6504.9 11027.6 11100.4 11812.8 7910.8 7935.3 8034.3 8295.5 8310.6 10904.1 11011.1 11177.7 11321.1 11332.5 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5579.25 Median:4998.30 stddev:3105.67 (62.1%) stddev-sans-first:3097.64 03:21:15 INFO - PROCESS | 1637 | Values: 1288.8 1468.7 1593.7 1744.6 1816.9 8308.2 8728.7 8742.3 8746.0 8748.2 7664.2 7938.6 7943.2 8432.8 8599.2 2342.5 2368.5 2373.6 2376.9 2384.0 2018.7 2364.6 2402.0 2404.5 2412.3 2346.3 2355.8 2382.4 2385.6 2387.3 2338.1 2340.1 2364.8 2368.7 2372.0 2358.8 2371.1 2390.9 2391.9 2403.7 8604.4 8605.7 8682.0 8698.8 8707.3 5141.8 5305.5 5311.4 5318.1 5321.3 3501.8 3574.9 3612.2 3648.1 3648.6 1060.9 1077.5 1109.9 1118.4 1122.5 11381.5 11400.2 11409.5 11419.8 11419.8 4501.4 4664.4 4756.5 4761.4 4811.2 8122.6 9674.6 9678.4 9689.4 9696.9 5315.5 5321.7 5323.7 5329.4 5333.1 4298.2 4374.9 4835.1 4872.2 4892.0 3201.4 3773.0 3855.9 3889.2 3914.9 1050.4 1256.8 1262.3 1271.3 1276.2 779.9 820.6 903.3 922.4 924.0 5606.0 5772.6 5825.8 5883.9 6058.6 444.4 445.8 454.2 456.0 464.0 3900.0 3929.6 3930.6 3943.6 3999.5 9169.0 10428.6 10521.4 10529.5 10529.8 10143.4 10414.0 10464.0 10485.7 10513.8 8030.8 8141.0 8174.5 8178.3 8188.8 5602.9 5624.4 5712.2 5779.5 5809.5 9567.6 9714.3 9732.6 9737.9 9752.3 9447.4 9623.4 9794.2 9917.7 9969.3 6659.7 6863.7 6869.9 7180.6 7369.6 7890.4 8141.1 8156.2 8254.1 8289.4 7275.8 7411.0 7445.9 7519.2 7564.3 7053.3 7188.4 7197.1 7217.2 7361.6 6804.6 6918.2 6925.4 6929.8 6930.5 4802.3 4925.9 4931.8 4986.4 4990.4 4905.3 4961.2 5006.2 5063.5 5137.0 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4352.13 Median:4873.71 stddev:1373.87 (28.2%) stddev-sans-first:1343.44 03:21:15 INFO - PROCESS | 1637 | Values: 288.3 290.9 299.0 301.8 303.9 4756.7 4910.5 5108.7 5258.9 5278.6 4427.1 4787.5 5008.6 5093.4 5100.5 3746.4 3898.6 3952.8 3959.7 3972.4 3540.6 3824.2 3922.0 3944.8 3966.4 3403.5 3486.4 3859.3 3919.0 3962.0 3069.8 3310.0 3846.1 3939.9 3943.2 3436.3 3445.8 3454.0 3466.4 3481.0 3991.0 4205.2 4326.8 4611.6 4932.3 4272.9 4411.8 4436.3 4453.2 4458.7 4743.3 5215.3 5245.3 5263.8 5475.8 3086.8 3384.2 3581.4 3657.4 3664.3 5508.3 5621.5 5664.1 5703.9 5735.6 3390.5 3727.6 3730.9 3739.6 3800.8 3660.7 3796.9 3803.5 3827.9 3829.9 3522.8 4406.6 4417.9 4441.5 4449.6 5358.1 5358.4 5377.6 5385.3 5414.0 1015.9 1137.1 1165.8 1181.6 1207.4 808.6 834.3 837.2 838.4 838.4 4458.0 4665.9 4749.1 4817.5 4910.3 4908.8 4947.4 5078.3 5293.8 5348.3 4231.6 4476.5 4564.0 4730.3 4953.0 2465.2 2526.6 2534.8 2695.3 2729.6 4576.0 4895.9 5322.8 5335.6 5352.9 4939.8 4965.3 5704.9 5864.8 5882.7 4879.4 5408.8 5433.4 5887.7 5902.0 5433.9 5688.0 5700.9 5723.9 5727.6 5642.8 5655.7 5677.9 5679.9 5685.9 5126.0 5808.8 5870.8 5932.2 6011.7 3395.3 3473.3 3644.2 3764.6 3785.2 4868.0 5140.4 5645.0 5663.9 5673.2 5220.2 5426.6 5484.4 5502.3 5533.8 4665.7 5083.2 5429.4 5488.3 5549.5 4657.3 5164.0 5300.6 5342.5 5460.3 4609.4 5461.6 5481.5 5548.7 5577.3 5143.5 5319.5 5320.4 5404.8 5431.7 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20049.32 Median:4534.15 stddev:29796.30 (657.2%) stddev-sans-first:29844.44 03:21:15 INFO - PROCESS | 1637 | Values: 693.8 741.8 754.0 767.4 814.9 13857.4 14160.4 14498.9 14681.4 14919.3 12585.2 13232.1 13392.1 13396.1 13404.5 705.8 825.3 836.3 838.9 841.2 831.7 836.0 837.8 838.4 841.2 887.6 890.5 894.1 895.4 896.2 882.1 882.8 884.1 884.3 886.6 650.6 652.0 653.8 656.0 656.0 12064.8 12221.6 12360.0 12411.8 12417.3 11628.0 11699.2 11712.1 11713.2 11928.0 10105.6 10148.7 10372.3 10402.7 10403.6 3497.7 3512.8 3516.5 3548.3 3595.9 84200.4 86549.6 87726.6 90304.1 90981.0 8435.8 8689.5 8761.4 8771.6 9102.4 18303.5 18405.4 18437.2 18639.0 18723.6 7403.9 7458.5 7572.2 7582.9 7690.5 4122.6 4137.9 4201.7 4335.6 4353.1 3354.6 3501.0 3503.9 3512.7 3518.8 586.4 594.0 605.0 616.8 617.9 610.6 612.2 637.0 659.7 660.5 8539.4 9083.4 9199.8 9242.9 9252.0 553.5 583.8 605.2 610.0 637.1 4260.1 4492.5 4493.6 4529.8 4538.5 94262.6 106506.0 107260.0 111503.0 117672.0 63166.0 88662.6 100935.0 102165.8 108735.0 85110.0 85520.6 85760.6 88157.1 90287.0 8946.8 9649.8 9694.0 9720.4 9726.1 54107.7 54535.2 56407.4 56657.6 57626.4 52819.9 53183.7 73909.0 79509.4 82260.6 10567.3 10615.0 10744.8 10756.5 10842.1 11843.6 11986.7 12172.9 12253.1 13572.7 12579.0 12652.6 13000.0 13134.8 13460.1 12813.8 12933.2 13074.7 13089.3 13313.0 12811.8 12917.1 12964.7 13079.5 13196.1 10852.4 10860.7 11324.4 11504.2 11507.2 11247.3 11315.9 11491.7 11646.1 11649.2 03:21:15 INFO - PROCESS | 1637 | -------- Summary: end -------- 03:21:15 INFO - PROCESS | 1637 | 03:21:15 INFO - TEST-INFO | 1637: exit 0 03:21:15 INFO - TEST-OK | dromaeo_css | took 1120106ms 03:21:15 INFO - TEST-START | kraken 03:21:15 INFO - operating with platform_type : mac_ 03:21:15 INFO - Initialising browser for kraken test... 03:21:16 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpfXZnev/profile http://localhost:49243/getInfo.html) 03:21:17 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 03:21:18 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 03:21:18 INFO - PROCESS | 1646 | colorDepth:24 03:21:18 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 03:21:18 INFO - PROCESS | 1646 | __metrics 03:21:18 INFO - PROCESS | 1646 | 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:21:18 INFO - PROCESS | 1646 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:21:19 INFO - TEST-INFO | 1646: exit 0 03:21:19 INFO - Browser initialized. 03:21:19 INFO - Running cycle 1/1 for kraken test... 03:21:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpfXZnev/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:31 INFO - PROCESS | 1648 | RSS: Main: 222887936 03:21:31 INFO - PROCESS | 1648 | 03:21:34 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 03:21:35 INFO - PROCESS | 1648 | RSS: Main: 272429056 03:21:35 INFO - PROCESS | 1648 | 03:21:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 03:21:38 INFO - PROCESS | 1648 | RSS: Main: 282218496 03:21:38 INFO - PROCESS | 1648 | 03:21:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 03:21:42 INFO - PROCESS | 1648 | RSS: Main: 379592704 03:21:42 INFO - PROCESS | 1648 | 03:21:44 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 03:21:45 INFO - PROCESS | 1648 | RSS: Main: 283340800 03:21:45 INFO - PROCESS | 1648 | 03:21:47 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:21:47 INFO - PROCESS | 1648 | RSS: Main: 284250112 03:21:47 INFO - PROCESS | 1648 | 03:21:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 03:21:51 INFO - PROCESS | 1648 | RSS: Main: 324345856 03:21:51 INFO - PROCESS | 1648 | 03:21:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 03:21:54 INFO - PROCESS | 1648 | RSS: Main: 324648960 03:21:54 INFO - PROCESS | 1648 | 03:21:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 03:21:58 INFO - PROCESS | 1648 | RSS: Main: 323244032 03:21:58 INFO - PROCESS | 1648 | 03:21:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:22:00 INFO - PROCESS | 1648 | RSS: Main: 301441024 03:22:00 INFO - PROCESS | 1648 | 03:22:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:22:02 INFO - PROCESS | 1648 | RSS: Main: 302747648 03:22:02 INFO - PROCESS | 1648 | 03:22:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:22:05 INFO - PROCESS | 1648 | RSS: Main: 304459776 03:22:05 INFO - PROCESS | 1648 | 03:22:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:22:09 INFO - PROCESS | 1648 | RSS: Main: 314617856 03:22:09 INFO - PROCESS | 1648 | 03:22:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:22:12 INFO - PROCESS | 1648 | RSS: Main: 314888192 03:22:12 INFO - PROCESS | 1648 | 03:22:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 03:22:15 INFO - PROCESS | 1648 | RSS: Main: 358199296 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | __start_tp_report 03:22:15 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 03:22:15 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 03:22:15 INFO - PROCESS | 1648 | |i|pagename|runs| 03:22:15 INFO - PROCESS | 1648 | |0;ai-astar;116;114;114;114;118;163;169;114;114;115 03:22:15 INFO - PROCESS | 1648 | |1;audio-beat-detection;148;113;111;147;115;114;114;111;144;112 03:22:15 INFO - PROCESS | 1648 | |2;audio-dft;184;185;183;182;187;183;197;264;204;184 03:22:15 INFO - PROCESS | 1648 | |3;audio-fft;79;62;58;87;61;60;58;60;104;76 03:22:15 INFO - PROCESS | 1648 | |4;audio-oscillator;87;72;94;69;67;68;90;68;68;91 03:22:15 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;87;84;87;84;87;84;88;116;88;84 03:22:15 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;102;153;135;92;94;93;92;92;93 03:22:15 INFO - PROCESS | 1648 | |7;imaging-desaturate;84;84;82;80;84;91;86;85;81;84 03:22:15 INFO - PROCESS | 1648 | |8;json-parse-financial;53;53;55;55;57;57;57;57;57;57 03:22:15 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;65;72;68;81;75;73;71;71;70;74 03:22:15 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;84;95;103;103;84;85;84;83;85;84 03:22:15 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;145;158;150;158;155;154;146;150;197;160 03:22:15 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;167;164;165;165;166;168;166;167;167;165 03:22:15 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;66;67;65;69;86;88;85;87;67;66 03:22:15 INFO - PROCESS | 1648 | __end_tp_report 03:22:15 INFO - PROCESS | 1648 | __start_cc_report 03:22:15 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1953 03:22:15 INFO - PROCESS | 1648 | __end_cc_report 03:22:15 INFO - PROCESS | 1648 | __startTimestamp1479900135009__endTimestamp 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | ------- Summary: start ------- 03:22:15 INFO - PROCESS | 1648 | Number of tests: 14 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:125.10 Median:115.50 stddev:21.64 (18.7%) stddev-sans-first:22.70 03:22:15 INFO - PROCESS | 1648 | Values: 116.0 114.0 114.0 114.0 118.0 163.0 169.0 114.0 114.0 115.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:114.50 stddev:16.25 (14.2%) stddev-sans-first:14.48 03:22:15 INFO - PROCESS | 1648 | Values: 148.0 113.0 111.0 147.0 115.0 114.0 114.0 111.0 144.0 112.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:195.30 Median:186.00 stddev:25.18 (13.5%) stddev-sans-first:26.38 03:22:15 INFO - PROCESS | 1648 | Values: 184.0 185.0 183.0 182.0 187.0 183.0 197.0 264.0 204.0 184.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:70.50 Median:61.50 stddev:15.61 (25.4%) stddev-sans-first:16.25 03:22:15 INFO - PROCESS | 1648 | Values: 79.0 62.0 58.0 87.0 61.0 60.0 58.0 60.0 104.0 76.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.50 stddev:11.47 (14.4%) stddev-sans-first:11.63 03:22:15 INFO - PROCESS | 1648 | Values: 87.0 72.0 94.0 69.0 67.0 68.0 90.0 68.0 68.0 91.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:87.00 stddev:9.68 (11.1%) stddev-sans-first:10.24 03:22:15 INFO - PROCESS | 1648 | Values: 87.0 84.0 87.0 84.0 87.0 84.0 88.0 116.0 88.0 84.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:92.50 stddev:21.71 (23.5%) stddev-sans-first:22.72 03:22:15 INFO - PROCESS | 1648 | Values: 94.0 102.0 153.0 135.0 92.0 94.0 93.0 92.0 92.0 93.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:3.03 (3.6%) stddev-sans-first:3.22 03:22:15 INFO - PROCESS | 1648 | Values: 84.0 84.0 82.0 80.0 84.0 91.0 86.0 85.0 81.0 84.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:57.00 stddev:1.69 (3.0%) stddev-sans-first:1.45 03:22:15 INFO - PROCESS | 1648 | Values: 53.0 53.0 55.0 55.0 57.0 57.0 57.0 57.0 57.0 57.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:4.29 (5.9%) stddev-sans-first:3.73 03:22:15 INFO - PROCESS | 1648 | Values: 65.0 72.0 68.0 81.0 75.0 73.0 71.0 71.0 70.0 74.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:84.00 stddev:8.14 (9.7%) stddev-sans-first:8.43 03:22:15 INFO - PROCESS | 1648 | Values: 84.0 95.0 103.0 103.0 84.0 85.0 84.0 83.0 85.0 84.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:156.50 stddev:14.86 (9.5%) stddev-sans-first:15.07 03:22:15 INFO - PROCESS | 1648 | Values: 145.0 158.0 150.0 158.0 155.0 154.0 146.0 150.0 197.0 160.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.50 stddev:1.25 (0.7%) stddev-sans-first:1.27 03:22:15 INFO - PROCESS | 1648 | Values: 167.0 164.0 165.0 165.0 166.0 168.0 166.0 167.0 167.0 165.0 03:22:15 INFO - PROCESS | 1648 | 03:22:15 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:77.00 stddev:10.32 (13.4%) stddev-sans-first:10.47 03:22:15 INFO - PROCESS | 1648 | Values: 66.0 67.0 65.0 69.0 86.0 88.0 85.0 87.0 67.0 66.0 03:22:15 INFO - PROCESS | 1648 | -------- Summary: end -------- 03:22:15 INFO - PROCESS | 1648 | 03:22:16 INFO - TEST-INFO | 1648: exit 0 03:22:16 INFO - TEST-OK | kraken | took 60065ms 03:22:16 INFO - SUITE-END | took 1180s 03:22:16 INFO - Completed test suite (00:19:40) 03:22:16 INFO - javascript benchmark 03:22:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3162.3359094590137, 3255.2024753935284, 3288.7697861149713, 3293.9294729212984, 3351.8994430167104, 5526.640768350059, 5586.944130558698, 5598.496135347828, 5644.689542075184, 5671.546276297891, 5555.583331250163, 5618.12918997555, 5627.986962346776, 5629.408912064226, 5639.2668953036155, 5066.594672426207, 5085.389753229599, 5098.770555325019, 5143.922841157386, 5187.247849061884, 5029.0444815485125, 5067.6452648314635, 5078.974605126988, 5105.846824595268, 5167.689938603696, 5071.98560287942, 5153.2785410042625, 5168.0, 5181.3264275644115, 5191.325127733408, 5155.819546315861, 5159.22611608258, 5159.896802063975, 5179.171332586768, 5210.478952104797, 4961.082199793013, 4985.651004429691, 5107.106256405114, 5114.207297868837, 5115.974420127913, 4559.886002849956, 5517.751701173457, 5535.169724541311, 5546.611737178399, 5575.944240557583, 6042.486388656926, 6082.695865206766, 6085.300190478063, 6096.237970253718, 6113.144159817629, 6205.317415084337, 6212.378762123797, 6213.440790328892, 6219.222597175353, 6232.065190221458, 5565.471280228372, 5566.359868615121, 5608.46719561641, 5619.831405057855, 5756.42435756421, 6260.968695156495, 6305.842353941188, 6309.495240380758, 6310.810675679737, 6323.557350985387, 5997.970010149921, 6001.1898393717165, 6002.519798416116, 6023.337432882379, 6029.517638588903, 6018.759249630053, 6074.0, 6083.178770865877, 6108.570292304616, 6150.907736383958, 5977.731002104916, 6022.036474164112, 6027.849303767353, 6051.334353221142, 6062.090686396988, 5926.940730592725, 6055.8183254501555, 6078.149059131725, 6078.756849725962, 6112.0, 4868.294097355863, 4871.805127794849, 4887.315775791392, 4889.511048895153, 4904.166291730415, 4388.363687265329, 4390.934135987963, 4405.0, 4407.757573333433, 4433.0, 4961.206207006861, 5489.39616642169, 5530.751116199781, 5562.082256427725, 5674.602777805514, 6095.542834287447, 6117.309816958451, 6117.663528505975, 6143.082036032938, 6183.134361189437, 5590.496855282962, 5605.215269862222, 5654.773809047674, 5690.975624387567, 5723.084306511021, 5337.2527846101575, 5347.81282655113, 5348.786048558092, 5357.249985002103, 5358.651687640291, 5163.974180129076, 6133.151048553184, 6181.629102253879, 6200.81397558074, 6203.844903877439, 4975.726335051607, 6179.3202747698615, 6196.008638617799, 6202.81391558254, 6206.565540412128, 6260.7495700171485, 6281.089242059968, 6285.43431091204, 6307.24313082433, 6308.684565771601, 5875.383084776208, 5962.403759624003, 6039.818805435844, 6089.786857459968, 6119.755209791737, 6073.726682299219, 6098.878022439615, 6100.603460775035, 6103.450689437972, 6105.297890742621, 5912.940870591239, 5941.7623295067715, 5944.0, 5944.851378715567, 5950.642961422329, 5064.924026139684, 5441.564674825925, 5914.467697907209, 5931.733072011683, 5933.703314834154, 5674.0, 5800.390958949419, 5806.68063256525, 5808.680522571299, 5823.417658234142, 5875.73559189846, 5920.993431116634, 5921.615095018982, 5926.081457374114, 5926.259217597801, 5681.431856814451, 5732.283464566929, 5773.971130144322, 5786.739596718242, 5789.768409263583], "value": 5568.638098931692, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.1975163216004, 474.0, 519.4934938435025, 527.5542166869, 547.1819629653669, 30394.848025759868, 39065.80467097664, 39945.400818987735, 40009.399859002064, 42029.78985105078, 33620.66379336206, 40902.38646420306, 41318.79340603293, 41341.7932910335, 41636.583634163646, 9181.862272065908, 9315.02192269812, 9317.254619630414, 9330.160285574297, 9333.579988900481, 6883.414909732679, 8596.355273354493, 10906.454677266105, 11154.888451115507, 11201.271917325388, 11039.337639741601, 11094.223404361697, 11105.55577776888, 11123.55505779772, 11183.44082795861, 8438.535880526568, 11121.22151449399, 11183.273087249303, 11201.831972520418, 11212.271202371861, 8485.193906578865, 10835.404052777094, 11132.666020019331, 11142.387168705718, 11289.983901448826, 39452.80273598642, 39646.80176599127, 39700.0, 39779.602203977876, 39867.40198897019, 3259.5925509311337, 3271.7219036381935, 3286.211309285778, 3603.351396748584, 3605.13476765577, 27347.589786153225, 27522.76147573364, 28386.29034274139, 28450.715492845015, 29221.707782922113, 3132.98433507831, 3234.8059116453087, 3321.053499752583, 3333.3000069985333, 3333.3499967506345, 492445.9999999964, 492471.0, 492742.53628731985, 493342.0, 496334.0, 60453.69773151106, 60765.39234607686, 60868.0, 60896.0, 61023.694881525305, 64563.67718161473, 64689.0, 64819.35180648133, 64821.67589162024, 64830.0, 16104.03375797456, 16343.673126537402, 16364.672706545802, 16367.017978921303, 16378.590535236475, 11268.830967535494, 11565.95906368431, 12306.876931230752, 12561.120721549403, 12676.556320528738, 15520.223988800515, 17492.562685932953, 17883.28466861311, 17902.0, 18011.549711257063, 5422.593305502103, 5602.831915042555, 5724.885502289931, 6251.718672659741, 6536.346365363521, 887.4941283468361, 925.7639301977953, 950.6672664567346, 1079.5897558927556, 1106.833974903771, 28456.288592785346, 28787.28031799222, 29083.201480729516, 29237.0, 29494.0, 879.1867522541623, 1009.9949500252452, 1107.2636696596835, 1157.427073598574, 1171.6602185366319, 14605.634859128606, 15054.172020538976, 15058.472953446795, 15366.385344586315, 15418.074915505105, 642037.7898110573, 647248.0, 658311.0, 658790.0, 807791.8052048746, 48683.756581217574, 49627.0, 49702.25446618304, 55634.0, 55819.72090139523, 1334.6796768775423, 1351.067763243359, 1362.8364596248514, 1364.1133263378883, 1367.7332920080887, 25236.873815630803, 28936.421271574873, 29524.852375738843, 30091.849540752155, 31436.742530299445, 49992.25011624756, 50466.24300635567, 50544.0, 50649.0, 50678.74660626673, 535052.0, 647741.0, 648133.0, 673326.0, 688020.0, 25404.745952540237, 25557.872210639573, 25561.105361312264, 25579.87210063938, 25638.615420769078, 44146.0, 44412.0, 44453.55546444623, 44501.10997779961, 44544.331835021854, 36529.086772830895, 36991.445128323634, 37339.25321493501, 38413.80793096017, 38507.42238866475, 36866.0, 37096.0, 37409.0, 37876.62123378841, 38331.0, 35283.647163529065, 35834.462483062256, 36304.81847590745, 36573.63426365809, 36630.63369366379, 31914.68085319117, 31979.6802031986, 33104.17239568934, 33207.501887471226, 33478.0, 23524.8823755887, 24350.87824560937, 24424.755752442958, 25040.0, 25290.36774080663], "value": 19718.79569378322, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.59219075976273, 356.12571137856565, 375.9436084587313, 388.71623714688275, 406.81286608160246, 14728.705425891476, 15047.247637618117, 15852.841471585281, 19835.404937851887, 20407.693884591743, 17144.05707686077, 19259.422217333504, 20244.89877550614, 20378.777273363572, 20637.277695280667, 5579.246801681782, 5725.599208055438, 5763.884722305552, 5831.067029275317, 5834.912476312858, 5123.385193776733, 5472.671639701611, 5688.687122208277, 5747.885042299152, 5747.942520574804, 5618.494335509803, 5687.886242275152, 5690.459406356389, 5707.543396528268, 5713.8571535711735, 5585.58108141891, 5868.6478811271245, 5926.525877929756, 5936.198613187251, 5960.433758792908, 5529.30883639545, 5726.541876649858, 5753.424657534255, 5776.913346299809, 5779.248697669276, 10993.505292261867, 13083.803742943863, 13091.86908130916, 13271.3364331784, 13285.269310187936, 2135.903884325209, 2160.459885028743, 2218.0684112672716, 2415.7825795678295, 2714.6335244741904, 9686.370385924964, 9827.066428689262, 10083.840358358735, 10107.938666439992, 10377.896221037769, 1766.7968183658786, 1800.9369672061546, 1805.2417984446563, 1830.789459212181, 1836.4857839804877, 96524.0, 110192.4490377543, 111518.0, 111713.44143279232, 119710.0, 11281.492332845042, 11422.543098276143, 11573.537058517733, 11586.420678966018, 11707.063434925185, 14210.573682789533, 14225.430982760574, 14435.989480736247, 14477.420903163966, 14522.49171279, 8341.290990265774, 8450.830983380298, 8468.110848360957, 8474.025487069066, 8595.613197406006, 6905.274946130584, 7456.32893039629, 7720.382369410434, 7720.42096842739, 8236.299914507214, 4126.442930204444, 4166.958330416717, 4230.196262710137, 4278.679099067583, 4556.681032327771, 1830.20386132033, 1885.6700077486385, 1887.8489720822301, 1887.905604719786, 1888.1975160556708, 803.2971150243537, 890.7618410409536, 917.2891009467716, 936.1761649748314, 953.5661274120258, 19194.424167275003, 19943.900280498794, 20614.969251537364, 20642.0, 20651.69022464664, 920.8664743612272, 924.8427767279578, 945.0691069296737, 947.209080417845, 969.3214749675255, 10415.42715150674, 11072.833907491395, 11077.390743509184, 11080.778384432428, 11123.054540363997, 611560.0, 613015.0, 619307.0, 627389.0, 629603.0, 81251.5937420309, 110356.0, 114061.0, 127669.0, 134156.0, 848.2568177311714, 878.1043335797451, 894.5840184314455, 909.3452714046009, 917.4311926605548, 19126.808731912504, 19140.23439062422, 19668.90165549163, 19684.409467715992, 19689.015549222768, 54546.999999998414, 54814.72592637011, 55758.72120639371, 56833.71583142058, 58459.0, 80187.59906200432, 81371.5931420339, 81656.59171704104, 82932.0, 84839.5758021206, 7610.505317154589, 7789.961050194939, 7946.7615971520945, 8084.070331911906, 8199.754007379788, 9133.497657628894, 12299.323537205182, 12718.51988862163, 12806.743865122458, 13055.281959492318, 10331.276810623183, 10875.456227188452, 11022.834657480305, 11400.828987565028, 11424.314541127558, 10799.838002429813, 11022.779544408906, 11096.223264371416, 11245.887541124484, 11455.427228638702, 5788.21859049023, 6504.902426463702, 11027.558897644005, 11100.446897213847, 11812.82280765772, 7910.841783164419, 7935.253264927811, 8034.276915077671, 8295.502269863828, 8310.584470776557, 10904.127669786556, 11011.119110470983, 11177.72055698614, 11321.094312455149, 11332.546698131982], "value": 9141.67231395526, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.7615791078651, 1468.7356275870343, 1593.7131316363057, 1744.624905645286, 1816.9454916352513, 8308.2107199816, 8728.694495692653, 8742.300615950724, 8745.956270218656, 8748.168923952222, 7664.1952594977565, 7938.603069846514, 7943.165967573408, 8432.789180270496, 8599.22606965373, 2342.543204075206, 2368.46709490365, 2373.620220764678, 2376.9286921392386, 2384.0225507541822, 2018.6568283391784, 2364.609839376501, 2402.039184326266, 2404.4589967257316, 2412.264259400882, 2346.2609278077434, 2355.811535077198, 2382.404398900275, 2385.630227314769, 2387.307680772574, 2338.088145623208, 2340.0873659272897, 2364.810815134785, 2368.6683864259016, 2371.988140059306, 2358.7995020423286, 2371.1226846066893, 2390.8685022323766, 2391.9402014949765, 2403.687520622308, 8604.35467339952, 8605.655773769042, 8682.0, 8698.826023479494, 8707.25988290996, 5141.820036298712, 5305.522502974751, 5311.415744268166, 5318.095923692981, 5321.255024296602, 3501.8294622971866, 3574.910627234314, 3612.223371975038, 3648.1244501319757, 3648.5804132524836, 1060.9204309676804, 1077.536659236528, 1109.9278546894507, 1118.4184224203368, 1122.4892673833385, 11381.544738210461, 11400.201985860993, 11409.48657310423, 11419.771604567863, 11419.828702569468, 4501.392312037899, 4664.416947881515, 4756.524347565285, 4761.428628564595, 4811.230203167476, 8122.553259570662, 9674.613015479303, 9678.370905891095, 9689.41863488179, 9696.903030969741, 5315.468453154731, 5321.680699158062, 5323.707196104174, 5329.413764485904, 5333.1466965286145, 4298.173276357598, 4374.8906277342685, 4835.081334546495, 4872.19608764557, 4891.997140586172, 3201.439748044083, 3772.981135094307, 3855.9228815423535, 3889.202713443737, 3914.8825535233987, 1050.3802756373775, 1256.8491780986158, 1262.3435813376993, 1271.3389037700344, 1276.2087505746497, 779.8791187365854, 820.6307161777224, 903.3134817538587, 922.4142669404977, 924.0251534630894, 5605.971970140124, 5772.567057470623, 5825.79609713658, 5883.94116058834, 6058.57589968698, 444.38896517288725, 445.7793392270782, 454.23688203817244, 455.9840405585855, 463.95824375805665, 3900.0249937515623, 3929.587393323717, 3930.6462418382484, 3943.6450719434815, 3999.5402827261023, 9169.0, 10428.5828566858, 10521.3687178768, 10529.526171322155, 10529.789404211873, 10143.442110683984, 10414.0, 10464.0, 10485.68542943713, 10513.84229236562, 8030.839383212303, 8141.0, 8174.468659537111, 8178.345732341399, 8188.754337369888, 5602.943970560243, 5624.353199382123, 5712.200291959046, 5779.479846813807, 5809.50619197373, 9567.569459374206, 9714.271429642664, 9732.562034708597, 9737.853932190881, 9752.268579856398, 9447.433154010507, 9623.374480658735, 9794.21646268311, 9917.65288214909, 9969.252306077218, 6659.7003134860015, 6863.656817159222, 6869.896951545631, 7180.640967951686, 7369.631518424165, 7890.447668663368, 8141.104478507241, 8156.184381561797, 8254.092049874389, 8289.378296627656, 7275.781726548212, 7410.96294518524, 7445.888311675438, 7519.210482899217, 7564.319211271012, 7053.259407762111, 7188.38898693596, 7197.1363436387965, 7217.206107328085, 7361.5583065014125, 6804.625745583842, 6918.204406493318, 6925.376716095576, 6929.826754331182, 6930.480213984072, 4802.327674125555, 4925.901481970269, 4931.827386041471, 4986.426560945538, 4990.426100998432, 4905.288733133604, 4961.231009193581, 5006.173981293192, 5063.493650635054, 5137.023965446702], "value": 4428.813490045965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.34545581529926, 290.88655424384496, 299.0415717625011, 301.763115953976, 303.9422509723154, 4756.738379389132, 4910.459849416561, 5108.744562771865, 5258.947410525893, 5278.604104692145, 4427.1367083418745, 4787.54518320759, 5008.649394542384, 5093.363329583802, 5100.515451032155, 3746.4193050077283, 3898.5906479819705, 3952.841886324544, 3959.722819402643, 3972.423998520213, 3540.628234035415, 3824.2351529694033, 3921.980075341985, 3944.842206311758, 3966.4446977423186, 3403.5235067090625, 3486.378833842263, 3859.2667393195343, 3918.960810391902, 3962.0, 3069.785115041948, 3309.9739080885, 3846.0961674006585, 3939.9315359436237, 3943.2310699413624, 3436.261203841161, 3445.84564171003, 3453.9654603453896, 3466.3587236361354, 3480.990512751299, 3991.0244429007826, 4205.201011807747, 4326.8269269229195, 4611.631069514431, 4932.334134891816, 4272.893177670583, 4411.757353345564, 4436.290193569013, 4453.242948698697, 4458.687891847572, 4743.335932969387, 5215.322008138917, 5245.26566280721, 5263.815766448194, 5475.767952210721, 3086.79935804172, 3384.238546327081, 3581.3553560359123, 3657.3782456982376, 3664.3221004114325, 5508.3114610673665, 5621.4659607337235, 5664.065429204176, 5703.857403564903, 5735.598508104435, 3390.456972463732, 3727.552693676776, 3730.8880733578035, 3739.6447337502896, 3800.8289626966853, 3660.6888414485065, 3796.924061518754, 3803.54357477099, 3827.885163445101, 3829.9234015320094, 3522.771019883751, 4406.5593440655675, 4417.9396944733035, 4441.467023957081, 4449.599536041707, 5358.142697168434, 5358.3837858645975, 5377.569794416438, 5385.299911011543, 5413.9729301353245, 1015.8679371681634, 1137.1016896651552, 1165.830954511608, 1181.639599922015, 1207.3600991474532, 808.6482380164647, 834.2741814621319, 837.24647816964, 838.3586556284447, 838.4256784102909, 4458.041521072985, 4665.883352916137, 4749.097671442415, 4817.470078291385, 4910.288008238786, 4908.840110351434, 4947.406311242674, 5078.314427552233, 5293.814716484904, 5348.251244825728, 4231.555686652857, 4476.477997480873, 4564.0, 4730.337752714623, 4952.975235123801, 2465.211132437603, 2526.595744680879, 2534.809889258313, 2695.299222202202, 2729.5905614158037, 4576.039031803359, 4895.853124406273, 5322.840314790563, 5335.573154147659, 5352.892942141136, 4939.801788204215, 4965.3048573199785, 5704.8859022819315, 5864.765409383577, 5882.676452795052, 4879.438579654477, 5408.78364865401, 5433.424306951019, 5887.676177810176, 5901.967155747727, 5433.945660543344, 5687.985367755096, 5700.862176683743, 5723.8855222896145, 5727.570432217517, 5642.830715078554, 5655.688937108499, 5677.886442271214, 5679.870048731726, 5685.914711279417, 5125.974370128126, 5808.796692115756, 5870.823875283749, 5932.199153114274, 6011.6635267427, 3395.2700169463683, 3473.305338932173, 3644.1982763791925, 3764.642358975893, 3785.1580645644144, 4867.975660121819, 5140.43455219933, 5644.951726068041, 5663.943360566342, 5673.234113394804, 5220.1647736362, 5426.616212865694, 5484.396716361277, 5502.339719233718, 5533.750981205775, 4665.720056796603, 5083.213006310322, 5429.375621803385, 5488.341399032141, 5549.528290095387, 4657.323363589173, 5163.974180129076, 5300.5759539236, 5342.519173274424, 5460.25271912984, 4609.400777898852, 5461.617686761889, 5481.479259470344, 5548.725022740657, 5577.27495425592, 5143.537081662668, 5319.46805319465, 5320.44135365781, 5404.756785944721, 5431.6740995540395], "value": 3908.093364294248, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.8196069022055, 741.8182545276404, 753.9886901696475, 767.393758930445, 814.8818421328906, 13857.376418061187, 14160.362783674733, 14498.927505362471, 14681.412743490248, 14919.254037298118, 12585.244885306887, 13232.073754837165, 13392.129511581765, 13396.129251598664, 13404.463821447154, 705.8270723672707, 825.3026192867018, 836.3434703757551, 838.9370797190205, 841.2344766262702, 831.6798032757372, 835.9540225287607, 837.8198687282234, 838.4172999765166, 841.1672444280149, 887.5562218890555, 890.4568213389826, 894.0657013420958, 895.3598177303227, 896.2068569316169, 882.0561998661467, 882.7837179890968, 884.1158841158841, 884.3323290915382, 886.570013543431, 650.6291413894082, 651.9869602607921, 653.7515744017243, 655.9508036897251, 655.9704813283414, 12064.758704825943, 12221.572244971383, 12360.0, 12411.75176496465, 12417.317047562381, 11628.0, 11699.18105732599, 11712.063034957184, 11713.238639488405, 11928.0, 10105.646302379455, 10148.695539133763, 10372.273940824145, 10402.687919362432, 10403.583856645726, 3497.6502349765074, 3512.771669841452, 3516.472529120627, 3548.3258180945536, 3595.94606080909, 84200.4219620231, 86549.56725216334, 87726.5613671934, 90304.09695903088, 90981.0, 8435.831283374298, 8689.522076285739, 8761.38670293086, 8771.561421928878, 9102.408343457653, 18303.45089647313, 18405.355812546495, 18437.1703273353, 18639.0, 18723.625527489374, 7403.851922961619, 7458.477906546598, 7572.20491848348, 7582.924170758332, 7690.46166768332, 4122.628963393249, 4137.8965525861495, 4201.68487363449, 4335.588119128741, 4353.064091220403, 3354.563906692163, 3500.964990350115, 3503.9299214015577, 3512.7014203792965, 3518.7888726676483, 586.4253032028636, 593.9554533410012, 604.9909251361233, 616.7872084130968, 617.8548041210312, 610.6397225636807, 612.1919066831829, 636.9840753981188, 659.6701649175412, 660.5475249454139, 8539.359548033923, 9083.36416450842, 9199.816003679889, 9242.86135707965, 9251.953740231256, 553.532265235875, 583.8306891001562, 605.1890466774544, 609.9725512351955, 637.0953246390137, 4260.148841376957, 4492.460904691458, 4493.550644935481, 4529.818807247673, 4538.478075021349, 94262.6434339147, 106506.0, 107260.0, 111503.0, 117672.0, 63166.0, 88662.55668721616, 100935.0, 102165.84839061194, 108735.0, 85109.99999999876, 85520.57239713887, 85760.57119714361, 88157.11842881617, 90287.0, 8946.821063578822, 9649.800790852869, 9694.011937361236, 9720.416774993522, 9726.124648781644, 54107.72946135244, 54535.18197226965, 56407.43592564022, 56657.583560411316, 57626.4237357621, 52819.94360112699, 53183.73408132935, 73909.0, 79509.40981180228, 82260.58869705853, 10567.260291779501, 10615.0, 10744.785104297714, 10756.462176890967, 10842.132629389787, 11843.644690659294, 11986.700332491757, 12172.939135304267, 12253.081018923438, 13572.728545429234, 12579.0, 12652.557160499338, 13000.0, 13134.802977955147, 13460.12509186939, 12813.8077928833, 12933.224006559636, 13074.673133171365, 13089.280089595164, 13313.0, 12811.807822882478, 12917.07550318033, 12964.740705185655, 13079.476820927437, 13196.14225075367, 10852.403117828595, 10860.67417977462, 11324.37715925599, 11504.194706370474, 11507.194496385173, 11247.268927519684, 11315.943420282845, 11491.712707182387, 11646.126540509325, 11649.242799218362], "value": 7108.549491659675, "unit": "score"}], "name": "dromaeo_css", "value": 7057.043837368332, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 114.0, 118.0, 163.0, 169.0, 114.0, 114.0, 115.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 113.0, 111.0, 147.0, 115.0, 114.0, 114.0, 111.0, 144.0, 112.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 182.0, 187.0, 183.0, 197.0, 264.0, 204.0, 184.0], "value": 195.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 62.0, 58.0, 87.0, 61.0, 60.0, 58.0, 60.0, 104.0, 76.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 94.0, 69.0, 67.0, 68.0, 90.0, 68.0, 68.0, 91.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 88.0, 116.0, 88.0, 84.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 153.0, 135.0, 92.0, 94.0, 93.0, 92.0, 92.0, 93.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 82.0, 80.0, 84.0, 91.0, 86.0, 85.0, 81.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 55.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 68.0, 81.0, 75.0, 73.0, 71.0, 71.0, 70.0, 74.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 95.0, 103.0, 103.0, 84.0, 85.0, 84.0, 83.0, 85.0, 84.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 158.0, 150.0, 158.0, 155.0, 154.0, 146.0, 150.0, 197.0, 160.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 165.0, 165.0, 166.0, 168.0, 166.0, 167.0, 167.0, 165.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 69.0, 86.0, 88.0, 85.0, 87.0, 67.0, 66.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1482.8999999999999, "alertThreshold": 2.0}]} 03:22:16 INFO - Return code: 0 03:22:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:22:16 ERROR - # TBPL SUCCESS # 03:22:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:16 INFO - 2016-11-23 03:22:16.323 screenresolution[1652:9844] starting screenresolution argv=screenresolution get 03:22:16 INFO - 2016-11-23 03:22:16.327 screenresolution[1652:9844] Display 0: 1600x1200x32@60 03:22:16 INFO - 2016-11-23 03:22:16.333 screenresolution[1653:9848] starting screenresolution argv=screenresolution list 03:22:16 INFO - Available Modes on Display 0 03:22:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:16 INFO - Intel Iris: 03:22:16 INFO - Chipset Model: Intel Iris 03:22:16 INFO - Type: GPU 03:22:16 INFO - Bus: Built-In 03:22:16 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:16 INFO - Vendor: Intel (0x8086) 03:22:16 INFO - Device ID: 0x0a2e 03:22:16 INFO - Revision ID: 0x0009 03:22:16 INFO - Displays: 03:22:16 INFO - Display: 03:22:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:16 INFO - Display Serial Number: 1600x1200 60 03:22:16 INFO - Main Display: Yes 03:22:16 INFO - Mirror: Off 03:22:16 INFO - Online: Yes 03:22:16 INFO - Rotation: Supported 03:22:16 INFO - Return code: 0 03:22:16 INFO - Running post-action listener: _resource_record_post_action 03:22:16 INFO - [mozharness: 2016-11-23 11:22:16.582895Z] Finished run-tests step (success) 03:22:16 INFO - Running post-run listener: _resource_record_post_run 03:22:16 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38806528; Write bytes: 653933568; Read time: 389; Write time: 1903 03:22:16 INFO - TinderboxPrint: CPU usage
53.4% 03:22:16 INFO - TinderboxPrint: I/O read bytes / time
38,806,528 / 389 03:22:16 INFO - TinderboxPrint: I/O write bytes / time
653,933,568 / 1,903 03:22:16 INFO - TinderboxPrint: CPU idle
2,256.4 (46.6%) 03:22:16 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 03:22:16 INFO - TinderboxPrint: CPU user
1,618.9 (33.4%) 03:22:16 INFO - TinderboxPrint: Swap in / out
386,809,856 / 0 03:22:16 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349276672; Write bytes: 351602176; Read time: 26859; Write time: 701 03:22:16 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 34575360; Write bytes: 300799488; Read time: 355; Write time: 1195 03:22:17 INFO - Running post-run listener: _upload_blobber_files 03:22:17 INFO - Blob upload gear active. 03:22:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:22:17 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:22:17 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:22:17 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:22:17 INFO - (blobuploader) - INFO - Open directory for files ... 03:22:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:22:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:22:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:18 INFO - (blobuploader) - INFO - Done attempting. 03:22:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:22:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:22:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:20 INFO - (blobuploader) - INFO - Done attempting. 03:22:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:22:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:22:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:23 INFO - (blobuploader) - INFO - Done attempting. 03:22:23 INFO - (blobuploader) - INFO - Iteration through files over. 03:22:23 INFO - Return code: 0 03:22:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:22:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:22:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3be70b8cb7ce1d006b54e487eb886334dd2128bb27bb3ad1a775f7d2d8ad8019a9690615f88590e6e4748607b03c90d514388456abf8bcb09919c9431d1a9c39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e5ce4c6e6c75c78b018e64066d9d3578643e0bc1054fc2becb3daa5595a12e6c10f77ce2b4720835e356c31d61d3027d2fe67d1436497e014111cf0a67903a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81c959f1636091a1a3a565599b3702682999ca8d94339c59408baac9352484d8caa33c48921d5fe3bae7158a42a26c7fc7440c2341cf8a112903498485c74a60"} 03:22:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:22:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:22:23 INFO - Contents: 03:22:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3be70b8cb7ce1d006b54e487eb886334dd2128bb27bb3ad1a775f7d2d8ad8019a9690615f88590e6e4748607b03c90d514388456abf8bcb09919c9431d1a9c39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e5ce4c6e6c75c78b018e64066d9d3578643e0bc1054fc2becb3daa5595a12e6c10f77ce2b4720835e356c31d61d3027d2fe67d1436497e014111cf0a67903a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81c959f1636091a1a3a565599b3702682999ca8d94339c59408baac9352484d8caa33c48921d5fe3bae7158a42a26c7fc7440c2341cf8a112903498485c74a60"} 03:22:23 INFO - Running post-run listener: copy_logs_to_upload_dir 03:22:23 INFO - Copying logs to upload dir... 03:22:23 INFO - mkdir: /builds/slave/test/build/upload/logs 03:22:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.429592 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-23 03:22:23.978645) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 03:22:23.983343) ========= 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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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/3be70b8cb7ce1d006b54e487eb886334dd2128bb27bb3ad1a775f7d2d8ad8019a9690615f88590e6e4748607b03c90d514388456abf8bcb09919c9431d1a9c39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e5ce4c6e6c75c78b018e64066d9d3578643e0bc1054fc2becb3daa5595a12e6c10f77ce2b4720835e356c31d61d3027d2fe67d1436497e014111cf0a67903a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81c959f1636091a1a3a565599b3702682999ca8d94339c59408baac9352484d8caa33c48921d5fe3bae7158a42a26c7fc7440c2341cf8a112903498485c74a60"} build_url:https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011160 build_url: 'https://queue.taskcluster.net/v1/task/QWJJBBYISd-M2YjO3ky1Cw/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/3be70b8cb7ce1d006b54e487eb886334dd2128bb27bb3ad1a775f7d2d8ad8019a9690615f88590e6e4748607b03c90d514388456abf8bcb09919c9431d1a9c39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e5ce4c6e6c75c78b018e64066d9d3578643e0bc1054fc2becb3daa5595a12e6c10f77ce2b4720835e356c31d61d3027d2fe67d1436497e014111cf0a67903a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81c959f1636091a1a3a565599b3702682999ca8d94339c59408baac9352484d8caa33c48921d5fe3bae7158a42a26c7fc7440c2341cf8a112903498485c74a60"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 03:22:24.020228) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 03:22:24.020528) ========= 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.HhvP7FHQjF/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.6tMjEL1Oah/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.005075 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 03:22:24.068340) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 03:22:24.068611) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 03:22:24.068880) ========= ========= Total master_lag: 0.47 =========