builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0033 starttime: 1487339238.4 results: success (0) buildid: 20170217031519 builduid: 01cda19a86454dfeafb506c52935c66f revision: 41c9d8ee2649f82bddff7038fd0dfd04f05a6664 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.399609) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.400018) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.400302) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.427219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.427487) ========= 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.hQQPSKBR0s/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.5FtN3etZMd/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-17 05:47:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2017-02-17 05:47:18 (14.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132887 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.585032) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.585372) ========= 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.hQQPSKBR0s/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.5FtN3etZMd/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.028612 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:18.636711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 05:47:18.637055) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 41c9d8ee2649f82bddff7038fd0dfd04f05a6664 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 41c9d8ee2649f82bddff7038fd0dfd04f05a6664 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hQQPSKBR0s/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.5FtN3etZMd/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-17 05:47:18,741 truncating revision to first 12 chars 2017-02-17 05:47:18,742 Setting DEBUG logging. 2017-02-17 05:47:18,742 attempt 1/10 2017-02-17 05:47:18,742 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/41c9d8ee2649?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 05:47:20,429 unpacking tar archive at: mozilla-beta-41c9d8ee2649/testing/mozharness/ program finished with exit code 0 elapsedTime=1.957434 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 05:47:20.618441) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:20.618759) ========= script_repo_revision: 41c9d8ee2649f82bddff7038fd0dfd04f05a6664 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:20.619181) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:20.619444) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 05:47:20.639677) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-17 05:47:20.640032) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hQQPSKBR0s/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.5FtN3etZMd/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:47:20 INFO - MultiFileLogger online at 20170217 05:47:20 in /builds/slave/test 05:47:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:47:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:47:20 INFO - {'append_to_log': False, 05:47:20 INFO - 'base_work_dir': '/builds/slave/test', 05:47:20 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:47:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:47:20 INFO - 'branch': 'Mozilla-Beta', 05:47:20 INFO - 'buildbot_json_path': 'buildprops.json', 05:47:20 INFO - 'config_files': ('talos/mac_config.py',), 05:47:20 INFO - 'default_actions': ('clobber', 05:47:20 INFO - 'read-buildbot-config', 05:47:20 INFO - 'download-and-extract', 05:47:20 INFO - 'populate-webroot', 05:47:20 INFO - 'create-virtualenv', 05:47:20 INFO - 'install', 05:47:20 INFO - 'run-tests'), 05:47:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:47:20 INFO - 'download_minidump_stackwalk': True, 05:47:20 INFO - 'download_symbols': 'ondemand', 05:47:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:47:20 INFO - 'tooltool.py': '/tools/tooltool.py', 05:47:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:47:20 INFO - '/tools/misc-python/virtualenv.py')}, 05:47:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:47:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:47:20 INFO - 'installer_path': 'installer.exe', 05:47:20 INFO - 'log_level': 'info', 05:47:20 INFO - 'log_name': 'talos', 05:47:20 INFO - 'log_to_console': True, 05:47:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:47:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:47:20 INFO - 'opt_config_files': (), 05:47:20 INFO - 'pip_index': False, 05:47:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:20 INFO - 'cmd': ('bash', 05:47:20 INFO - '-c', 05:47:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:20 INFO - 'enabled': True, 05:47:20 INFO - 'halt_on_failure': False, 05:47:20 INFO - 'name': 'check_screen_resolution'},), 05:47:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:20 INFO - 'cmd': ('bash', 05:47:20 INFO - '-c', 05:47:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:20 INFO - 'enabled': True, 05:47:20 INFO - 'halt_on_failure': False, 05:47:20 INFO - 'name': 'check_screen_resolution'},), 05:47:20 INFO - 'run_cmd_checks_enabled': True, 05:47:20 INFO - 'sps_profile': False, 05:47:20 INFO - 'sps_profile_interval': 0, 05:47:20 INFO - 'suite': 'dromaeojs-e10s', 05:47:20 INFO - 'system_bits': '32', 05:47:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:47:20 INFO - 'title': 't-yosemite-r7-0033', 05:47:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:47:20 INFO - 'use_talos_json': True, 05:47:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:47:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:47:20 INFO - 'work_dir': 'build'} 05:47:20 INFO - [mozharness: 2017-02-17 13:47:20.856715Z] Running clobber step. 05:47:20 INFO - Running pre-action listener: _resource_record_pre_action 05:47:20 INFO - Running main action method: clobber 05:47:20 INFO - rmtree: /builds/slave/test/build 05:47:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:47:25 INFO - Running post-action listener: _resource_record_post_action 05:47:25 INFO - [mozharness: 2017-02-17 13:47:25.463999Z] Finished clobber step (success) 05:47:25 INFO - [mozharness: 2017-02-17 13:47:25.464162Z] Running read-buildbot-config step. 05:47:25 INFO - Running pre-action listener: _resource_record_pre_action 05:47:25 INFO - Running main action method: read_buildbot_config 05:47:25 INFO - Using buildbot properties: 05:47:25 INFO - { 05:47:25 INFO - "project": "", 05:47:25 INFO - "product": "firefox", 05:47:25 INFO - "script_repo_revision": "production", 05:47:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:47:25 INFO - "repository": "", 05:47:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:47:25 INFO - "buildid": "20170217031519", 05:47:25 INFO - "pgo_build": "False", 05:47:25 INFO - "basedir": "/builds/slave/test", 05:47:25 INFO - "buildnumber": 8, 05:47:25 INFO - "slavename": "t-yosemite-r7-0033", 05:47:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:47:25 INFO - "platform": "yosemite_r7", 05:47:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:47:25 INFO - "branch": "mozilla-beta", 05:47:25 INFO - "repo_path": "releases/mozilla-beta", 05:47:25 INFO - "revision": "41c9d8ee2649f82bddff7038fd0dfd04f05a6664", 05:47:25 INFO - "stage_platform": "macosx64", 05:47:25 INFO - "builduid": "01cda19a86454dfeafb506c52935c66f", 05:47:25 INFO - "slavebuilddir": "test" 05:47:25 INFO - } 05:47:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 05:47:25 INFO - Running post-action listener: _resource_record_post_action 05:47:25 INFO - [mozharness: 2017-02-17 13:47:25.467444Z] Finished read-buildbot-config step (success) 05:47:25 INFO - [mozharness: 2017-02-17 13:47:25.467532Z] Running download-and-extract step. 05:47:25 INFO - Running pre-action listener: _resource_record_pre_action 05:47:25 INFO - Running main action method: download_and_extract 05:47:25 INFO - mkdir: /builds/slave/test/build/tests 05:47:25 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')]} 05:47:25 INFO - trying https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 05:47:25 INFO - Downloading https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 05:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 05:47:26 INFO - Downloaded 1165 bytes. 05:47:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 05:47:26 INFO - Using the following test package requirements: 05:47:26 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 05:47:26 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 05:47:26 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 05:47:26 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 05:47:26 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 05:47:26 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:47:26 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 05:47:26 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 05:47:26 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 05:47:26 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 05:47:26 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 05:47:26 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 05:47:26 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 05:47:26 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 05:47:26 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 05:47:26 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 05:47:26 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 05:47:26 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 05:47:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 05:47:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 05:47:26 INFO - Fetch https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 05:47:27 INFO - Http code: 200 05:47:27 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 05:47:27 INFO - x-amz-cf-id: dYbFyAX7OWEN6zg3RXvu3h_I4iI77QJOqD0zss9jhOPeIngNvRMbHw== 05:47:27 INFO - x-amz-version-id: XRN_zqaqSlYE4genUxuHhLHLKbfCcOHM 05:47:27 INFO - Expected file size: 16093127 05:47:27 INFO - Obtained file size: 16093127 05:47:34 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:47:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 05:47:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 05:47:34 INFO - Fetch https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 05:47:34 INFO - Http code: 200 05:47:34 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 05:47:34 INFO - x-amz-cf-id: EmWzqc3GrIa7w5z9k8z24sh361Vnu5lfeG2bOHhC6SsuixyxKWgLeQ== 05:47:34 INFO - x-amz-version-id: XRN_zqaqSlYE4genUxuHhLHLKbfCcOHM 05:47:34 INFO - Expected file size: 16093127 05:47:34 INFO - Obtained file size: 16093127 05:47:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 05:47:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 05:47:41 INFO - Fetch https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 05:47:42 INFO - Http code: 200 05:47:42 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 05:47:42 INFO - x-amz-cf-id: OGnFqpPqAhVG5EXa2d6LsGdMdnkNT0onVY9f0v7wzHtpht7YokVUyA== 05:47:42 INFO - x-amz-version-id: vNFzn.n5Tw5wSWKrP4_kgAyGQBw0CCos 05:47:42 INFO - Expected file size: 14403005 05:47:42 INFO - Obtained file size: 14403005 05:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:42 INFO - trying https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 05:47:42 INFO - Downloading https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 05:47:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 05:47:45 INFO - Downloaded 82139619 bytes. 05:47:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 05:47:45 INFO - mkdir: /builds/slave/test/properties 05:47:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:47:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:47:45 INFO - Contents: 05:47:45 INFO - build_url:https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 05:47:45 INFO - Running post-action listener: _resource_record_post_action 05:47:45 INFO - Running post-action listener: set_extra_try_arguments 05:47:45 INFO - [mozharness: 2017-02-17 13:47:45.699181Z] Finished download-and-extract step (success) 05:47:45 INFO - [mozharness: 2017-02-17 13:47:45.699313Z] Running populate-webroot step. 05:47:45 INFO - Running pre-action listener: _resource_record_pre_action 05:47:45 INFO - Running main action method: populate_webroot 05:47:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:47:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:47:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:47:45 INFO - '--tppagecycles', 05:47:45 INFO - '7'], 05:47:45 INFO - 'tests': ['tsvgm']}}, 05:47:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:47:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:47:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:47:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:47:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['damp', 'tps']}, 05:47:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'tests': ['damp', 'tps']}, 05:47:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['dromaeo_dom']}, 05:47:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:47:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['basic_compositor_video']}, 05:47:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:47:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['a11yr', 05:47:45 INFO - 'ts_paint', 05:47:45 INFO - 'tpaint', 05:47:45 INFO - 'sessionrestore', 05:47:45 INFO - 'sessionrestore_no_auto_restore', 05:47:45 INFO - 'tabpaint']}, 05:47:45 INFO - 'other-e10s': {'tests': ['a11yr', 05:47:45 INFO - 'ts_paint', 05:47:45 INFO - 'tpaint', 05:47:45 INFO - 'sessionrestore', 05:47:45 INFO - 'sessionrestore_no_auto_restore', 05:47:45 INFO - 'tabpaint']}, 05:47:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:47:45 INFO - 'ts_paint', 05:47:45 INFO - 'tpaint', 05:47:45 INFO - 'sessionrestore', 05:47:45 INFO - 'sessionrestore_no_auto_restore', 05:47:45 INFO - 'tabpaint']}, 05:47:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:47:45 INFO - 'ts_paint', 05:47:45 INFO - 'tpaint', 05:47:45 INFO - 'sessionrestore', 05:47:45 INFO - 'sessionrestore_no_auto_restore', 05:47:45 INFO - 'tabpaint']}, 05:47:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['a11yr', 05:47:45 INFO - 'ts_paint', 05:47:45 INFO - 'tpaint', 05:47:45 INFO - 'sessionrestore', 05:47:45 INFO - 'sessionrestore_no_auto_restore', 05:47:45 INFO - 'tabpaint']}, 05:47:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['a11yr', 05:47:45 INFO - 'ts_paint', 05:47:45 INFO - 'tpaint', 05:47:45 INFO - 'sessionrestore', 05:47:45 INFO - 'sessionrestore_no_auto_restore', 05:47:45 INFO - 'tabpaint']}, 05:47:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['tsvgx', 05:47:45 INFO - 'tsvgr_opacity', 05:47:45 INFO - 'tart', 05:47:45 INFO - 'tscrollx', 05:47:45 INFO - 'cart']}, 05:47:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:47:45 INFO - 'tsvgr_opacity', 05:47:45 INFO - 'tart', 05:47:45 INFO - 'tscrollx', 05:47:45 INFO - 'cart']}, 05:47:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'talos_options': ['--disable-e10s'], 05:47:45 INFO - 'tests': ['tp5o']}, 05:47:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'tests': ['tp5o']}, 05:47:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'talos_options': ['--disable-e10s', 05:47:45 INFO - '--xperf_path', 05:47:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:45 INFO - 'tests': ['tp5n']}, 05:47:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:45 INFO - 'talos_options': ['--xperf_path', 05:47:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:45 INFO - 'tests': ['tp5n']}}, 05:47:45 INFO - 'talos.zip': {'path': '', 05:47:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:47:45 INFO - Running post-action listener: _resource_record_post_action 05:47:45 INFO - [mozharness: 2017-02-17 13:47:45.717896Z] Finished populate-webroot step (success) 05:47:45 INFO - [mozharness: 2017-02-17 13:47:45.718032Z] Running create-virtualenv step. 05:47:45 INFO - Running pre-action listener: _resource_record_pre_action 05:47:45 INFO - Running main action method: create_virtualenv 05:47:45 INFO - Creating virtualenv /builds/slave/test/build/venv 05:47:45 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 05:47:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:47:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:47:45 INFO - Using real prefix '/tools/python27' 05:47:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:47:46 INFO - Installing distribute.............................................................................................................................................................................................done. 05:47:49 INFO - Installing pip.................done. 05:47:49 INFO - Return code: 0 05:47:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:47:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:47:49 INFO - Reading from file tmpfile_stdout 05:47:49 INFO - Output received: 05:47:49 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) 05:47:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:47:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:47:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:47:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:47:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:49 INFO - 'HOME': '/Users/cltbld', 05:47:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:49 INFO - 'LOGNAME': 'cltbld', 05:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:49 INFO - 'MOZ_NO_REMOTE': '1', 05:47:49 INFO - 'NO_EM_RESTART': '1', 05:47:49 INFO - 'PAGER': '/bin/cat', 05:47:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:49 INFO - 'PWD': '/builds/slave/test', 05:47:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:49 INFO - 'SHELL': '/bin/bash', 05:47:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:47:49 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:47:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:49 INFO - 'USER': 'cltbld', 05:47:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:49 INFO - 'XPC_FLAGS': '0x0', 05:47:49 INFO - 'XPC_SERVICE_NAME': '0', 05:47:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:49 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 05:47:49 INFO - Cleaning up... 05:47:49 INFO - Return code: 0 05:47:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:47:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:47:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:47:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:47:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:49 INFO - 'HOME': '/Users/cltbld', 05:47:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:49 INFO - 'LOGNAME': 'cltbld', 05:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:49 INFO - 'MOZ_NO_REMOTE': '1', 05:47:49 INFO - 'NO_EM_RESTART': '1', 05:47:49 INFO - 'PAGER': '/bin/cat', 05:47:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:49 INFO - 'PWD': '/builds/slave/test', 05:47:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:49 INFO - 'SHELL': '/bin/bash', 05:47:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:47:49 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:47:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:49 INFO - 'USER': 'cltbld', 05:47:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:49 INFO - 'XPC_FLAGS': '0x0', 05:47:49 INFO - 'XPC_SERVICE_NAME': '0', 05:47:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:50 INFO - Downloading/unpacking psutil>=3.1.1 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:47:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:53 INFO - Installing collected packages: psutil 05:47:53 INFO - Running setup.py install for psutil 05:47:53 INFO - building 'psutil._psutil_osx' extension 05:47:53 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 05:47:54 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 05:47:54 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 05:47:54 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 05:47:54 INFO - building 'psutil._psutil_posix' extension 05:47:54 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 05:47:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:47:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:47:54 INFO - ^ 05:47:54 INFO - 1 warning generated. 05:47:54 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 05:47:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:54 INFO - Successfully installed psutil 05:47:54 INFO - Cleaning up... 05:47:54 INFO - Return code: 0 05:47:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:54 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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:47:54 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 05:47:54 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 05:47:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:47:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:55 INFO - 'HOME': '/Users/cltbld', 05:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:55 INFO - 'LOGNAME': 'cltbld', 05:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:55 INFO - 'MOZ_NO_REMOTE': '1', 05:47:55 INFO - 'NO_EM_RESTART': '1', 05:47:55 INFO - 'PAGER': '/bin/cat', 05:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:55 INFO - 'PWD': '/builds/slave/test', 05:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:55 INFO - 'SHELL': '/bin/bash', 05:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:47:55 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:55 INFO - 'USER': 'cltbld', 05:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:55 INFO - 'XPC_FLAGS': '0x0', 05:47:55 INFO - 'XPC_SERVICE_NAME': '0', 05:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:47:55 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 05:47:55 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 05:47:55 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 05:47:55 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 05:47:55 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 05:47:55 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 05:47:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:47:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:47:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:47:57 INFO - Installing collected packages: mozsystemmonitor 05:47:57 INFO - Running setup.py install for mozsystemmonitor 05:47:58 INFO - Successfully installed mozsystemmonitor 05:47:58 INFO - Cleaning up... 05:47:58 INFO - Return code: 0 05:47:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:47:58 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')]} 05:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:58 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')]} 05:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:58 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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:47:58 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 05:47:58 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 05:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:58 INFO - 'HOME': '/Users/cltbld', 05:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:58 INFO - 'LOGNAME': 'cltbld', 05:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:58 INFO - 'MOZ_NO_REMOTE': '1', 05:47:58 INFO - 'NO_EM_RESTART': '1', 05:47:58 INFO - 'PAGER': '/bin/cat', 05:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:58 INFO - 'PWD': '/builds/slave/test', 05:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:58 INFO - 'SHELL': '/bin/bash', 05:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:47:58 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:58 INFO - 'USER': 'cltbld', 05:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:58 INFO - 'XPC_FLAGS': '0x0', 05:47:58 INFO - 'XPC_SERVICE_NAME': '0', 05:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:58 INFO - Downloading/unpacking jsonschema==2.5.1 05:47:58 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 05:47:58 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 05:47:58 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 05:47:58 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 05:47:58 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 05:47:58 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 05:48:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:48:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:01 INFO - Downloading functools32-3.2.3-2.tar.gz 05:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:48:01 INFO - warning: no files found matching '*.txt' 05:48:01 INFO - no previously-included directories found matching 'build' 05:48:01 INFO - no previously-included directories found matching 'dist' 05:48:01 INFO - no previously-included directories found matching '.git*' 05:48:01 INFO - Installing collected packages: jsonschema, functools32 05:48:01 INFO - Running setup.py install for functools32 05:48:01 INFO - warning: no files found matching '*.txt' 05:48:01 INFO - no previously-included directories found matching 'build' 05:48:01 INFO - no previously-included directories found matching 'dist' 05:48:01 INFO - no previously-included directories found matching '.git*' 05:48:01 INFO - Successfully installed jsonschema functools32 05:48:01 INFO - Cleaning up... 05:48:01 INFO - Return code: 0 05:48:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:48:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:01 INFO - 'HOME': '/Users/cltbld', 05:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:01 INFO - 'LOGNAME': 'cltbld', 05:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:01 INFO - 'MOZ_NO_REMOTE': '1', 05:48:01 INFO - 'NO_EM_RESTART': '1', 05:48:01 INFO - 'PAGER': '/bin/cat', 05:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:01 INFO - 'PWD': '/builds/slave/test', 05:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:01 INFO - 'SHELL': '/bin/bash', 05:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:48:01 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:01 INFO - 'USER': 'cltbld', 05:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:01 INFO - 'XPC_FLAGS': '0x0', 05:48:01 INFO - 'XPC_SERVICE_NAME': '0', 05:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:48:02 INFO - Cleaning up... 05:48:02 INFO - Return code: 0 05:48:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:48:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:48:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:48:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:02 INFO - 'HOME': '/Users/cltbld', 05:48:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:02 INFO - 'LOGNAME': 'cltbld', 05:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:02 INFO - 'MOZ_NO_REMOTE': '1', 05:48:02 INFO - 'NO_EM_RESTART': '1', 05:48:02 INFO - 'PAGER': '/bin/cat', 05:48:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:02 INFO - 'PWD': '/builds/slave/test', 05:48:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:02 INFO - 'SHELL': '/bin/bash', 05:48:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:48:02 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:48:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:02 INFO - 'USER': 'cltbld', 05:48:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:02 INFO - 'XPC_FLAGS': '0x0', 05:48:02 INFO - 'XPC_SERVICE_NAME': '0', 05:48:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:48:02 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 05:48:02 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 05:48:02 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 05:48:02 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 05:48:02 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 05:48:02 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 05:48:05 INFO - Downloading blobuploader-1.2.4.tar.gz 05:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:48:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:48:05 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 05:48:05 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 05:48:05 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 05:48:05 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 05:48:05 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 05:48:05 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 05:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:48:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:48:05 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 05:48:05 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 05:48:05 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 05:48:05 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 05:48:05 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 05:48:05 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 05:48:06 INFO - Downloading docopt-0.6.1.tar.gz 05:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:48:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:48:06 INFO - Running setup.py install for blobuploader 05:48:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:48:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:48:06 INFO - Running setup.py install for requests 05:48:07 INFO - Running setup.py install for docopt 05:48:07 INFO - Successfully installed blobuploader requests docopt 05:48:07 INFO - Cleaning up... 05:48:07 INFO - Return code: 0 05:48:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48: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')]} 05:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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')]} 05:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:07 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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:48:07 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 05:48:07 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 05:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:07 INFO - 'HOME': '/Users/cltbld', 05:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:07 INFO - 'LOGNAME': 'cltbld', 05:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:07 INFO - 'MOZ_NO_REMOTE': '1', 05:48:07 INFO - 'NO_EM_RESTART': '1', 05:48:07 INFO - 'PAGER': '/bin/cat', 05:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:07 INFO - 'PWD': '/builds/slave/test', 05:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:07 INFO - 'SHELL': '/bin/bash', 05:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:48:07 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:07 INFO - 'USER': 'cltbld', 05:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:07 INFO - 'XPC_FLAGS': '0x0', 05:48:07 INFO - 'XPC_SERVICE_NAME': '0', 05:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-osNwCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-2P3sJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-cRpiUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-D1BHTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-e9hRg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-dIlkKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-3oRZhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-TkkmZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-5QARRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IT49mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-fC8IU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-YLnzcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GafiqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-0o2z7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-e9qocR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-C_e6xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-nfhjdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:48:09 INFO - Running setup.py install for manifestparser 05:48:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:48:09 INFO - Running setup.py install for mozcrash 05:48:09 INFO - Running setup.py install for mozdebug 05:48:09 INFO - Running setup.py install for mozdevice 05:48:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:48:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:48:09 INFO - Running setup.py install for mozfile 05:48:10 INFO - Running setup.py install for mozhttpd 05:48:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:48:10 INFO - Running setup.py install for mozinfo 05:48:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:48:10 INFO - Running setup.py install for mozInstall 05:48:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:48:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:48:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:48:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:48:10 INFO - Running setup.py install for mozleak 05:48:10 INFO - Running setup.py install for mozlog 05:48:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:48:10 INFO - Running setup.py install for moznetwork 05:48:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:48:11 INFO - Running setup.py install for mozprocess 05:48:11 INFO - Running setup.py install for mozprofile 05:48:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:48:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:48:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:48:11 INFO - Running setup.py install for mozrunner 05:48:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:48:11 INFO - Running setup.py install for mozscreenshot 05:48:11 INFO - Running setup.py install for moztest 05:48:11 INFO - Running setup.py install for mozversion 05:48:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:48:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:48:11 INFO - Cleaning up... 05:48:11 INFO - Return code: 0 05:48:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:11 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')]} 05:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:11 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')]} 05:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:11 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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:48:11 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 05:48:11 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 05:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:11 INFO - 'HOME': '/Users/cltbld', 05:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:11 INFO - 'LOGNAME': 'cltbld', 05:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:11 INFO - 'MOZ_NO_REMOTE': '1', 05:48:11 INFO - 'NO_EM_RESTART': '1', 05:48:11 INFO - 'PAGER': '/bin/cat', 05:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:11 INFO - 'PWD': '/builds/slave/test', 05:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:11 INFO - 'SHELL': '/bin/bash', 05:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:48:11 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:11 INFO - 'USER': 'cltbld', 05:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:11 INFO - 'XPC_FLAGS': '0x0', 05:48:11 INFO - 'XPC_SERVICE_NAME': '0', 05:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-hBSibY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:12 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)) 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-PQ1HZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:12 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)) 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-0ZZRVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:12 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)) 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-64_qu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:12 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)) 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-eMt1N1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:12 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)) 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IMajMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:12 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)) 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-YPFVuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:12 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)) 05:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-uzoNjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kQME4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-wNLvEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-MUk8b6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ZLksJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-BmRSbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tIg5Jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ALxKA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:13 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)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-DNT5Jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-712rlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:14 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)) 05:48:14 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)) 05:48:14 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)) 05:48:14 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)) 05:48:14 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)) 05:48:14 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)) 05:48:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:14 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 05:48:14 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 05:48:14 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 05:48:14 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 05:48:14 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 05:48:14 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 05:48:16 INFO - Downloading blessings-1.6.tar.gz 05:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:48:17 INFO - Installing collected packages: blessings 05:48:17 INFO - Running setup.py install for blessings 05:48:17 INFO - Successfully installed blessings 05:48:17 INFO - Cleaning up... 05:48:17 INFO - Return code: 0 05:48:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:48:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:17 INFO - Reading from file tmpfile_stdout 05:48:17 INFO - Current package versions: 05:48:17 INFO - blessings == 1.6 05:48:17 INFO - blobuploader == 1.2.4 05:48:17 INFO - docopt == 0.6.1 05:48:17 INFO - functools32 == 3.2.3-2 05:48:17 INFO - jsonschema == 2.5.1 05:48:17 INFO - manifestparser == 1.1 05:48:17 INFO - mozInstall == 1.12 05:48:17 INFO - mozcrash == 1.0 05:48:17 INFO - mozdebug == 0.1 05:48:17 INFO - mozdevice == 0.48 05:48:17 INFO - mozfile == 1.2 05:48:17 INFO - mozhttpd == 0.7 05:48:17 INFO - mozinfo == 0.9 05:48:17 INFO - mozleak == 0.1 05:48:17 INFO - mozlog == 3.4 05:48:17 INFO - moznetwork == 0.27 05:48:17 INFO - mozprocess == 0.23 05:48:17 INFO - mozprofile == 0.28 05:48:17 INFO - mozrunner == 6.13 05:48:17 INFO - mozscreenshot == 0.1 05:48:17 INFO - mozsystemmonitor == 0.3 05:48:17 INFO - moztest == 0.8 05:48:17 INFO - mozversion == 1.4 05:48:17 INFO - psutil == 3.1.1 05:48:17 INFO - requests == 1.2.3 05:48:17 INFO - wsgiref == 0.1.2 05:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:17 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')]} 05:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:17 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')]} 05:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:17 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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:48:17 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 05:48:17 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 05:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:17 INFO - 'HOME': '/Users/cltbld', 05:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:17 INFO - 'LOGNAME': 'cltbld', 05:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:17 INFO - 'MOZ_NO_REMOTE': '1', 05:48:17 INFO - 'NO_EM_RESTART': '1', 05:48:17 INFO - 'PAGER': '/bin/cat', 05:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:17 INFO - 'PWD': '/builds/slave/test', 05:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:17 INFO - 'SHELL': '/bin/bash', 05:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:48:17 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:17 INFO - 'USER': 'cltbld', 05:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:17 INFO - 'XPC_FLAGS': '0x0', 05:48:17 INFO - 'XPC_SERVICE_NAME': '0', 05:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 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)) 05:48:18 INFO - Cleaning up... 05:48:18 INFO - Return code: 0 05:48:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:18 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 0x10d742490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd4b463640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.hQQPSKBR0s/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.5FtN3etZMd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 05:48:18 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 05:48:18 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 05:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:18 INFO - 'HOME': '/Users/cltbld', 05:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:18 INFO - 'LOGNAME': 'cltbld', 05:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:18 INFO - 'MOZ_NO_REMOTE': '1', 05:48:18 INFO - 'NO_EM_RESTART': '1', 05:48:18 INFO - 'PAGER': '/bin/cat', 05:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:18 INFO - 'PWD': '/builds/slave/test', 05:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:18 INFO - 'SHELL': '/bin/bash', 05:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:48:18 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:18 INFO - 'USER': 'cltbld', 05:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:18 INFO - 'XPC_FLAGS': '0x0', 05:48:18 INFO - 'XPC_SERVICE_NAME': '0', 05:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:48:18 INFO - Cleaning up... 05:48:18 INFO - Return code: 0 05:48:18 INFO - Running post-action listener: _resource_record_post_action 05:48:18 INFO - Running post-action listener: _start_resource_monitoring 05:48:18 INFO - Starting resource monitoring. 05:48:18 INFO - [mozharness: 2017-02-17 13:48:18.543130Z] Finished create-virtualenv step (success) 05:48:18 INFO - [mozharness: 2017-02-17 13:48:18.544090Z] Running install step. 05:48:18 INFO - Running pre-action listener: _resource_record_pre_action 05:48:18 INFO - Running main action method: install 05:48:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:18 INFO - Reading from file tmpfile_stdout 05:48:18 INFO - Detecting whether we're running mozinstall >=1.0... 05:48:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:48:18 INFO - Reading from file tmpfile_stdout 05:48:18 INFO - Output received: 05:48:18 INFO - Usage: mozinstall [options] installer 05:48:18 INFO - Options: 05:48:18 INFO - -h, --help show this help message and exit 05:48:18 INFO - -d DEST, --destination=DEST 05:48:18 INFO - Directory to install application into. [default: 05:48:18 INFO - "/builds/slave/test"] 05:48:18 INFO - --app=APP Application being installed. [default: firefox] 05:48:18 INFO - mkdir: /builds/slave/test/build/application 05:48:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:48:40 INFO - Reading from file tmpfile_stdout 05:48:40 INFO - Output received: 05:48:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:48:40 INFO - Running post-action listener: _resource_record_post_action 05:48:40 INFO - [mozharness: 2017-02-17 13:48:40.288563Z] Finished install step (success) 05:48:40 INFO - [mozharness: 2017-02-17 13:48:40.288696Z] Running run-tests step. 05:48:40 INFO - Running pre-action listener: _resource_record_pre_action 05:48:40 INFO - Running main action method: run_tests 05:48:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:40 INFO - 2017-02-17 05:48:40.302 screenresolution[1623:7508] starting screenresolution argv=screenresolution get 05:48:40 INFO - 2017-02-17 05:48:40.305 screenresolution[1623:7508] Display 0: 1600x1200x32@60 05:48:40 INFO - 2017-02-17 05:48:40.310 screenresolution[1624:7510] starting screenresolution argv=screenresolution list 05:48:40 INFO - Available Modes on Display 0 05:48:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:40 INFO - Intel Iris: 05:48:40 INFO - Chipset Model: Intel Iris 05:48:40 INFO - Type: GPU 05:48:40 INFO - Bus: Built-In 05:48:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:40 INFO - Vendor: Intel (0x8086) 05:48:40 INFO - Device ID: 0x0a2e 05:48:40 INFO - Revision ID: 0x0009 05:48:40 INFO - Displays: 05:48:40 INFO - Display: 05:48:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:40 INFO - Display Serial Number: 1600x1200 60 05:48:40 INFO - Main Display: Yes 05:48:40 INFO - Mirror: Off 05:48:40 INFO - Online: Yes 05:48:40 INFO - Rotation: Supported 05:48:40 INFO - Return code: 0 05:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:48:40 INFO - Python 2.7.3 05:48:40 INFO - Return code: 0 05:48:40 INFO - grabbing minidump binary from tooltool 05:48: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')]} 05:48:40 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 0x10d556d30>, '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 0x10d3bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:48:40 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 05:48:40 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 05:48:40 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 05:48:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:48:40 INFO - Return code: 0 05:48:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:48:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:48:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hQQPSKBR0s/Render', 05:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:40 INFO - 'HOME': '/Users/cltbld', 05:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:40 INFO - 'LOGNAME': 'cltbld', 05:48:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:48:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:40 INFO - 'MOZ_NO_REMOTE': '1', 05:48:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:48:40 INFO - 'NO_EM_RESTART': '1', 05:48:40 INFO - 'PAGER': '/bin/cat', 05:48:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:40 INFO - 'PWD': '/builds/slave/test', 05:48:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:40 INFO - 'SHELL': '/bin/bash', 05:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FtN3etZMd/Listeners', 05:48:40 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:40 INFO - 'USER': 'cltbld', 05:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:40 INFO - 'XPC_FLAGS': '0x0', 05:48:40 INFO - 'XPC_SERVICE_NAME': '0', 05:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:48:40 INFO - mozversion application_buildid: 20170217031519 05:48:40 INFO - mozversion application_changeset: 41c9d8ee2649f82bddff7038fd0dfd04f05a6664 05:48:40 INFO - mozversion application_display_name: Firefox 05:48:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:48:40 INFO - mozversion application_name: Firefox 05:48:40 INFO - mozversion application_remotingname: firefox 05:48:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:48:40 INFO - mozversion application_vendor: Mozilla 05:48:40 INFO - mozversion application_version: 52.0 05:48:40 INFO - mozversion platform_buildid: 20170217031519 05:48:40 INFO - mozversion platform_changeset: 41c9d8ee2649f82bddff7038fd0dfd04f05a6664 05:48:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:48:40 INFO - mozversion platform_version: 52.0 05:48:40 INFO - using testdate: 1487339320 05:48:40 INFO - actual date: 1487339320 05:48:40 INFO - starting webserver on 'localhost:49244' 05:48:40 INFO - SUITE-START | Running 2 tests 05:48:40 INFO - TEST-START | dromaeo_css 05:48:40 INFO - operating with platform_type : mac_ 05:48:40 INFO - Initialising browser for dromaeo_css test... 05:48:40 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpNY34Hs/profile http://localhost:49244/getInfo.html) 05:48:43 INFO - PROCESS | 1634 | 2017-02-17 05:48:43.864 plugin-container[1637:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 05:48:43 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:43 INFO - PROCESS | 1634 | 2017-02-17 05:48:43.864 plugin-container[1637:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4f, name = 'com.apple.CFPasteboardClient' 05:48:43 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:43 INFO - PROCESS | 1634 | 2017-02-17 05:48:43.864 plugin-container[1637:7689] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:43 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 05:48:43 INFO - PROCESS | 1634 | colorDepth:24 05:48:43 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 05:48:43 INFO - PROCESS | 1634 | __metrics 05:48:44 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 05:48:44 INFO - TEST-INFO | 1634: exit 0 05:48:44 INFO - Browser initialized. 05:48:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:48:44 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpNY34Hs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:47 INFO - PROCESS | 1638 | 2017-02-17 05:48:47.481 plugin-container[1639:7813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:48:47 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:47 INFO - PROCESS | 1638 | 2017-02-17 05:48:47.482 plugin-container[1639:7813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1b, name = 'com.apple.CFPasteboardClient' 05:48:47 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:47 INFO - PROCESS | 1638 | 2017-02-17 05:48:47.482 plugin-container[1639:7813] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:57 INFO - PROCESS | 1638 | RSS: Main: 177676288 05:48:57 INFO - PROCESS | 1638 | 05:51:58 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:52:00 INFO - PROCESS | 1638 | RSS: Main: 183083008 05:52:00 INFO - PROCESS | 1638 | 05:55:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 05:55:03 INFO - PROCESS | 1638 | RSS: Main: 194826240 05:55:03 INFO - PROCESS | 1638 | 05:58:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:58:06 INFO - PROCESS | 1638 | RSS: Main: 196718592 05:58:06 INFO - PROCESS | 1638 | 06:01:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 06:01:08 INFO - PROCESS | 1638 | RSS: Main: 199733248 06:01:08 INFO - PROCESS | 1638 | 06:04:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 06:04:12 INFO - PROCESS | 1638 | RSS: Main: 201056256 06:04:12 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 06:07:15 INFO - PROCESS | 1638 | RSS: Main: 201637888 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | __start_tp_report 06:07:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 06:07:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 06:07:15 INFO - PROCESS | 1638 | |i|pagename|runs| 06:07:15 INFO - PROCESS | 1638 | |0;dojo.html;3584.8566057357707;3595.3168897909413;3610.386234340162;3623.728220383471;3649.215418684982;5989.1914591530185;6006.609570377922;6015.338312785596;6034.818955431344;6080.574359794816;5954.910676339859;6042.57701960863;6043.728032238549;6044.486218671407;6064.727087281072;4905.9264111038365;5059.16523773577;5358.089124848775;5366.329208848894;5461.918071228915;5445.37378201506;5479.0959491684025;5486.972565137169;5487.286652735159;5505.614606977513;3833.616638336172;5392.946070539303;5467.17992301156;5507.779688812443;5531.778728850841;5430.619856610039;5431.538319242869;5475.425080366544;5488.643238189545;5538.889222215574;5360.115580929142;5420.186971954199;5431.266778984826;5456.890862182774;5470.671759694431;5897.115432685088;6038.245219347582;6044.093385992092;6045.969770151165;6051.304100028509;6213.285472170668;6233.096201050822;6250.531210159256;6252.5935814171935;6296.244450665904;6423.164988551505;6433.806985790434;6495.577787443801;6527.5430719849155;6537.64042977636;6118.60229085108;6120.234970628716;6128.785492507741;6149.815505534841;6151.200343955257;6429.54993150475;6516.348365163541;6591.703373348211;6592.241892182365;6629.502787290972;5315.707636080053;6302.716377763011;6322.0200868865395;6324.873502529924;6331.651759153198;6213.254409470893;6267.903116954515;6488.870222595616;6492.383223593751;6518.087467754518;4739.4075740532435;6275.497960163176;6298.811035668938;6362.586431881913;6395.456386207132;5954.8090381923785;6395.2645445773405;6415;6415.711292991827;6455.644939528371;4651.720896746206;5174.172132458863;5184.533391994739;5189.6886186828915;5207.661502002357;4437.467503899553;4468.419105516262;4553;4581.106684196549;4653.348531205634;4769.546893045155;6130.581587307925;6169.321374648785;6179.567430279927;6194.34959329273;6437.002264648987;6447.774327898501;6479.449246814073;6488.994205897999;6507.544471886922;5061.544460998454;5080.390353157571;6004.63972161663;6086.3000754913755;6103.542234332443;5752.13717942312;5752.165935939349;5754.251947246831;5761.740721667535;5764.567657425626;5505.9724701376235;6648.33516648341;6690.096836927147;6692.83267918306;6700.564463309869;6222.09779581949;6239.812805615657;6281.152044473937;6293.465055470337;6465.2564955030775;6542.803715888531;6543.149390579194;6548.0832683423405;6550.508711846726;6577.506686998398;5628.690422026664;6213.068039793886;6273.121762953099;6287.96856015717;6305.873882522415;6218.81343559694;6228.314885362516;6331.461825744863;6367.07677386767;6374.872502549831;6139.1241809172225;6159.476444502267;6213.689315534296;6255.093011513214;6306.621602703853;6186.938130618636;6215.564910456224;6234;6243.906341404791;6248.218972628421;6023.849608584597;6117.431594453999;6138.263408391022;6170.568060235741;6171.197744293213;6122.032243765608;6136.090214303957;6140.657627863787;6174;6205.906911396423;6111.083337499411;6133;6166.2600487939935;6184.505239580732;6195.132681424694 06:07:15 INFO - PROCESS | 1638 | |1;ext.html;516.961227907907;530.7823792245179;532.3585079978626;538.4131296886393;540.5081375947886;35855.64144358559;40100.79697609073;40197.19605607886;40251.798741006256;40256.19487610246;41281.38077928834;41571.16857662845;41708.58291417085;41756.37365439521;41887.53393631223;9582.377145485556;9764.072413120743;9766;9780.070893265163;9789.6084156634;9589.089036541518;9615.326927115104;9692.612295508172;9720.465374404404;9724.638550602922;9708.417494950325;9714;9761.853572196424;9763.36538125023;9773.853392199122;9671.951640241788;9698.612055517771;9748.415095094317;9748.512574371252;9752.756181095458;9608.087231712976;9652.806943861084;9663.985281545478;9675.758106047335;9700.029997000314;39133.41299880504;39394;39411.60588394108;39524.80237598823;39603;3179.8728050877935;3519.331327047853;3754.2679177560385;3970.8881513176357;4007.7194596378267;28877;29009.70990290091;29906.40187196244;30781;31066.534001989763;2781.6105745195687;3177.9830454254648;3237.951430728541;3383.967889793612;3395.5246265522846;509946.9999999963;510205;510219;510537;511251;52307.73846130745;52393.7380313096;52492.737536312074;52607;52629;49870.25194622084;58602.70698646479;59268.11097833536;59526.404735952085;59684;16153.27310271017;16288.918555407385;16429.1785410729;16429.507114786575;16513.587160320847;12362.505499780087;12811.103222774316;12843.293618850867;12915.612531624067;12986.220826750425;14696.485623003357;15568.610784730472;15580.298886550132;15620.843791562167;15782.526524204291;7126.608036558039;7283.7450689225625;7341.963290183515;7406.925930740731;7415.221401752846;873.5195642396657;884.4162852517308;1028.1158203944601;1072.7371793910543;1074.6131392698621;29286.707132928823;30451.543226851616;30736.231594210323;30884.382312353628;31066.223344416572;1067.546292825546;1071.8606581144556;1096.446294621211;1097.4403054442132;1253.3357320620087;13978.650533736738;14697.41210351574;16378.590535236475;16743.748843767353;17000.744988825176;589011;695620;704806;705634;706479.4676026587;58693.70653146792;58828.41171588315;59342.406575934554;59422;59509;1365.8565850585744;1389.1317926296065;1410.4851729119002;1411.5765270419038;1412.3573773933042;30438;31172.03865632761;31412.842935785175;31928.36143277168;32118.03645890627;49944.25083623676;55755.163672544135;57593.71203143957;57748;60520;621539;631019;634382;637423;638688;26616.733832661426;42789;43107.9223019427;43280.701578952685;43410.13179736325;44430.3335449975;45392;45604.08791824211;47197.29204061873;47390.52609473861;36091.81954090213;38056.80971595124;38895.805520972215;39237.60762392454;40066.39900401438;38236;38763.2247355057;39734.00664983399;39789.80105099456;39828.60171398249;37424.812875936535;38007.42988855114;38842.41736374013;39114.608853912236;39122.413163802;31851.522227166148;34633.30733385268;34922.82538587291;35185.648143518236;35898.8205058973;25792.09727659523;26220.60669089927;26262.606060908718;26568.734312657398;26964.859412745933 06:07:15 INFO - PROCESS | 1638 | |2;jquery.html;378.64974898219157;380.62698555415693;389.2778895149496;394.33357625612723;417.7827529684564;15779.28993195306;17529.912350438244;20333.881636509985;20546.589068218625;20619.38141855743;10438;11730.472128754205;15591.37634494619;19984;20154.59690806183;5296.867578310545;5466.125419932811;5500.779968801243;5506.5870059745475;5525.950069486785;5264.6051546134;5291.656042357254;5387.973060134694;5406.83779486616;5442.8911421771545;5333.306670132877;5336.653117547347;5341.572674186075;5357.142857142858;5454.781808727646;4959.429665588467;5098.949010509921;5214.217867319894;5363.517283444489;5431.83704488866;4532.206863798855;5284.894302113975;5285.947140528584;5293.6294459387855;5296.443873393316;11203;12950.287734174617;23097.383183177186;24544.509109817704;24682.50634987308;1905.9237630494763;2169.045619927227;2188.9233876814396;2242.260054182116;2301.746807851135;10410.011048950337;10595.735106622318;12580.937095314557;14575.70848583033;15624.140672263022;1635.9106205143996;1839.1356062650532;1865.0861078071782;1877.737116803635;1928.7878333383458;93152;101080.49459752654;114864.85135148626;125690.37154814259;133136;11416.42917854104;11439.027682646902;11696.824547631792;11715.238509496856;11802.645920622223;14966.326515306837;15052.247387630574;15055.397784088515;15087.4719384821;15120.319585618674;7417.998570193063;8302.70940517079;8541.871871921927;8769.42998705095;8779.20987111163;8583.313334933191;8593.52735599548;8735.1701588349;8783.341249406192;8786.033536311;4494.393256910341;4634.72191668501;4643.466001409881;4664.113818374498;4764.809407623657;1860.4604664647372;1933.7679478462674;1994.9800501995085;1996.4509759816167;1998.6202621501868;844.6030365728221;893.6112790935964;896.4621227263721;898.9820203595891;961.4375590279625;21056.684149737765;21592;21730.674039889414;21839.563208735737;21880.562388752453;924.4684306523641;966.4346357380871;970.5389939778548;987.6345752071636;990.5790039233297;10839.458027098772;11160.832587511193;11412.372319522507;11473;11491.655250342503;580686;581143;584411;585402;603894;104397.95602044034;109074;124068;135763;135838.32080839731;935.8362286599954;953.9284553658504;974.5370948799264;976.9755756106155;978.1734434402919;18955.336563220222;19501.414957551297;19727.704084438457;19765.209391624176;20160.899195503927;49782.00435991332;50325;56651.43348566462;56974.715126424104;57555.42444575501;74450;80220.79668805089;82266;84057.57971210105;84294.73557896513;5472.644278121909;5626.662400255997;7952.687806511861;8045.758627241192;8515.40392172542;9312.394694344846;9503.524823758646;9833.409995400012;11933.641990740292;12530.872221500109;9695;10491.108255798343;10712.250142490262;11011.504482298476;11396.94301528487;9034.23832352685;10009.649662261785;10233.539490723084;10552.947235263775;10585.576576936835;11218.326900386002;11220.495077721685;11220.943895280472;11445.599404020819;11644.76710465769;6195.845103872439;6266.530010249341;7180.3537681608905;7454.4781865268915;8500.91499085026;7846.686132554634;10452.111570516287;10871.96716311949;11146.108311334912;11576.768464630492 06:07:15 INFO - PROCESS | 1638 | |3;mootools.html;1507.253909314889;1536.8078990126235;1554.059793824736;1856.2482194711147;1895.099827581898;7631.427642926788;7836.373090152789;8078.757637270884;8527.872081918777;8545.102764209762;8062.63718132684;8333.541655208961;8363.749087527383;8465.492070475775;8472.322214222862;2362.1614326913955;2394.3415560720855;2397.880105994693;2398.6162214045758;2404.2546810488802;2394.1261439574605;2433.5741245282093;2447.8164137689655;2449.081594402099;2452.4236804350962;2354.799842013431;2359.6106642403984;2375.774301441369;2380.6310021946624;2408.7470815564375;2355.634876594122;2357.870317132557;2358.9646155307682;2385.8210634202505;2389.187676190086;2387.438951846315;2387.438951846315;2392.629142482918;2408.783209511135;2409.6987876515436;8411.1588841116;8482.575871206414;8486.78783030423;8488.915110848875;8503.447275927045;5128.9230661540105;5206.817761378334;5272.420033796279;5300.284461597674;5308.73456327186;3521.5421995140723;3568.80371579563;3592.802395868226;3593;3665.193657395369;1078.0728573426848;1082.5020490574345;1115.380963565221;1121.3328069798456;1129.8983491096199;10821.080208182313;10898.400587967659;10927.508262128224;10928.672139835817;10943.23397362185;4577.61090307321;4580.587747102777;4769.189237829577;4800.159972004885;4865.732384718804;9345.813083738287;9468.621255149717;9476.478793666414;9512.476813775309;9517.809643807084;5272.261883336336;5292.894142117136;5297.814576489882;5307.469253074739;5308.362996440452;4395.27477966132;4397.780110994438;4760;4793.9280910786365;4833.613310935117;3320.4355259605927;3825.598312177237;3948.4669569607736;3960.8019599019935;3994.8202330894596;1290.832191815058;1291.1930043722673;1307.3724612186204;1321.808337791015;1323.602919124259;919.5264438814016;919.6689191890914;927.3415874729018;939.1641439119189;940.5297351324338;5624.156376543551;5958.136070269873;5966.612170208922;6006.489448396936;6219.782307619212;930.3627015494408;951.7877164569578;955.7180631713661;986.9210463163073;1019.3272440189439;3880.2239552089695;3960;3961.742486738353;3985.242803867256;4005.7796821175116;8492.98084229884;10062.748431289276;10168.949155254177;10192.643257485952;10212.489375531193;10082.69751907444;10111.39331640104;10125.038121378457;10177.134943529883;10222.488875556193;6982.301769822977;7523.134839493418;7786.961065194751;8003.679852805939;8039.115697273295;5781.335146458211;5795.159701843293;5865.618734782225;5911.379305172895;5935.703214839327;8978.820423591622;9381.953090234505;9402.341836071682;9420.434773913586;9519.809603808024;9182.714419981074;9572.664956726481;9624.518774061129;9756.170725488413;9900.752481188028;6886.932525458562;6935.583864968118;7252.419806415369;7310.451716121206;7358.816029599088;6883.242843287335;7886;7959.124496305518;7962.92037079645;7971.880421793794;6949.339812717783;7107.822304442224;7213.098362704662;7263.237360077116;7348.889766653611;6894.310568943066;7069.646517674199;7076.150861896605;7176.174739904978;7189.676464559003;6773.390394864486;6783.898241526283;6831.726730930707;6855.5886646801355;6856.554323968926;4858.708477491362;4922.581580565692;4949.307097006494;4960.479149689375;4972.950270497394;4260.403543503974;4397.7581233031215;4837.7097374157665;4928.359313289249;5117.1300878851425 06:07:15 INFO - PROCESS | 1638 | |4;prototype.html;317.71247021445595;320.72671494168156;321.7522507669094;324.5877735276201;328.65819547670424;4825.541573550516;5515.448455154483;5560.721963901804;5584.553235741141;5609.635373700707;4734.106357457019;5194.480551944804;5421.783128674848;5422.4785474754235;5423.891522169564;3701.777893326409;3769.1519408133086;3773.77357358558;4100.774457404841;4107.322291821846;3498.8950331490096;4164.7709375984305;4168.166366726652;4221.34569141782;4225.556316586761;4167.728842702974;4176.352665336878;4179.164167166564;4207.621314081732;4214.957850421487;4063.9796801015805;4100.159467309194;4165.562615925314;4205.768682722449;4228.619424251817;3097.552933182783;3170.461021626329;3364.764466487347;3552.502649629054;3654.9766065501703;5278.419373868871;5328.387235467932;5337.733113344356;5354.598405119632;5358.598105142091;4250.468691413574;4389.827035617358;4508.549145085498;4822.9389534302745;4874.829380971684;5052.9747351263;5494.395616482184;5712.571557133231;5761.502009477566;5763.740631671585;3551.4140166872435;4029.53660329063;4031.8992025199314;4039.35370340747;4040.0707837197315;4630.560096790799;5396.78412863485;6021.397860213943;6030.216071910668;6048.516118710492;3532.4877892705927;3682.8895133146048;4063.1264278180333;4086.2849001424634;4108.8356465741035;4156.459660244149;4176.749395036308;4182.790860456965;4194.056337324138;4225.94351412147;4368.628666563378;4479.148961697267;4834.709917404967;4858.78135483904;4865.197242455026;5468.589855760754;5772.884542309131;5817.621854579439;5821.476067153892;5875.353711091777;1108.7249662887582;1154.2958795134962;1308.652088349002;1319.280991859439;1319.5315662939518;841.760098371961;851.0213254757078;868.8609822428381;878.3719640457103;898.6924025542819;4637.284204844229;4792.976035119872;5144.408393034849;5165.767540460688;5213.6350455467755;4702.648675662169;4911.495137479911;5281.181416880389;5652.905603467621;5688.715564221855;4199.790010499524;4298.935515967263;4837.443693975168;5162.45794191612;5196.038732834438;2727.290904364879;2727.836329820217;2845.2602323395654;2994.89189000067;3049.085274417666;5330.374235858023;5374.704391258519;5454.699991500426;5552.278203833475;5932.495737862244;4772.546608072227;5599.452249226592;5663.292088488939;5901.734421951022;5963.761449542057;4984.800607975641;5503.889922201533;5672.205891175238;6209.037599172136;6263.530235232376;5485.561155107662;5539.058360078877;5814.819442708984;6074.878502430015;6113.602615829956;5056.74716264178;5743.8564035899435;5932.970335148297;5949.613275137102;5983.760649573968;5605.972774701616;5670.716464176857;5981.970090149695;6233.2208473940755;6294.772519358681;4040.8383664653084;4114.547399785962;4140.585941405835;4142.813573389265;4143.337066069535;5374.811881584125;5618.269624948731;5944.791932282523;5960.433758792908;5976.282846058501;5800.854978625399;5845.736941837543;5853.414658534112;5854.648721076749;5861.944849927054;5596.216529685766;5681.289838770153;5752.223449834386;5755.309362876482;5823.4467725566;5699.1451282306325;5699.857503562444;5735;5753.884922301447;5760.654360738369;4958.504149585013;5555.611107222618;5572.721363931706;5732.140178973187;5906.438888305604;4859.902801943871;5233.528982391603;5717.5425965921795;5738.110592858114;5754.223179870663 06:07:15 INFO - PROCESS | 1638 | |5;yui.html;705.5801797930229;754.3738696881588;763.2215140556632;767.08333541418;797.1629788721848;12473.37633118345;14668.559943201695;15328.693426131471;15357.923210383933;15394.384224631016;13657.590272291847;13764.242966636832;13783.379747911344;13812.378442970064;13815;838.8238469921324;863.386995233385;864.8745931839879;866.8786369908187;869.0483920107472;716.863795878784;835.6406745099605;854.2141230068352;858.2661824140364;861.1517655109712;855.4311382930343;869.0657543141117;878.4992554244084;901.0268909577674;903.286403741047;882.2280504558511;883.0948278015054;887.9389129989665;889.1641856654724;889.1775108025051;640.3180612647562;641.9165508483867;665.9567128136704;669.718718138383;673.4511373230802;12483.375831208403;12500.624981250578;12568.74862502754;12630.179038362478;13704.588862334047;13389.196648201041;13398.598042058753;13411.530596429078;13552.661183470393;13587.796183057164;11214.383208923506;11240.213185077047;11476.311421314747;11503.144026349919;11778.469968851423;3660.213054193361;3667.8833027925893;3684.5762737285286;3696.3900956342172;3823.254465379252;90580.73548161349;90683.54658226733;91374.08625913723;91417;91699.54150229273;10238.897611023944;10475.528601212964;10542.683719488428;10672.89327106719;10945.069669078217;16762.245698943578;20014.099365528586;20111;20112.79887201109;20155.79844201539;7464.776056718307;7552.131504876899;7691.269329413697;7735.651895664709;8011.359091272684;4357.7385356878685;4367.323064924942;4452.465704115527;4506.549345065533;4579.358889755437;3380.8985730428126;3514.3498452786316;3515.156362472988;3516.876909308162;3521.454174602941;576.3400905962733;631.9146915166485;642.7139922734432;645.9224893012869;659.6932426421737;543.6411968101035;550.4660479335039;633.1136409027398;680.384252251713;682.757621044532;8788.516631585197;9339.719808405758;9346.485943273185;9429.811403771886;9489.240860731124;603.9094135879653;654.4796886475264;656.7537173559915;665.1386454541381;713.3579778199559;4345.847895323648;4551.431071116111;4573.725576465423;4575.931361029588;4591.79336929839;80689.59655201803;97969.51015244878;100098;101766.43817466353;103336.48331758293;86116.56941715251;89739;91671;92460.07539924649;93441;57386.852262954504;83537.58231208926;83918.58040709757;84490;87841;7120.786376408716;9453.962468944153;9488.573014214238;9805.795283741802;9870.259730520096;51869.48130518646;53377.4662253388;53688.46311536991;54163.45836541584;55122;37414;48944.75527622339;53231;77091.84362234459;78067.6096619536;10156.746081348025;12265.018798495921;12569.748605027666;12852.935735321264;13003.804942925675;13876.930615346859;13970.65073373133;13995;14070.381756857643;14103.153810770977;12859.485620575073;12927.224366538037;13228.404721787736;13278.136921100098;13486.460541578226;12667.366631668565;13385.263810490127;13386.995975301697;13610.931945340606;13898.930505347409;12467.563635272722;13098.934505327794;13435.596932092052;13543.729125417634;13569.728605428034;10462.529186186488;10586.311889727143;10773.515191816543;10896.673099807018;10993.560257589606;10516.258069626605;10568.418736969541;11274.843043947707;11405.087592992375;11474.024707899922 06:07:15 INFO - PROCESS | 1638 | __end_tp_report 06:07:15 INFO - PROCESS | 1638 | __start_cc_report 06:07:15 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,370 06:07:15 INFO - PROCESS | 1638 | __end_cc_report 06:07:15 INFO - PROCESS | 1638 | __startTimestamp1487340435370__endTimestamp 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | ------- Summary: start ------- 06:07:15 INFO - PROCESS | 1638 | Number of tests: 6 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5901.44 Median:6139.12 stddev:670.80 (10.9%) stddev-sans-first:647.77 06:07:15 INFO - PROCESS | 1638 | Values: 3584.9 3595.3 3610.4 3623.7 3649.2 5989.2 6006.6 6015.3 6034.8 6080.6 5954.9 6042.6 6043.7 6044.5 6064.7 4905.9 5059.2 5358.1 5366.3 5461.9 5445.4 5479.1 5487.0 5487.3 5505.6 3833.6 5392.9 5467.2 5507.8 5531.8 5430.6 5431.5 5475.4 5488.6 5538.9 5360.1 5420.2 5431.3 5456.9 5470.7 5897.1 6038.2 6044.1 6046.0 6051.3 6213.3 6233.1 6250.5 6252.6 6296.2 6423.2 6433.8 6495.6 6527.5 6537.6 6118.6 6120.2 6128.8 6149.8 6151.2 6429.5 6516.3 6591.7 6592.2 6629.5 5315.7 6302.7 6322.0 6324.9 6331.7 6213.3 6267.9 6488.9 6492.4 6518.1 4739.4 6275.5 6298.8 6362.6 6395.5 5954.8 6395.3 6415.0 6415.7 6455.6 4651.7 5174.2 5184.5 5189.7 5207.7 4437.5 4468.4 4553.0 4581.1 4653.3 4769.5 6130.6 6169.3 6179.6 6194.3 6437.0 6447.8 6479.4 6489.0 6507.5 5061.5 5080.4 6004.6 6086.3 6103.5 5752.1 5752.2 5754.3 5761.7 5764.6 5506.0 6648.3 6690.1 6692.8 6700.6 6222.1 6239.8 6281.2 6293.5 6465.3 6542.8 6543.1 6548.1 6550.5 6577.5 5628.7 6213.1 6273.1 6288.0 6305.9 6218.8 6228.3 6331.5 6367.1 6374.9 6139.1 6159.5 6213.7 6255.1 6306.6 6186.9 6215.6 6234.0 6243.9 6248.2 6023.8 6117.4 6138.3 6170.6 6171.2 6122.0 6136.1 6140.7 6174.0 6205.9 6111.1 6133.0 6166.3 6184.5 6195.1 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:73638.33 Median:40149.00 stddev:163967.44 (408.4%) stddev-sans-first:164335.50 06:07:15 INFO - PROCESS | 1638 | Values: 517.0 530.8 532.4 538.4 540.5 35855.6 40100.8 40197.2 40251.8 40256.2 41281.4 41571.2 41708.6 41756.4 41887.5 9582.4 9764.1 9766.0 9780.1 9789.6 9589.1 9615.3 9692.6 9720.5 9724.6 9708.4 9714.0 9761.9 9763.4 9773.9 9672.0 9698.6 9748.4 9748.5 9752.8 9608.1 9652.8 9664.0 9675.8 9700.0 39133.4 39394.0 39411.6 39524.8 39603.0 3179.9 3519.3 3754.3 3970.9 4007.7 28877.0 29009.7 29906.4 30781.0 31066.5 2781.6 3178.0 3238.0 3384.0 3395.5 509947.0 510205.0 510219.0 510537.0 511251.0 52307.7 52393.7 52492.7 52607.0 52629.0 49870.3 58602.7 59268.1 59526.4 59684.0 16153.3 16288.9 16429.2 16429.5 16513.6 12362.5 12811.1 12843.3 12915.6 12986.2 14696.5 15568.6 15580.3 15620.8 15782.5 7126.6 7283.7 7342.0 7406.9 7415.2 873.5 884.4 1028.1 1072.7 1074.6 29286.7 30451.5 30736.2 30884.4 31066.2 1067.5 1071.9 1096.4 1097.4 1253.3 13978.7 14697.4 16378.6 16743.7 17000.7 589011.0 695620.0 704806.0 705634.0 706479.5 58693.7 58828.4 59342.4 59422.0 59509.0 1365.9 1389.1 1410.5 1411.6 1412.4 30438.0 31172.0 31412.8 31928.4 32118.0 49944.3 55755.2 57593.7 57748.0 60520.0 621539.0 631019.0 634382.0 637423.0 638688.0 26616.7 42789.0 43107.9 43280.7 43410.1 44430.3 45392.0 45604.1 47197.3 47390.5 36091.8 38056.8 38895.8 39237.6 40066.4 38236.0 38763.2 39734.0 39789.8 39828.6 37424.8 38007.4 38842.4 39114.6 39122.4 31851.5 34633.3 34922.8 35185.6 35898.8 25792.1 26220.6 26262.6 26568.7 26964.9 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:34121.13 Median:391.81 stddev:98034.63 (25021.2%) stddev-sans-first:98276.90 06:07:15 INFO - PROCESS | 1638 | Values: 378.6 380.6 389.3 394.3 417.8 15779.3 17529.9 20333.9 20546.6 20619.4 10438.0 11730.5 15591.4 19984.0 20154.6 5296.9 5466.1 5500.8 5506.6 5526.0 5264.6 5291.7 5388.0 5406.8 5442.9 5333.3 5336.7 5341.6 5357.1 5454.8 4959.4 5098.9 5214.2 5363.5 5431.8 4532.2 5284.9 5285.9 5293.6 5296.4 11203.0 12950.3 23097.4 24544.5 24682.5 1905.9 2169.0 2188.9 2242.3 2301.7 10410.0 10595.7 12580.9 14575.7 15624.1 1635.9 1839.1 1865.1 1877.7 1928.8 93152.0 101080.5 114864.9 125690.4 133136.0 11416.4 11439.0 11696.8 11715.2 11802.6 14966.3 15052.2 15055.4 15087.5 15120.3 7418.0 8302.7 8541.9 8769.4 8779.2 8583.3 8593.5 8735.2 8783.3 8786.0 4494.4 4634.7 4643.5 4664.1 4764.8 1860.5 1933.8 1995.0 1996.5 1998.6 844.6 893.6 896.5 899.0 961.4 21056.7 21592.0 21730.7 21839.6 21880.6 924.5 966.4 970.5 987.6 990.6 10839.5 11160.8 11412.4 11473.0 11491.7 580686.0 581143.0 584411.0 585402.0 603894.0 104398.0 109074.0 124068.0 135763.0 135838.3 935.8 953.9 974.5 977.0 978.2 18955.3 19501.4 19727.7 19765.2 20160.9 49782.0 50325.0 56651.4 56974.7 57555.4 74450.0 80220.8 82266.0 84057.6 84294.7 5472.6 5626.7 7952.7 8045.8 8515.4 9312.4 9503.5 9833.4 11933.6 12530.9 9695.0 10491.1 10712.3 11011.5 11396.9 9034.2 10009.6 10233.5 10552.9 10585.6 11218.3 11220.5 11220.9 11445.6 11644.8 6195.8 6266.5 7180.4 7454.5 8500.9 7846.7 10452.1 10872.0 11146.1 11576.8 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5514.02 Median:5167.87 stddev:2963.15 (57.3%) stddev-sans-first:2956.16 06:07:15 INFO - PROCESS | 1638 | Values: 1507.3 1536.8 1554.1 1856.2 1895.1 7631.4 7836.4 8078.8 8527.9 8545.1 8062.6 8333.5 8363.7 8465.5 8472.3 2362.2 2394.3 2397.9 2398.6 2404.3 2394.1 2433.6 2447.8 2449.1 2452.4 2354.8 2359.6 2375.8 2380.6 2408.7 2355.6 2357.9 2359.0 2385.8 2389.2 2387.4 2387.4 2392.6 2408.8 2409.7 8411.2 8482.6 8486.8 8488.9 8503.4 5128.9 5206.8 5272.4 5300.3 5308.7 3521.5 3568.8 3592.8 3593.0 3665.2 1078.1 1082.5 1115.4 1121.3 1129.9 10821.1 10898.4 10927.5 10928.7 10943.2 4577.6 4580.6 4769.2 4800.2 4865.7 9345.8 9468.6 9476.5 9512.5 9517.8 5272.3 5292.9 5297.8 5307.5 5308.4 4395.3 4397.8 4760.0 4793.9 4833.6 3320.4 3825.6 3948.5 3960.8 3994.8 1290.8 1291.2 1307.4 1321.8 1323.6 919.5 919.7 927.3 939.2 940.5 5624.2 5958.1 5966.6 6006.5 6219.8 930.4 951.8 955.7 986.9 1019.3 3880.2 3960.0 3961.7 3985.2 4005.8 8493.0 10062.7 10168.9 10192.6 10212.5 10082.7 10111.4 10125.0 10177.1 10222.5 6982.3 7523.1 7787.0 8003.7 8039.1 5781.3 5795.2 5865.6 5911.4 5935.7 8978.8 9382.0 9402.3 9420.4 9519.8 9182.7 9572.7 9624.5 9756.2 9900.8 6886.9 6935.6 7252.4 7310.5 7358.8 6883.2 7886.0 7959.1 7962.9 7971.9 6949.3 7107.8 7213.1 7263.2 7348.9 6894.3 7069.6 7076.2 7176.2 7189.7 6773.4 6783.9 6831.7 6855.6 6856.6 4858.7 4922.6 4949.3 4960.5 4973.0 4260.4 4397.8 4837.7 4928.4 5117.1 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4629.27 Median:5204.84 stddev:1429.14 (27.5%) stddev-sans-first:1396.03 06:07:15 INFO - PROCESS | 1638 | Values: 317.7 320.7 321.8 324.6 328.7 4825.5 5515.4 5560.7 5584.6 5609.6 4734.1 5194.5 5421.8 5422.5 5423.9 3701.8 3769.2 3773.8 4100.8 4107.3 3498.9 4164.8 4168.2 4221.3 4225.6 4167.7 4176.4 4179.2 4207.6 4215.0 4064.0 4100.2 4165.6 4205.8 4228.6 3097.6 3170.5 3364.8 3552.5 3655.0 5278.4 5328.4 5337.7 5354.6 5358.6 4250.5 4389.8 4508.5 4822.9 4874.8 5053.0 5494.4 5712.6 5761.5 5763.7 3551.4 4029.5 4031.9 4039.4 4040.1 4630.6 5396.8 6021.4 6030.2 6048.5 3532.5 3682.9 4063.1 4086.3 4108.8 4156.5 4176.7 4182.8 4194.1 4225.9 4368.6 4479.1 4834.7 4858.8 4865.2 5468.6 5772.9 5817.6 5821.5 5875.4 1108.7 1154.3 1308.7 1319.3 1319.5 841.8 851.0 868.9 878.4 898.7 4637.3 4793.0 5144.4 5165.8 5213.6 4702.6 4911.5 5281.2 5652.9 5688.7 4199.8 4298.9 4837.4 5162.5 5196.0 2727.3 2727.8 2845.3 2994.9 3049.1 5330.4 5374.7 5454.7 5552.3 5932.5 4772.5 5599.5 5663.3 5901.7 5963.8 4984.8 5503.9 5672.2 6209.0 6263.5 5485.6 5539.1 5814.8 6074.9 6113.6 5056.7 5743.9 5933.0 5949.6 5983.8 5606.0 5670.7 5982.0 6233.2 6294.8 4040.8 4114.5 4140.6 4142.8 4143.3 5374.8 5618.3 5944.8 5960.4 5976.3 5800.9 5845.7 5853.4 5854.6 5861.9 5596.2 5681.3 5752.2 5755.3 5823.4 5699.1 5699.9 5735.0 5753.9 5760.7 4958.5 5555.6 5572.7 5732.1 5906.4 4859.9 5233.5 5717.5 5738.1 5754.2 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19542.96 Median:4574.83 stddev:27992.85 (611.9%) stddev-sans-first:28035.65 06:07:15 INFO - PROCESS | 1638 | Values: 705.6 754.4 763.2 767.1 797.2 12473.4 14668.6 15328.7 15357.9 15394.4 13657.6 13764.2 13783.4 13812.4 13815.0 838.8 863.4 864.9 866.9 869.0 716.9 835.6 854.2 858.3 861.2 855.4 869.1 878.5 901.0 903.3 882.2 883.1 887.9 889.2 889.2 640.3 641.9 666.0 669.7 673.5 12483.4 12500.6 12568.7 12630.2 13704.6 13389.2 13398.6 13411.5 13552.7 13587.8 11214.4 11240.2 11476.3 11503.1 11778.5 3660.2 3667.9 3684.6 3696.4 3823.3 90580.7 90683.5 91374.1 91417.0 91699.5 10238.9 10475.5 10542.7 10672.9 10945.1 16762.2 20014.1 20111.0 20112.8 20155.8 7464.8 7552.1 7691.3 7735.7 8011.4 4357.7 4367.3 4452.5 4506.5 4579.4 3380.9 3514.3 3515.2 3516.9 3521.5 576.3 631.9 642.7 645.9 659.7 543.6 550.5 633.1 680.4 682.8 8788.5 9339.7 9346.5 9429.8 9489.2 603.9 654.5 656.8 665.1 713.4 4345.8 4551.4 4573.7 4575.9 4591.8 80689.6 97969.5 100098.0 101766.4 103336.5 86116.6 89739.0 91671.0 92460.1 93441.0 57386.9 83537.6 83918.6 84490.0 87841.0 7120.8 9454.0 9488.6 9805.8 9870.3 51869.5 53377.5 53688.5 54163.5 55122.0 37414.0 48944.8 53231.0 77091.8 78067.6 10156.7 12265.0 12569.7 12852.9 13003.8 13876.9 13970.7 13995.0 14070.4 14103.2 12859.5 12927.2 13228.4 13278.1 13486.5 12667.4 13385.3 13387.0 13610.9 13898.9 12467.6 13098.9 13435.6 13543.7 13569.7 10462.5 10586.3 10773.5 10896.7 10993.6 10516.3 10568.4 11274.8 11405.1 11474.0 06:07:15 INFO - PROCESS | 1638 | -------- Summary: end -------- 06:07:15 INFO - PROCESS | 1638 | 06:07:15 INFO - TEST-INFO | 1638: exit 0 06:07:15 INFO - TEST-OK | dromaeo_css | took 1115052ms 06:07:15 INFO - TEST-START | kraken 06:07:15 INFO - operating with platform_type : mac_ 06:07:15 INFO - Initialising browser for kraken test... 06:07:15 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpgnjoQJ/profile http://localhost:49244/getInfo.html) 06:07:18 INFO - PROCESS | 1650 | 2017-02-17 06:07:18.784 plugin-container[1652:10103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 06:07:18 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:18 INFO - PROCESS | 1650 | 2017-02-17 06:07:18.785 plugin-container[1652:10103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 06:07:18 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:18 INFO - PROCESS | 1650 | 2017-02-17 06:07:18.785 plugin-container[1652:10103] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:18 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 06:07:18 INFO - PROCESS | 1650 | colorDepth:24 06:07:18 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 06:07:18 INFO - PROCESS | 1650 | __metrics 06:07:18 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 06:07:19 INFO - TEST-INFO | 1650: exit 0 06:07:19 INFO - Browser initialized. 06:07:19 INFO - Running cycle 1/1 for kraken test... 06:07:19 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpgnjoQJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:22 INFO - PROCESS | 1653 | 2017-02-17 06:07:22.142 plugin-container[1654:10226] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 06:07:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:22 INFO - PROCESS | 1653 | 2017-02-17 06:07:22.142 plugin-container[1654:10226] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 06:07:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:22 INFO - PROCESS | 1653 | 2017-02-17 06:07:22.142 plugin-container[1654:10226] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:31 INFO - PROCESS | 1653 | RSS: Main: 177782784 06:07:31 INFO - PROCESS | 1653 | 06:07:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 06:07:35 INFO - PROCESS | 1653 | RSS: Main: 190775296 06:07:35 INFO - PROCESS | 1653 | 06:07:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 06:07:39 INFO - PROCESS | 1653 | RSS: Main: 198651904 06:07:39 INFO - PROCESS | 1653 | 06:07:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 06:07:43 INFO - PROCESS | 1653 | RSS: Main: 202543104 06:07:43 INFO - PROCESS | 1653 | 06:07:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 06:07:45 INFO - PROCESS | 1653 | RSS: Main: 203804672 06:07:45 INFO - PROCESS | 1653 | 06:07:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:07:48 INFO - PROCESS | 1653 | RSS: Main: 203902976 06:07:48 INFO - PROCESS | 1653 | 06:07:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 06:07:51 INFO - PROCESS | 1653 | RSS: Main: 207515648 06:07:51 INFO - PROCESS | 1653 | 06:07:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 06:07:55 INFO - PROCESS | 1653 | RSS: Main: 207515648 06:07:55 INFO - PROCESS | 1653 | 06:07:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 06:07:59 INFO - PROCESS | 1653 | RSS: Main: 207863808 06:07:59 INFO - PROCESS | 1653 | 06:08:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:01 INFO - PROCESS | 1653 | RSS: Main: 207060992 06:08:01 INFO - PROCESS | 1653 | 06:08:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:04 INFO - PROCESS | 1653 | RSS: Main: 207015936 06:08:04 INFO - PROCESS | 1653 | 06:08:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:06 INFO - PROCESS | 1653 | RSS: Main: 207048704 06:08:06 INFO - PROCESS | 1653 | 06:08:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:10 INFO - PROCESS | 1653 | RSS: Main: 207482880 06:08:10 INFO - PROCESS | 1653 | 06:08:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:13 INFO - PROCESS | 1653 | RSS: Main: 207532032 06:08:13 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 06:08:15 INFO - PROCESS | 1653 | RSS: Main: 209354752 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | __start_tp_report 06:08:15 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 06:08:15 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 06:08:15 INFO - PROCESS | 1653 | |i|pagename|runs| 06:08:15 INFO - PROCESS | 1653 | |0;ai-astar;109;118;118;119;118;117;138;169;157;118 06:08:15 INFO - PROCESS | 1653 | |1;audio-beat-detection;142;113;109;143;124;140;141;109;170;139 06:08:15 INFO - PROCESS | 1653 | |2;audio-dft;201;198;194;197;198;199;201;192;205;234 06:08:15 INFO - PROCESS | 1653 | |3;audio-fft;77;59;58;86;59;60;55;57;76;64 06:08:15 INFO - PROCESS | 1653 | |4;audio-oscillator;86;71;89;95;55;70;113;98;102;118 06:08:15 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;124;131;91;73;135;123;104;74;117;131 06:08:15 INFO - PROCESS | 1653 | |6;imaging-darkroom;169;119;99;104;116;108;171;170;164;111 06:08:15 INFO - PROCESS | 1653 | |7;imaging-desaturate;76;73;79;72;134;130;139;91;87;71 06:08:15 INFO - PROCESS | 1653 | |8;json-parse-financial;54;55;54;56;59;59;59;59;59;59 06:08:15 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;68;69;75;75;66;68;72;71;69;75 06:08:15 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;85;82;83;84;85;85;84;87;82;84 06:08:15 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;138;136;138;148;161;152;145;142;188;139 06:08:15 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;164;174;163;163;164;164;162;197;227;169 06:08:15 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;65;66;67;68;66;67;67;77;84;83 06:08:15 INFO - PROCESS | 1653 | __end_tp_report 06:08:15 INFO - PROCESS | 1653 | __start_cc_report 06:08:15 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,954 06:08:15 INFO - PROCESS | 1653 | __end_cc_report 06:08:15 INFO - PROCESS | 1653 | __startTimestamp1487340495987__endTimestamp 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | ------- Summary: start ------- 06:08:15 INFO - PROCESS | 1653 | Number of tests: 14 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:128.10 Median:118.50 stddev:19.96 (16.8%) stddev-sans-first:19.94 06:08:15 INFO - PROCESS | 1653 | Values: 109.0 118.0 118.0 119.0 118.0 117.0 138.0 169.0 157.0 118.0 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:140.50 stddev:19.24 (13.7%) stddev-sans-first:20.13 06:08:15 INFO - PROCESS | 1653 | Values: 142.0 113.0 109.0 143.0 124.0 140.0 141.0 109.0 170.0 139.0 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:201.90 Median:200.00 stddev:11.86 (5.9%) stddev-sans-first:12.57 06:08:15 INFO - PROCESS | 1653 | Values: 201.0 198.0 194.0 197.0 198.0 199.0 201.0 192.0 205.0 234.0 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:65.10 Median:62.00 stddev:10.63 (17.1%) stddev-sans-first:10.37 06:08:15 INFO - PROCESS | 1653 | Values: 77.0 59.0 58.0 86.0 59.0 60.0 55.0 57.0 76.0 64.0 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:78.50 stddev:19.86 (25.3%) stddev-sans-first:21.01 06:08:15 INFO - PROCESS | 1653 | Values: 86.0 71.0 89.0 95.0 55.0 70.0 113.0 98.0 102.0 118.0 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:133.00 stddev:23.53 (17.7%) stddev-sans-first:24.43 06:08:15 INFO - PROCESS | 1653 | Values: 124.0 131.0 91.0 73.0 135.0 123.0 104.0 74.0 117.0 131.0 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:133.10 Median:166.50 stddev:31.02 (18.6%) stddev-sans-first:30.06 06:08:15 INFO - PROCESS | 1653 | Values: 169.0 119.0 99.0 104.0 116.0 108.0 171.0 170.0 164.0 111.0 06:08:15 INFO - PROCESS | 1653 | 06:08:15 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:74.50 stddev:27.82 (37.3%) stddev-sans-first:28.63 06:08:16 INFO - PROCESS | 1653 | Values: 76.0 73.0 79.0 72.0 134.0 130.0 139.0 91.0 87.0 71.0 06:08:16 INFO - PROCESS | 1653 | 06:08:16 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:59.00 stddev:2.26 (3.8%) stddev-sans-first:2.06 06:08:16 INFO - PROCESS | 1653 | Values: 54.0 55.0 54.0 56.0 59.0 59.0 59.0 59.0 59.0 59.0 06:08:16 INFO - PROCESS | 1653 | 06:08:16 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.50 stddev:3.33 (4.7%) stddev-sans-first:3.37 06:08:16 INFO - PROCESS | 1653 | Values: 68.0 69.0 75.0 75.0 66.0 68.0 72.0 71.0 69.0 75.0 06:08:16 INFO - PROCESS | 1653 | 06:08:16 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.50 stddev:1.52 (1.8%) stddev-sans-first:1.58 06:08:16 INFO - PROCESS | 1653 | Values: 85.0 82.0 83.0 84.0 85.0 85.0 84.0 87.0 82.0 84.0 06:08:16 INFO - PROCESS | 1653 | 06:08:16 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:146.50 stddev:15.81 (10.8%) stddev-sans-first:16.29 06:08:16 INFO - PROCESS | 1653 | Values: 138.0 136.0 138.0 148.0 161.0 152.0 145.0 142.0 188.0 139.0 06:08:16 INFO - PROCESS | 1653 | 06:08:16 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:166.50 stddev:21.20 (12.7%) stddev-sans-first:22.13 06:08:16 INFO - PROCESS | 1653 | Values: 164.0 174.0 163.0 163.0 164.0 164.0 162.0 197.0 227.0 169.0 06:08:16 INFO - PROCESS | 1653 | 06:08:16 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:67.50 stddev:7.39 (11.0%) stddev-sans-first:7.52 06:08:16 INFO - PROCESS | 1653 | Values: 65.0 66.0 67.0 68.0 66.0 67.0 67.0 77.0 84.0 83.0 06:08:16 INFO - PROCESS | 1653 | -------- Summary: end -------- 06:08:16 INFO - PROCESS | 1653 | 06:08:16 INFO - TEST-INFO | 1653: exit 0 06:08:16 INFO - TEST-OK | kraken | took 61048ms 06:08:16 INFO - SUITE-END | took 1176s 06:08:17 INFO - Completed test suite (00:19:36) 06:08:17 INFO - javascript benchmark 06:08:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7287.552928272211, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3584.8566057357707, 3595.3168897909413, 3610.386234340162, 3623.728220383471, 3649.215418684982, 5989.1914591530185, 6006.609570377922, 6015.338312785596, 6034.818955431344, 6080.574359794816, 5954.910676339859, 6042.57701960863, 6043.728032238549, 6044.486218671407, 6064.727087281072, 4905.9264111038365, 5059.16523773577, 5358.089124848775, 5366.329208848894, 5461.918071228915, 5445.37378201506, 5479.0959491684025, 5486.972565137169, 5487.286652735159, 5505.614606977513, 3833.616638336172, 5392.946070539303, 5467.17992301156, 5507.779688812443, 5531.778728850841, 5430.619856610039, 5431.538319242869, 5475.425080366544, 5488.643238189545, 5538.889222215574, 5360.115580929142, 5420.186971954199, 5431.266778984826, 5456.890862182774, 5470.671759694431, 5897.115432685088, 6038.245219347582, 6044.093385992092, 6045.969770151165, 6051.304100028509, 6213.285472170668, 6233.096201050822, 6250.531210159256, 6252.5935814171935, 6296.244450665904, 6423.164988551505, 6433.806985790434, 6495.577787443801, 6527.5430719849155, 6537.64042977636, 6118.60229085108, 6120.234970628716, 6128.785492507741, 6149.815505534841, 6151.200343955257, 6429.54993150475, 6516.348365163541, 6591.703373348211, 6592.241892182365, 6629.502787290972, 5315.707636080053, 6302.716377763011, 6322.0200868865395, 6324.873502529924, 6331.651759153198, 6213.254409470893, 6267.903116954515, 6488.870222595616, 6492.383223593751, 6518.087467754518, 4739.4075740532435, 6275.497960163176, 6298.811035668938, 6362.586431881913, 6395.456386207132, 5954.8090381923785, 6395.2645445773405, 6415.0, 6415.711292991827, 6455.644939528371, 4651.720896746206, 5174.172132458863, 5184.533391994739, 5189.6886186828915, 5207.661502002357, 4437.467503899553, 4468.419105516262, 4553.0, 4581.106684196549, 4653.348531205634, 4769.546893045155, 6130.581587307925, 6169.321374648785, 6179.567430279927, 6194.34959329273, 6437.002264648987, 6447.774327898501, 6479.449246814073, 6488.994205897999, 6507.544471886922, 5061.544460998454, 5080.390353157571, 6004.63972161663, 6086.3000754913755, 6103.542234332443, 5752.13717942312, 5752.165935939349, 5754.251947246831, 5761.740721667535, 5764.567657425626, 5505.9724701376235, 6648.33516648341, 6690.096836927147, 6692.83267918306, 6700.564463309869, 6222.09779581949, 6239.812805615657, 6281.152044473937, 6293.465055470337, 6465.2564955030775, 6542.803715888531, 6543.149390579194, 6548.0832683423405, 6550.508711846726, 6577.506686998398, 5628.690422026664, 6213.068039793886, 6273.121762953099, 6287.96856015717, 6305.873882522415, 6218.81343559694, 6228.314885362516, 6331.461825744863, 6367.07677386767, 6374.872502549831, 6139.1241809172225, 6159.476444502267, 6213.689315534296, 6255.093011513214, 6306.621602703853, 6186.938130618636, 6215.564910456224, 6234.0, 6243.906341404791, 6248.218972628421, 6023.849608584597, 6117.431594453999, 6138.263408391022, 6170.568060235741, 6171.197744293213, 6122.032243765608, 6136.090214303957, 6140.657627863787, 6174.0, 6205.906911396423, 6111.083337499411, 6133.0, 6166.2600487939935, 6184.505239580732, 6195.132681424694], "value": 5862.997003685036, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.961227907907, 530.7823792245179, 532.3585079978626, 538.4131296886393, 540.5081375947886, 35855.64144358559, 40100.79697609073, 40197.19605607886, 40251.798741006256, 40256.19487610246, 41281.38077928834, 41571.16857662845, 41708.58291417085, 41756.37365439521, 41887.53393631223, 9582.377145485556, 9764.072413120743, 9766.0, 9780.070893265163, 9789.6084156634, 9589.089036541518, 9615.326927115104, 9692.612295508172, 9720.465374404404, 9724.638550602922, 9708.417494950325, 9714.0, 9761.853572196424, 9763.36538125023, 9773.853392199122, 9671.951640241788, 9698.612055517771, 9748.415095094317, 9748.512574371252, 9752.756181095458, 9608.087231712976, 9652.806943861084, 9663.985281545478, 9675.758106047335, 9700.029997000314, 39133.41299880504, 39394.0, 39411.60588394108, 39524.80237598823, 39603.0, 3179.8728050877935, 3519.331327047853, 3754.2679177560385, 3970.8881513176357, 4007.7194596378267, 28877.0, 29009.70990290091, 29906.40187196244, 30781.0, 31066.534001989763, 2781.6105745195687, 3177.9830454254648, 3237.951430728541, 3383.967889793612, 3395.5246265522846, 509946.9999999963, 510205.0, 510219.0, 510537.0, 511251.0, 52307.73846130745, 52393.7380313096, 52492.737536312074, 52607.0, 52629.0, 49870.25194622084, 58602.70698646479, 59268.11097833536, 59526.404735952085, 59684.0, 16153.27310271017, 16288.918555407385, 16429.1785410729, 16429.507114786575, 16513.587160320847, 12362.505499780087, 12811.103222774316, 12843.293618850867, 12915.612531624067, 12986.220826750425, 14696.485623003357, 15568.610784730472, 15580.298886550132, 15620.843791562167, 15782.526524204291, 7126.608036558039, 7283.7450689225625, 7341.963290183515, 7406.925930740731, 7415.221401752846, 873.5195642396657, 884.4162852517308, 1028.1158203944601, 1072.7371793910543, 1074.6131392698621, 29286.707132928823, 30451.543226851616, 30736.231594210323, 30884.382312353628, 31066.223344416572, 1067.546292825546, 1071.8606581144556, 1096.446294621211, 1097.4403054442132, 1253.3357320620087, 13978.650533736738, 14697.41210351574, 16378.590535236475, 16743.748843767353, 17000.744988825176, 589011.0, 695620.0, 704806.0, 705634.0, 706479.4676026587, 58693.70653146792, 58828.41171588315, 59342.406575934554, 59422.0, 59509.0, 1365.8565850585744, 1389.1317926296065, 1410.4851729119002, 1411.5765270419038, 1412.3573773933042, 30438.0, 31172.03865632761, 31412.842935785175, 31928.36143277168, 32118.03645890627, 49944.25083623676, 55755.163672544135, 57593.71203143957, 57748.0, 60520.0, 621539.0, 631019.0, 634382.0, 637423.0, 638688.0, 26616.733832661426, 42789.0, 43107.9223019427, 43280.701578952685, 43410.13179736325, 44430.3335449975, 45392.0, 45604.08791824211, 47197.29204061873, 47390.52609473861, 36091.81954090213, 38056.80971595124, 38895.805520972215, 39237.60762392454, 40066.39900401438, 38236.0, 38763.2247355057, 39734.00664983399, 39789.80105099456, 39828.60171398249, 37424.812875936535, 38007.42988855114, 38842.41736374013, 39114.608853912236, 39122.413163802, 31851.522227166148, 34633.30733385268, 34922.82538587291, 35185.648143518236, 35898.8205058973, 25792.09727659523, 26220.60669089927, 26262.606060908718, 26568.734312657398, 26964.859412745933], "value": 20326.588610478913, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.64974898219157, 380.62698555415693, 389.2778895149496, 394.33357625612723, 417.7827529684564, 15779.28993195306, 17529.912350438244, 20333.881636509985, 20546.589068218625, 20619.38141855743, 10438.0, 11730.472128754205, 15591.37634494619, 19984.0, 20154.59690806183, 5296.867578310545, 5466.125419932811, 5500.779968801243, 5506.5870059745475, 5525.950069486785, 5264.6051546134, 5291.656042357254, 5387.973060134694, 5406.83779486616, 5442.8911421771545, 5333.306670132877, 5336.653117547347, 5341.572674186075, 5357.142857142858, 5454.781808727646, 4959.429665588467, 5098.949010509921, 5214.217867319894, 5363.517283444489, 5431.83704488866, 4532.206863798855, 5284.894302113975, 5285.947140528584, 5293.6294459387855, 5296.443873393316, 11203.0, 12950.287734174617, 23097.383183177186, 24544.509109817704, 24682.50634987308, 1905.9237630494763, 2169.045619927227, 2188.9233876814396, 2242.260054182116, 2301.746807851135, 10410.011048950337, 10595.735106622318, 12580.937095314557, 14575.70848583033, 15624.140672263022, 1635.9106205143996, 1839.1356062650532, 1865.0861078071782, 1877.737116803635, 1928.7878333383458, 93152.0, 101080.49459752654, 114864.85135148626, 125690.37154814259, 133136.0, 11416.42917854104, 11439.027682646902, 11696.824547631792, 11715.238509496856, 11802.645920622223, 14966.326515306837, 15052.247387630574, 15055.397784088515, 15087.4719384821, 15120.319585618674, 7417.998570193063, 8302.70940517079, 8541.871871921927, 8769.42998705095, 8779.20987111163, 8583.313334933191, 8593.52735599548, 8735.1701588349, 8783.341249406192, 8786.033536311, 4494.393256910341, 4634.72191668501, 4643.466001409881, 4664.113818374498, 4764.809407623657, 1860.4604664647372, 1933.7679478462674, 1994.9800501995085, 1996.4509759816167, 1998.6202621501868, 844.6030365728221, 893.6112790935964, 896.4621227263721, 898.9820203595891, 961.4375590279625, 21056.684149737765, 21592.0, 21730.674039889414, 21839.563208735737, 21880.562388752453, 924.4684306523641, 966.4346357380871, 970.5389939778548, 987.6345752071636, 990.5790039233297, 10839.458027098772, 11160.832587511193, 11412.372319522507, 11473.0, 11491.655250342503, 580686.0, 581143.0, 584411.0, 585402.0, 603894.0, 104397.95602044034, 109074.0, 124068.0, 135763.0, 135838.32080839731, 935.8362286599954, 953.9284553658504, 974.5370948799264, 976.9755756106155, 978.1734434402919, 18955.336563220222, 19501.414957551297, 19727.704084438457, 19765.209391624176, 20160.899195503927, 49782.00435991332, 50325.0, 56651.43348566462, 56974.715126424104, 57555.42444575501, 74450.0, 80220.79668805089, 82266.0, 84057.57971210105, 84294.73557896513, 5472.644278121909, 5626.662400255997, 7952.687806511861, 8045.758627241192, 8515.40392172542, 9312.394694344846, 9503.524823758646, 9833.409995400012, 11933.641990740292, 12530.872221500109, 9695.0, 10491.108255798343, 10712.250142490262, 11011.504482298476, 11396.94301528487, 9034.23832352685, 10009.649662261785, 10233.539490723084, 10552.947235263775, 10585.576576936835, 11218.326900386002, 11220.495077721685, 11220.943895280472, 11445.599404020819, 11644.76710465769, 6195.845103872439, 6266.530010249341, 7180.3537681608905, 7454.4781865268915, 8500.91499085026, 7846.686132554634, 10452.111570516287, 10871.96716311949, 11146.108311334912, 11576.768464630492], "value": 9220.587541314835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.253909314889, 1536.8078990126235, 1554.059793824736, 1856.2482194711147, 1895.099827581898, 7631.427642926788, 7836.373090152789, 8078.757637270884, 8527.872081918777, 8545.102764209762, 8062.63718132684, 8333.541655208961, 8363.749087527383, 8465.492070475775, 8472.322214222862, 2362.1614326913955, 2394.3415560720855, 2397.880105994693, 2398.6162214045758, 2404.2546810488802, 2394.1261439574605, 2433.5741245282093, 2447.8164137689655, 2449.081594402099, 2452.4236804350962, 2354.799842013431, 2359.6106642403984, 2375.774301441369, 2380.6310021946624, 2408.7470815564375, 2355.634876594122, 2357.870317132557, 2358.9646155307682, 2385.8210634202505, 2389.187676190086, 2387.438951846315, 2387.438951846315, 2392.629142482918, 2408.783209511135, 2409.6987876515436, 8411.1588841116, 8482.575871206414, 8486.78783030423, 8488.915110848875, 8503.447275927045, 5128.9230661540105, 5206.817761378334, 5272.420033796279, 5300.284461597674, 5308.73456327186, 3521.5421995140723, 3568.80371579563, 3592.802395868226, 3593.0, 3665.193657395369, 1078.0728573426848, 1082.5020490574345, 1115.380963565221, 1121.3328069798456, 1129.8983491096199, 10821.080208182313, 10898.400587967659, 10927.508262128224, 10928.672139835817, 10943.23397362185, 4577.61090307321, 4580.587747102777, 4769.189237829577, 4800.159972004885, 4865.732384718804, 9345.813083738287, 9468.621255149717, 9476.478793666414, 9512.476813775309, 9517.809643807084, 5272.261883336336, 5292.894142117136, 5297.814576489882, 5307.469253074739, 5308.362996440452, 4395.27477966132, 4397.780110994438, 4760.0, 4793.9280910786365, 4833.613310935117, 3320.4355259605927, 3825.598312177237, 3948.4669569607736, 3960.8019599019935, 3994.8202330894596, 1290.832191815058, 1291.1930043722673, 1307.3724612186204, 1321.808337791015, 1323.602919124259, 919.5264438814016, 919.6689191890914, 927.3415874729018, 939.1641439119189, 940.5297351324338, 5624.156376543551, 5958.136070269873, 5966.612170208922, 6006.489448396936, 6219.782307619212, 930.3627015494408, 951.7877164569578, 955.7180631713661, 986.9210463163073, 1019.3272440189439, 3880.2239552089695, 3960.0, 3961.742486738353, 3985.242803867256, 4005.7796821175116, 8492.98084229884, 10062.748431289276, 10168.949155254177, 10192.643257485952, 10212.489375531193, 10082.69751907444, 10111.39331640104, 10125.038121378457, 10177.134943529883, 10222.488875556193, 6982.301769822977, 7523.134839493418, 7786.961065194751, 8003.679852805939, 8039.115697273295, 5781.335146458211, 5795.159701843293, 5865.618734782225, 5911.379305172895, 5935.703214839327, 8978.820423591622, 9381.953090234505, 9402.341836071682, 9420.434773913586, 9519.809603808024, 9182.714419981074, 9572.664956726481, 9624.518774061129, 9756.170725488413, 9900.752481188028, 6886.932525458562, 6935.583864968118, 7252.419806415369, 7310.451716121206, 7358.816029599088, 6883.242843287335, 7886.0, 7959.124496305518, 7962.92037079645, 7971.880421793794, 6949.339812717783, 7107.822304442224, 7213.098362704662, 7263.237360077116, 7348.889766653611, 6894.310568943066, 7069.646517674199, 7076.150861896605, 7176.174739904978, 7189.676464559003, 6773.390394864486, 6783.898241526283, 6831.726730930707, 6855.5886646801355, 6856.554323968926, 4858.708477491362, 4922.581580565692, 4949.307097006494, 4960.479149689375, 4972.950270497394, 4260.403543503974, 4397.7581233031215, 4837.7097374157665, 4928.359313289249, 5117.1300878851425], "value": 4509.934368867186, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.71247021445595, 320.72671494168156, 321.7522507669094, 324.5877735276201, 328.65819547670424, 4825.541573550516, 5515.448455154483, 5560.721963901804, 5584.553235741141, 5609.635373700707, 4734.106357457019, 5194.480551944804, 5421.783128674848, 5422.4785474754235, 5423.891522169564, 3701.777893326409, 3769.1519408133086, 3773.77357358558, 4100.774457404841, 4107.322291821846, 3498.8950331490096, 4164.7709375984305, 4168.166366726652, 4221.34569141782, 4225.556316586761, 4167.728842702974, 4176.352665336878, 4179.164167166564, 4207.621314081732, 4214.957850421487, 4063.9796801015805, 4100.159467309194, 4165.562615925314, 4205.768682722449, 4228.619424251817, 3097.552933182783, 3170.461021626329, 3364.764466487347, 3552.502649629054, 3654.9766065501703, 5278.419373868871, 5328.387235467932, 5337.733113344356, 5354.598405119632, 5358.598105142091, 4250.468691413574, 4389.827035617358, 4508.549145085498, 4822.9389534302745, 4874.829380971684, 5052.9747351263, 5494.395616482184, 5712.571557133231, 5761.502009477566, 5763.740631671585, 3551.4140166872435, 4029.53660329063, 4031.8992025199314, 4039.35370340747, 4040.0707837197315, 4630.560096790799, 5396.78412863485, 6021.397860213943, 6030.216071910668, 6048.516118710492, 3532.4877892705927, 3682.8895133146048, 4063.1264278180333, 4086.2849001424634, 4108.8356465741035, 4156.459660244149, 4176.749395036308, 4182.790860456965, 4194.056337324138, 4225.94351412147, 4368.628666563378, 4479.148961697267, 4834.709917404967, 4858.78135483904, 4865.197242455026, 5468.589855760754, 5772.884542309131, 5817.621854579439, 5821.476067153892, 5875.353711091777, 1108.7249662887582, 1154.2958795134962, 1308.652088349002, 1319.280991859439, 1319.5315662939518, 841.760098371961, 851.0213254757078, 868.8609822428381, 878.3719640457103, 898.6924025542819, 4637.284204844229, 4792.976035119872, 5144.408393034849, 5165.767540460688, 5213.6350455467755, 4702.648675662169, 4911.495137479911, 5281.181416880389, 5652.905603467621, 5688.715564221855, 4199.790010499524, 4298.935515967263, 4837.443693975168, 5162.45794191612, 5196.038732834438, 2727.290904364879, 2727.836329820217, 2845.2602323395654, 2994.89189000067, 3049.085274417666, 5330.374235858023, 5374.704391258519, 5454.699991500426, 5552.278203833475, 5932.495737862244, 4772.546608072227, 5599.452249226592, 5663.292088488939, 5901.734421951022, 5963.761449542057, 4984.800607975641, 5503.889922201533, 5672.205891175238, 6209.037599172136, 6263.530235232376, 5485.561155107662, 5539.058360078877, 5814.819442708984, 6074.878502430015, 6113.602615829956, 5056.74716264178, 5743.8564035899435, 5932.970335148297, 5949.613275137102, 5983.760649573968, 5605.972774701616, 5670.716464176857, 5981.970090149695, 6233.2208473940755, 6294.772519358681, 4040.8383664653084, 4114.547399785962, 4140.585941405835, 4142.813573389265, 4143.337066069535, 5374.811881584125, 5618.269624948731, 5944.791932282523, 5960.433758792908, 5976.282846058501, 5800.854978625399, 5845.736941837543, 5853.414658534112, 5854.648721076749, 5861.944849927054, 5596.216529685766, 5681.289838770153, 5752.223449834386, 5755.309362876482, 5823.4467725566, 5699.1451282306325, 5699.857503562444, 5735.0, 5753.884922301447, 5760.654360738369, 4958.504149585013, 5555.611107222618, 5572.721363931706, 5732.140178973187, 5906.438888305604, 4859.902801943871, 5233.528982391603, 5717.5425965921795, 5738.110592858114, 5754.223179870663], "value": 4167.8917376731815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.5801797930229, 754.3738696881588, 763.2215140556632, 767.08333541418, 797.1629788721848, 12473.37633118345, 14668.559943201695, 15328.693426131471, 15357.923210383933, 15394.384224631016, 13657.590272291847, 13764.242966636832, 13783.379747911344, 13812.378442970064, 13815.0, 838.8238469921324, 863.386995233385, 864.8745931839879, 866.8786369908187, 869.0483920107472, 716.863795878784, 835.6406745099605, 854.2141230068352, 858.2661824140364, 861.1517655109712, 855.4311382930343, 869.0657543141117, 878.4992554244084, 901.0268909577674, 903.286403741047, 882.2280504558511, 883.0948278015054, 887.9389129989665, 889.1641856654724, 889.1775108025051, 640.3180612647562, 641.9165508483867, 665.9567128136704, 669.718718138383, 673.4511373230802, 12483.375831208403, 12500.624981250578, 12568.74862502754, 12630.179038362478, 13704.588862334047, 13389.196648201041, 13398.598042058753, 13411.530596429078, 13552.661183470393, 13587.796183057164, 11214.383208923506, 11240.213185077047, 11476.311421314747, 11503.144026349919, 11778.469968851423, 3660.213054193361, 3667.8833027925893, 3684.5762737285286, 3696.3900956342172, 3823.254465379252, 90580.73548161349, 90683.54658226733, 91374.08625913723, 91417.0, 91699.54150229273, 10238.897611023944, 10475.528601212964, 10542.683719488428, 10672.89327106719, 10945.069669078217, 16762.245698943578, 20014.099365528586, 20111.0, 20112.79887201109, 20155.79844201539, 7464.776056718307, 7552.131504876899, 7691.269329413697, 7735.651895664709, 8011.359091272684, 4357.7385356878685, 4367.323064924942, 4452.465704115527, 4506.549345065533, 4579.358889755437, 3380.8985730428126, 3514.3498452786316, 3515.156362472988, 3516.876909308162, 3521.454174602941, 576.3400905962733, 631.9146915166485, 642.7139922734432, 645.9224893012869, 659.6932426421737, 543.6411968101035, 550.4660479335039, 633.1136409027398, 680.384252251713, 682.757621044532, 8788.516631585197, 9339.719808405758, 9346.485943273185, 9429.811403771886, 9489.240860731124, 603.9094135879653, 654.4796886475264, 656.7537173559915, 665.1386454541381, 713.3579778199559, 4345.847895323648, 4551.431071116111, 4573.725576465423, 4575.931361029588, 4591.79336929839, 80689.59655201803, 97969.51015244878, 100098.0, 101766.43817466353, 103336.48331758293, 86116.56941715251, 89739.0, 91671.0, 92460.07539924649, 93441.0, 57386.852262954504, 83537.58231208926, 83918.58040709757, 84490.0, 87841.0, 7120.786376408716, 9453.962468944153, 9488.573014214238, 9805.795283741802, 9870.259730520096, 51869.48130518646, 53377.4662253388, 53688.46311536991, 54163.45836541584, 55122.0, 37414.0, 48944.75527622339, 53231.0, 77091.84362234459, 78067.6096619536, 10156.746081348025, 12265.018798495921, 12569.748605027666, 12852.935735321264, 13003.804942925675, 13876.930615346859, 13970.65073373133, 13995.0, 14070.381756857643, 14103.153810770977, 12859.485620575073, 12927.224366538037, 13228.404721787736, 13278.136921100098, 13486.460541578226, 12667.366631668565, 13385.263810490127, 13386.995975301697, 13610.931945340606, 13898.930505347409, 12467.563635272722, 13098.934505327794, 13435.596932092052, 13543.729125417634, 13569.728605428034, 10462.529186186488, 10586.311889727143, 10773.515191816543, 10896.673099807018, 10993.560257589606, 10516.258069626605, 10568.418736969541, 11274.843043947707, 11405.087592992375, 11474.024707899922], "value": 7251.294807154792, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1563.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 118.0, 119.0, 118.0, 117.0, 138.0, 169.0, 157.0, 118.0], "value": 128.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 109.0, 143.0, 124.0, 140.0, 141.0, 109.0, 170.0, 139.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 198.0, 194.0, 197.0, 198.0, 199.0, 201.0, 192.0, 205.0, 234.0], "value": 201.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 58.0, 86.0, 59.0, 60.0, 55.0, 57.0, 76.0, 64.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 89.0, 95.0, 55.0, 70.0, 113.0, 98.0, 102.0, 118.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 131.0, 91.0, 73.0, 135.0, 123.0, 104.0, 74.0, 117.0, 131.0], "value": 110.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 119.0, 99.0, 104.0, 116.0, 108.0, 171.0, 170.0, 164.0, 111.0], "value": 133.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 79.0, 72.0, 134.0, 130.0, 139.0, 91.0, 87.0, 71.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 56.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 75.0, 75.0, 66.0, 68.0, 72.0, 71.0, 69.0, 75.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 83.0, 84.0, 85.0, 85.0, 84.0, 87.0, 82.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 138.0, 148.0, 161.0, 152.0, 145.0, 142.0, 188.0, 139.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 174.0, 163.0, 163.0, 164.0, 164.0, 162.0, 197.0, 227.0, 169.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 68.0, 66.0, 67.0, 67.0, 77.0, 84.0, 83.0], "value": 71.0, "unit": "score"}]}]} 06:08:17 INFO - Return code: 0 06:08:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:08:17 ERROR - # TBPL SUCCESS # 06:08:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:17 INFO - 2017-02-17 06:08:17.319 screenresolution[1658:10610] starting screenresolution argv=screenresolution get 06:08:17 INFO - 2017-02-17 06:08:17.323 screenresolution[1658:10610] Display 0: 1600x1200x32@60 06:08:17 INFO - 2017-02-17 06:08:17.329 screenresolution[1659:10613] starting screenresolution argv=screenresolution list 06:08:17 INFO - Available Modes on Display 0 06:08:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:17 INFO - Intel Iris: 06:08:17 INFO - Chipset Model: Intel Iris 06:08:17 INFO - Type: GPU 06:08:17 INFO - Bus: Built-In 06:08:17 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:17 INFO - Vendor: Intel (0x8086) 06:08:17 INFO - Device ID: 0x0a2e 06:08:17 INFO - Revision ID: 0x0009 06:08:17 INFO - Displays: 06:08:17 INFO - Display: 06:08:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:17 INFO - Display Serial Number: 1600x1200 60 06:08:17 INFO - Main Display: Yes 06:08:17 INFO - Mirror: Off 06:08:17 INFO - Online: Yes 06:08:17 INFO - Rotation: Supported 06:08:17 INFO - Return code: 0 06:08:17 INFO - Running post-action listener: _resource_record_post_action 06:08:17 INFO - [mozharness: 2017-02-17 14:08:17.568676Z] Finished run-tests step (success) 06:08:17 INFO - Running post-run listener: _resource_record_post_run 06:08:17 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 40687616; Write bytes: 502830592; Read time: 387; Write time: 1557 06:08:17 INFO - TinderboxPrint: CPU usage
54.2% 06:08:17 INFO - TinderboxPrint: I/O read bytes / time
40,687,616 / 387 06:08:17 INFO - TinderboxPrint: I/O write bytes / time
502,830,592 / 1,557 06:08:17 INFO - TinderboxPrint: CPU idle
2,196.0 (45.8%) 06:08:17 INFO - TinderboxPrint: CPU system
963.9 (20.1%) 06:08:17 INFO - TinderboxPrint: CPU user
1,633.7 (34.1%) 06:08:17 INFO - TinderboxPrint: Swap in / out
232,538,112 / 0 06:08:17 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 183554560; Write bytes: 176506368; Read time: 16942; Write time: 379 06:08:17 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 39745536; Write bytes: 308588544; Read time: 374; Write time: 1126 06:08:18 INFO - Running post-run listener: _upload_blobber_files 06:08:18 INFO - Blob upload gear active. 06:08:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:18 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 06:08:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:08:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:08:18 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:08:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:08:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:19 INFO - (blobuploader) - INFO - Done attempting. 06:08:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:08:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:08:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:20 INFO - (blobuploader) - INFO - Done attempting. 06:08:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:08:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:08:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:22 INFO - (blobuploader) - INFO - Done attempting. 06:08:22 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:22 INFO - Return code: 0 06:08:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60fc56eadec10b1d1bbb7a5d933dd8ba34ab5cb2944ac2bc482f6a1d56c93bb570b3415cf2edcded18b43d074901f1bda7944bf03320d8d1387e8cd8a8510677", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44c343e7413d006a5c6199c98a68cda40e625584c3abc3da58364db9e3a37c5c52ee144d06da5f17e653b894376f1092c12d28f8f29b3c7e1766f789a40a53e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa56a4eb41d451e248e8263a317ed5fb9b4240d46ed329b9b844ea0f5fdf8676818442f336dfeb20a4011e91ed1beab0871ddaba375ebcf130f94b662895f997"} 06:08:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:22 INFO - Contents: 06:08:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60fc56eadec10b1d1bbb7a5d933dd8ba34ab5cb2944ac2bc482f6a1d56c93bb570b3415cf2edcded18b43d074901f1bda7944bf03320d8d1387e8cd8a8510677", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44c343e7413d006a5c6199c98a68cda40e625584c3abc3da58364db9e3a37c5c52ee144d06da5f17e653b894376f1092c12d28f8f29b3c7e1766f789a40a53e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa56a4eb41d451e248e8263a317ed5fb9b4240d46ed329b9b844ea0f5fdf8676818442f336dfeb20a4011e91ed1beab0871ddaba375ebcf130f94b662895f997"} 06:08:22 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:22 INFO - Copying logs to upload dir... 06:08:22 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.275478 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-17 06:08:22.952527) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 06:08:22.956535) ========= 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.hQQPSKBR0s/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.5FtN3etZMd/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60fc56eadec10b1d1bbb7a5d933dd8ba34ab5cb2944ac2bc482f6a1d56c93bb570b3415cf2edcded18b43d074901f1bda7944bf03320d8d1387e8cd8a8510677", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44c343e7413d006a5c6199c98a68cda40e625584c3abc3da58364db9e3a37c5c52ee144d06da5f17e653b894376f1092c12d28f8f29b3c7e1766f789a40a53e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa56a4eb41d451e248e8263a317ed5fb9b4240d46ed329b9b844ea0f5fdf8676818442f336dfeb20a4011e91ed1beab0871ddaba375ebcf130f94b662895f997"} build_url:https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/E1Qg3e8vQeKzJKnGkLgP5w/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60fc56eadec10b1d1bbb7a5d933dd8ba34ab5cb2944ac2bc482f6a1d56c93bb570b3415cf2edcded18b43d074901f1bda7944bf03320d8d1387e8cd8a8510677", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44c343e7413d006a5c6199c98a68cda40e625584c3abc3da58364db9e3a37c5c52ee144d06da5f17e653b894376f1092c12d28f8f29b3c7e1766f789a40a53e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa56a4eb41d451e248e8263a317ed5fb9b4240d46ed329b9b844ea0f5fdf8676818442f336dfeb20a4011e91ed1beab0871ddaba375ebcf130f94b662895f997"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 06:08:22.991255) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 06:08:22.991552) ========= 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.hQQPSKBR0s/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.5FtN3etZMd/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005369 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 06:08:23.039707) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 06:08:23.039987) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 06:08:23.040314) ========= ========= Total master_lag: 0.17 =========