builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0315 starttime: 1483438073.11 results: success (0) buildid: 20170103004005 builduid: e7a1886d3b184d44a10c22e9008b56f3 revision: a6f6cf4180980a74083cc03f611cc510eb15b5cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.107353) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.107908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.108215) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.135150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.135565) ========= 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.smtX91A9Qu/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.b6dZjhH069/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-01-03 02:07:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.3M=0s 2017-01-03 02:07:53 (29.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.173151 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.332959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.333243) ========= 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.smtX91A9Qu/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.b6dZjhH069/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.025011 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:53.381830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 02:07:53.382127) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a6f6cf4180980a74083cc03f611cc510eb15b5cc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a6f6cf4180980a74083cc03f611cc510eb15b5cc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.smtX91A9Qu/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.b6dZjhH069/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-01-03 02:07:53,481 truncating revision to first 12 chars 2017-01-03 02:07:53,481 Setting DEBUG logging. 2017-01-03 02:07:53,482 attempt 1/10 2017-01-03 02:07:53,482 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a6f6cf418098?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 02:07:54,246 unpacking tar archive at: mozilla-aurora-a6f6cf418098/testing/mozharness/ program finished with exit code 0 elapsedTime=1.039242 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 02:07:54.444496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:54.444762) ========= script_repo_revision: a6f6cf4180980a74083cc03f611cc510eb15b5cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:54.445145) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:54.445421) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 02:07:54.465089) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-03 02:07:54.465388) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.smtX91A9Qu/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.b6dZjhH069/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:07:54 INFO - MultiFileLogger online at 20170103 02:07:54 in /builds/slave/test 02:07:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:07:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:07:54 INFO - {'append_to_log': False, 02:07:54 INFO - 'base_work_dir': '/builds/slave/test', 02:07:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:07:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:07:54 INFO - 'branch': 'Mozilla-Aurora', 02:07:54 INFO - 'buildbot_json_path': 'buildprops.json', 02:07:54 INFO - 'config_files': ('talos/mac_config.py',), 02:07:54 INFO - 'default_actions': ('clobber', 02:07:54 INFO - 'read-buildbot-config', 02:07:54 INFO - 'download-and-extract', 02:07:54 INFO - 'populate-webroot', 02:07:54 INFO - 'create-virtualenv', 02:07:54 INFO - 'install', 02:07:54 INFO - 'run-tests'), 02:07:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:07:54 INFO - 'download_minidump_stackwalk': True, 02:07:54 INFO - 'download_symbols': 'ondemand', 02:07:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:07:54 INFO - 'tooltool.py': '/tools/tooltool.py', 02:07:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:07:54 INFO - '/tools/misc-python/virtualenv.py')}, 02:07:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:07:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:07:54 INFO - 'installer_path': 'installer.exe', 02:07:54 INFO - 'log_level': 'info', 02:07:54 INFO - 'log_name': 'talos', 02:07:54 INFO - 'log_to_console': True, 02:07:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:07:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:07:54 INFO - 'opt_config_files': (), 02:07:54 INFO - 'pip_index': False, 02:07:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:07:54 INFO - 'cmd': ('bash', 02:07:54 INFO - '-c', 02:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:07:54 INFO - 'enabled': True, 02:07:54 INFO - 'halt_on_failure': False, 02:07:54 INFO - 'name': 'check_screen_resolution'},), 02:07:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:07:54 INFO - 'cmd': ('bash', 02:07:54 INFO - '-c', 02:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:07:54 INFO - 'enabled': True, 02:07:54 INFO - 'halt_on_failure': False, 02:07:54 INFO - 'name': 'check_screen_resolution'},), 02:07:54 INFO - 'run_cmd_checks_enabled': True, 02:07:54 INFO - 'sps_profile': False, 02:07:54 INFO - 'sps_profile_interval': 0, 02:07:54 INFO - 'suite': 'dromaeojs-e10s', 02:07:54 INFO - 'system_bits': '32', 02:07:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:07:54 INFO - 'title': 't-yosemite-r7-0315', 02:07:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:07:54 INFO - 'use_talos_json': True, 02:07:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:07:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:07:54 INFO - 'work_dir': 'build'} 02:07:54 INFO - [mozharness: 2017-01-03 10:07:54.674243Z] Running clobber step. 02:07:54 INFO - Running pre-action listener: _resource_record_pre_action 02:07:54 INFO - Running main action method: clobber 02:07:54 INFO - rmtree: /builds/slave/test/build 02:07:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:07:56 INFO - Running post-action listener: _resource_record_post_action 02:07:56 INFO - [mozharness: 2017-01-03 10:07:56.964449Z] Finished clobber step (success) 02:07:56 INFO - [mozharness: 2017-01-03 10:07:56.964608Z] Running read-buildbot-config step. 02:07:56 INFO - Running pre-action listener: _resource_record_pre_action 02:07:56 INFO - Running main action method: read_buildbot_config 02:07:56 INFO - Using buildbot properties: 02:07:56 INFO - { 02:07:56 INFO - "project": "", 02:07:56 INFO - "product": "firefox", 02:07:56 INFO - "script_repo_revision": "production", 02:07:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:07:56 INFO - "repository": "", 02:07:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:07:56 INFO - "buildid": "20170103004005", 02:07:56 INFO - "stage_platform": "macosx64", 02:07:56 INFO - "basedir": "/builds/slave/test", 02:07:56 INFO - "nightly_build": "True", 02:07:56 INFO - "buildnumber": 57, 02:07:56 INFO - "slavename": "t-yosemite-r7-0315", 02:07:56 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:07:56 INFO - "platform": "yosemite_r7", 02:07:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:07:56 INFO - "branch": "mozilla-aurora", 02:07:56 INFO - "repo_path": "releases/mozilla-aurora", 02:07:56 INFO - "revision": "a6f6cf4180980a74083cc03f611cc510eb15b5cc", 02:07:56 INFO - "pgo_build": "False", 02:07:56 INFO - "builduid": "e7a1886d3b184d44a10c22e9008b56f3", 02:07:56 INFO - "slavebuilddir": "test" 02:07:56 INFO - } 02:07:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:07:56 INFO - Running post-action listener: _resource_record_post_action 02:07:56 INFO - [mozharness: 2017-01-03 10:07:56.967965Z] Finished read-buildbot-config step (success) 02:07:56 INFO - [mozharness: 2017-01-03 10:07:56.968071Z] Running download-and-extract step. 02:07:56 INFO - Running pre-action listener: _resource_record_pre_action 02:07:56 INFO - Running main action method: download_and_extract 02:07:56 INFO - mkdir: /builds/slave/test/build/tests 02:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:07:56 INFO - trying https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:07:56 INFO - Downloading https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:07:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:07:58 INFO - Downloaded 1199 bytes. 02:07:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:07:58 INFO - Using the following test package requirements: 02:07:58 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:07:58 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:07:58 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:07:58 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'jsshell-mac.zip'], 02:07:58 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:07:58 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:07:58 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:07:58 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:07:58 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:07:58 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:07:58 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:07:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:07:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:07:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:07:58 INFO - Fetch https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:07:58 INFO - Http code: 200 02:07:58 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 02:07:58 INFO - x-amz-cf-id: EPLxte4wf1IDBRDZg8ImJGc76KkLH-uQLfjla7aYkdzvatf__gRWMQ== 02:07:58 INFO - x-amz-version-id: _EZIH2AEY4XTiruK7maqGkcOUxPUblVc 02:07:59 INFO - Expected file size: 16268435 02:07:59 INFO - Obtained file size: 16268435 02:08:05 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:08:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:08:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:05 INFO - Fetch https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:08:06 INFO - Http code: 200 02:08:06 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 02:08:06 INFO - x-amz-cf-id: xfR1sWfw99BvwtonvyXVPQb7IxN4qCeH1XZr5cqPoMBuw5CtiF8SUA== 02:08:06 INFO - x-amz-version-id: _EZIH2AEY4XTiruK7maqGkcOUxPUblVc 02:08:06 INFO - Expected file size: 16268435 02:08:06 INFO - Obtained file size: 16268435 02:08:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:08:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:08:13 INFO - Fetch https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:08:14 INFO - Http code: 200 02:08:14 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 02:08:14 INFO - x-amz-cf-id: sXPCd904UfNcJTrUo_da6TSUoGm0WUkSEYrsW0Gs_oWffle3EQKMjg== 02:08:14 INFO - x-amz-version-id: m9oed_5UG0hcUvWtymm_pYc6qr4em7TN 02:08:14 INFO - Expected file size: 14409038 02:08:14 INFO - Obtained file size: 14409038 02:08:14 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')]} 02:08:14 INFO - trying https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:08:14 INFO - Downloading https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:08:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:08:20 INFO - Downloaded 109941385 bytes. 02:08:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:08:20 INFO - mkdir: /builds/slave/test/properties 02:08:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:08:20 INFO - Writing to file /builds/slave/test/properties/build_url 02:08:20 INFO - Contents: 02:08:20 INFO - build_url:https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:08:21 INFO - Running post-action listener: _resource_record_post_action 02:08:21 INFO - Running post-action listener: set_extra_try_arguments 02:08:21 INFO - [mozharness: 2017-01-03 10:08:21.321926Z] Finished download-and-extract step (success) 02:08:21 INFO - [mozharness: 2017-01-03 10:08:21.322075Z] Running populate-webroot step. 02:08:21 INFO - Running pre-action listener: _resource_record_pre_action 02:08:21 INFO - Running main action method: populate_webroot 02:08:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:08:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:08:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:08:21 INFO - '--tppagecycles', 02:08:21 INFO - '7'], 02:08:21 INFO - 'tests': ['tsvgm']}}, 02:08:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:08:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:08:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:08:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:08:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['damp', 'tps']}, 02:08:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'tests': ['damp', 'tps']}, 02:08:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['dromaeo_dom']}, 02:08:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:08:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['basic_compositor_video']}, 02:08:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:08:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['a11yr', 02:08:21 INFO - 'ts_paint', 02:08:21 INFO - 'tpaint', 02:08:21 INFO - 'sessionrestore', 02:08:21 INFO - 'sessionrestore_no_auto_restore', 02:08:21 INFO - 'tabpaint']}, 02:08:21 INFO - 'other-e10s': {'tests': ['a11yr', 02:08:21 INFO - 'ts_paint', 02:08:21 INFO - 'tpaint', 02:08:21 INFO - 'sessionrestore', 02:08:21 INFO - 'sessionrestore_no_auto_restore', 02:08:21 INFO - 'tabpaint']}, 02:08:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:08:21 INFO - 'ts_paint', 02:08:21 INFO - 'tpaint', 02:08:21 INFO - 'sessionrestore', 02:08:21 INFO - 'sessionrestore_no_auto_restore', 02:08:21 INFO - 'tabpaint']}, 02:08:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:08:21 INFO - 'ts_paint', 02:08:21 INFO - 'tpaint', 02:08:21 INFO - 'sessionrestore', 02:08:21 INFO - 'sessionrestore_no_auto_restore', 02:08:21 INFO - 'tabpaint']}, 02:08:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['a11yr', 02:08:21 INFO - 'ts_paint', 02:08:21 INFO - 'tpaint', 02:08:21 INFO - 'sessionrestore', 02:08:21 INFO - 'sessionrestore_no_auto_restore', 02:08:21 INFO - 'tabpaint']}, 02:08:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['a11yr', 02:08:21 INFO - 'ts_paint', 02:08:21 INFO - 'tpaint', 02:08:21 INFO - 'sessionrestore', 02:08:21 INFO - 'sessionrestore_no_auto_restore', 02:08:21 INFO - 'tabpaint']}, 02:08:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['tsvgx', 02:08:21 INFO - 'tsvgr_opacity', 02:08:21 INFO - 'tart', 02:08:21 INFO - 'tscrollx', 02:08:21 INFO - 'cart']}, 02:08:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:08:21 INFO - 'tsvgr_opacity', 02:08:21 INFO - 'tart', 02:08:21 INFO - 'tscrollx', 02:08:21 INFO - 'cart']}, 02:08:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'talos_options': ['--disable-e10s'], 02:08:21 INFO - 'tests': ['tp5o']}, 02:08:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'tests': ['tp5o']}, 02:08:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'talos_options': ['--disable-e10s', 02:08:21 INFO - '--xperf_path', 02:08:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:21 INFO - 'tests': ['tp5n']}, 02:08:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:21 INFO - 'talos_options': ['--xperf_path', 02:08:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:21 INFO - 'tests': ['tp5n']}}, 02:08:21 INFO - 'talos.zip': {'path': '', 02:08:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:08:21 INFO - Running post-action listener: _resource_record_post_action 02:08:21 INFO - [mozharness: 2017-01-03 10:08:21.341219Z] Finished populate-webroot step (success) 02:08:21 INFO - [mozharness: 2017-01-03 10:08:21.341331Z] Running create-virtualenv step. 02:08:21 INFO - Running pre-action listener: _resource_record_pre_action 02:08:21 INFO - Running main action method: create_virtualenv 02:08:21 INFO - Creating virtualenv /builds/slave/test/build/venv 02:08:21 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 02:08:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:08:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:08:21 INFO - Using real prefix '/tools/python27' 02:08:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:08:22 INFO - Installing distribute.............................................................................................................................................................................................done. 02:08:25 INFO - Installing pip.................done. 02:08:25 INFO - Return code: 0 02:08:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:08:25 INFO - Reading from file tmpfile_stdout 02:08:25 INFO - Output received: 02:08:25 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) 02:08:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:08: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')]} 02:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08: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')]} 02:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:25 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:25 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 02:08:25 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 02:08:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:25 INFO - 'HOME': '/Users/cltbld', 02:08:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:25 INFO - 'LOGNAME': 'cltbld', 02:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:25 INFO - 'MOZ_NO_REMOTE': '1', 02:08:25 INFO - 'NO_EM_RESTART': '1', 02:08:25 INFO - 'PAGER': '/bin/cat', 02:08:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:25 INFO - 'PWD': '/builds/slave/test', 02:08:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:25 INFO - 'SHELL': '/bin/bash', 02:08:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:25 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:25 INFO - 'USER': 'cltbld', 02:08:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:25 INFO - 'XPC_FLAGS': '0x0', 02:08:25 INFO - 'XPC_SERVICE_NAME': '0', 02:08:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:25 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 02:08:25 INFO - Cleaning up... 02:08:25 INFO - Return code: 0 02:08:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:08: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')]} 02:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08: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')]} 02:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:25 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:25 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 02:08:25 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 02:08:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:25 INFO - 'HOME': '/Users/cltbld', 02:08:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:25 INFO - 'LOGNAME': 'cltbld', 02:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:25 INFO - 'MOZ_NO_REMOTE': '1', 02:08:25 INFO - 'NO_EM_RESTART': '1', 02:08:25 INFO - 'PAGER': '/bin/cat', 02:08:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:25 INFO - 'PWD': '/builds/slave/test', 02:08:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:25 INFO - 'SHELL': '/bin/bash', 02:08:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:25 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:25 INFO - 'USER': 'cltbld', 02:08:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:25 INFO - 'XPC_FLAGS': '0x0', 02:08:25 INFO - 'XPC_SERVICE_NAME': '0', 02:08:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:25 INFO - Downloading/unpacking psutil>=3.1.1 02:08:25 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 02:08:25 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 02:08:25 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 02:08:25 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 02:08:25 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 02:08:25 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 02:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:08:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:30 INFO - Installing collected packages: psutil 02:08:30 INFO - Running setup.py install for psutil 02:08:30 INFO - building 'psutil._psutil_osx' extension 02:08:30 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 02:08:30 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 02:08:30 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 02:08:30 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 02:08:30 INFO - building 'psutil._psutil_posix' extension 02:08:30 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 02:08:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:08:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:08:30 INFO - ^ 02:08:31 INFO - 1 warning generated. 02:08:31 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 02:08:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:31 INFO - Successfully installed psutil 02:08:31 INFO - Cleaning up... 02:08:31 INFO - Return code: 0 02:08:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:08:31 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')]} 02:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:31 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')]} 02:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:31 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:31 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 02:08:31 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 02:08:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:31 INFO - 'HOME': '/Users/cltbld', 02:08:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:31 INFO - 'LOGNAME': 'cltbld', 02:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:31 INFO - 'MOZ_NO_REMOTE': '1', 02:08:31 INFO - 'NO_EM_RESTART': '1', 02:08:31 INFO - 'PAGER': '/bin/cat', 02:08:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:31 INFO - 'PWD': '/builds/slave/test', 02:08:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:31 INFO - 'SHELL': '/bin/bash', 02:08:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:31 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:31 INFO - 'USER': 'cltbld', 02:08:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:31 INFO - 'XPC_FLAGS': '0x0', 02:08:31 INFO - 'XPC_SERVICE_NAME': '0', 02:08:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:08:31 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 02:08:31 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 02:08:31 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 02:08:31 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 02:08:31 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 02:08:31 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 02:08:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:08:34 INFO - Installing collected packages: mozsystemmonitor 02:08:34 INFO - Running setup.py install for mozsystemmonitor 02:08:34 INFO - Successfully installed mozsystemmonitor 02:08:34 INFO - Cleaning up... 02:08:34 INFO - Return code: 0 02:08:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:34 INFO - 'HOME': '/Users/cltbld', 02:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:34 INFO - 'LOGNAME': 'cltbld', 02:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:34 INFO - 'MOZ_NO_REMOTE': '1', 02:08:34 INFO - 'NO_EM_RESTART': '1', 02:08:34 INFO - 'PAGER': '/bin/cat', 02:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:34 INFO - 'PWD': '/builds/slave/test', 02:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:34 INFO - 'SHELL': '/bin/bash', 02:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:34 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:34 INFO - 'USER': 'cltbld', 02:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:34 INFO - 'XPC_FLAGS': '0x0', 02:08:34 INFO - 'XPC_SERVICE_NAME': '0', 02:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:34 INFO - Downloading/unpacking jsonschema==2.5.1 02:08:34 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 02:08:34 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 02:08:34 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 02:08:34 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 02:08:34 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 02:08:34 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 02:08:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:08:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:08:37 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 02:08:37 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 02:08:37 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 02:08:37 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 02:08:37 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 02:08:37 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 02:08:37 INFO - Downloading functools32-3.2.3-2.tar.gz 02:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:08:37 INFO - warning: no files found matching '*.txt' 02:08:37 INFO - no previously-included directories found matching 'build' 02:08:37 INFO - no previously-included directories found matching 'dist' 02:08:37 INFO - no previously-included directories found matching '.git*' 02:08:37 INFO - Installing collected packages: jsonschema, functools32 02:08:37 INFO - Running setup.py install for functools32 02:08:38 INFO - warning: no files found matching '*.txt' 02:08:38 INFO - no previously-included directories found matching 'build' 02:08:38 INFO - no previously-included directories found matching 'dist' 02:08:38 INFO - no previously-included directories found matching '.git*' 02:08:38 INFO - Successfully installed jsonschema functools32 02:08:38 INFO - Cleaning up... 02:08:38 INFO - Return code: 0 02:08:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:08:38 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')]} 02:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:38 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')]} 02:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:38 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:38 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 02:08:38 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 02:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:38 INFO - 'HOME': '/Users/cltbld', 02:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:38 INFO - 'LOGNAME': 'cltbld', 02:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:38 INFO - 'MOZ_NO_REMOTE': '1', 02:08:38 INFO - 'NO_EM_RESTART': '1', 02:08:38 INFO - 'PAGER': '/bin/cat', 02:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:38 INFO - 'PWD': '/builds/slave/test', 02:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:38 INFO - 'SHELL': '/bin/bash', 02:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:38 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:38 INFO - 'USER': 'cltbld', 02:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:38 INFO - 'XPC_FLAGS': '0x0', 02:08:38 INFO - 'XPC_SERVICE_NAME': '0', 02:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:08:38 INFO - Cleaning up... 02:08:38 INFO - Return code: 0 02:08:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:08:38 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')]} 02:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:38 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')]} 02:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:38 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:38 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 02:08:38 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 02:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:38 INFO - 'HOME': '/Users/cltbld', 02:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:38 INFO - 'LOGNAME': 'cltbld', 02:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:38 INFO - 'MOZ_NO_REMOTE': '1', 02:08:38 INFO - 'NO_EM_RESTART': '1', 02:08:38 INFO - 'PAGER': '/bin/cat', 02:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:38 INFO - 'PWD': '/builds/slave/test', 02:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:38 INFO - 'SHELL': '/bin/bash', 02:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:38 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:38 INFO - 'USER': 'cltbld', 02:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:38 INFO - 'XPC_FLAGS': '0x0', 02:08:38 INFO - 'XPC_SERVICE_NAME': '0', 02:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:38 INFO - Downloading/unpacking blobuploader==1.2.4 02:08:38 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 02:08:38 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 02:08:38 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 02:08:38 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 02:08:38 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 02:08:38 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 02:08:41 INFO - Downloading blobuploader-1.2.4.tar.gz 02:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:08:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:08:41 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 02:08:41 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 02:08:41 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 02:08:41 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 02:08:41 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 02:08:41 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 02:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:08:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 INFO - Downloading docopt-0.6.1.tar.gz 02:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:08:42 INFO - Installing collected packages: blobuploader, requests, docopt 02:08:42 INFO - Running setup.py install for blobuploader 02:08:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:08:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:08:42 INFO - Running setup.py install for requests 02:08:43 INFO - Running setup.py install for docopt 02:08:43 INFO - Successfully installed blobuploader requests docopt 02:08:43 INFO - Cleaning up... 02:08:43 INFO - Return code: 0 02:08:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:43 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:43 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 02:08:43 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 02:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:43 INFO - 'HOME': '/Users/cltbld', 02:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:43 INFO - 'LOGNAME': 'cltbld', 02:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:43 INFO - 'MOZ_NO_REMOTE': '1', 02:08:43 INFO - 'NO_EM_RESTART': '1', 02:08:43 INFO - 'PAGER': '/bin/cat', 02:08:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:43 INFO - 'PWD': '/builds/slave/test', 02:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:43 INFO - 'SHELL': '/bin/bash', 02:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:43 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:43 INFO - 'USER': 'cltbld', 02:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:43 INFO - 'XPC_FLAGS': '0x0', 02:08:43 INFO - 'XPC_SERVICE_NAME': '0', 02:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:43 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-4bJ3dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:43 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-JVvLn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-w4HVk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-qPrTSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-lzY_zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-Ge0wPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-yNGOPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-hu9uA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-EliSMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-mj2lg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-dMK8P2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-fUqQsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-1V4uHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-JqInaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-j_41UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-jSl_Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-O4Shsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:08:45 INFO - Running setup.py install for manifestparser 02:08:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:08:45 INFO - Running setup.py install for mozcrash 02:08:45 INFO - Running setup.py install for mozdebug 02:08:45 INFO - Running setup.py install for mozdevice 02:08:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Running setup.py install for mozfile 02:08:46 INFO - Running setup.py install for mozhttpd 02:08:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Running setup.py install for mozinfo 02:08:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Running setup.py install for mozInstall 02:08:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:08:46 INFO - Running setup.py install for mozleak 02:08:46 INFO - Running setup.py install for mozlog 02:08:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:08:47 INFO - Running setup.py install for moznetwork 02:08:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:08:47 INFO - Running setup.py install for mozprocess 02:08:47 INFO - Running setup.py install for mozprofile 02:08:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:08:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:08:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:08:47 INFO - Running setup.py install for mozrunner 02:08:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:08:47 INFO - Running setup.py install for mozscreenshot 02:08:47 INFO - Running setup.py install for moztest 02:08:47 INFO - Running setup.py install for mozversion 02:08:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:08:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:08:48 INFO - Cleaning up... 02:08:48 INFO - Return code: 0 02:08:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:48 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')]} 02:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:48 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')]} 02:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:48 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:48 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 02:08:48 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 02:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:48 INFO - 'HOME': '/Users/cltbld', 02:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:48 INFO - 'LOGNAME': 'cltbld', 02:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:48 INFO - 'MOZ_NO_REMOTE': '1', 02:08:48 INFO - 'NO_EM_RESTART': '1', 02:08:48 INFO - 'PAGER': '/bin/cat', 02:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:48 INFO - 'PWD': '/builds/slave/test', 02:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:48 INFO - 'SHELL': '/bin/bash', 02:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:48 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:48 INFO - 'USER': 'cltbld', 02:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:48 INFO - 'XPC_FLAGS': '0x0', 02:08:48 INFO - 'XPC_SERVICE_NAME': '0', 02:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-EuA0M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:48 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)) 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-VR308E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:48 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)) 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-r00gkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:48 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)) 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-KQDIRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:48 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)) 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-epI_mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:48 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)) 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-yOPC98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-DIRwDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-P5guGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-1oh6iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-VNSOMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-AmMkTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-FNiK9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-hxpiH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-ZkedfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-oK98Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:49 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)) 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:50 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-LcSI3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:50 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-6sqzMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:50 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)) 02:08:50 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)) 02:08:50 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)) 02:08:50 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)) 02:08:50 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)) 02:08:50 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)) 02:08:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08:52 INFO - Downloading blessings-1.6.tar.gz 02:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:08:52 INFO - Installing collected packages: blessings 02:08:52 INFO - Running setup.py install for blessings 02:08:53 INFO - Successfully installed blessings 02:08:53 INFO - Cleaning up... 02:08:53 INFO - Return code: 0 02:08:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:08:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:08:53 INFO - Reading from file tmpfile_stdout 02:08:53 INFO - Current package versions: 02:08:53 INFO - blessings == 1.6 02:08:53 INFO - blobuploader == 1.2.4 02:08:53 INFO - docopt == 0.6.1 02:08:53 INFO - functools32 == 3.2.3-2 02:08:53 INFO - jsonschema == 2.5.1 02:08:53 INFO - manifestparser == 1.1 02:08:53 INFO - mozInstall == 1.12 02:08:53 INFO - mozcrash == 1.0 02:08:53 INFO - mozdebug == 0.1 02:08:53 INFO - mozdevice == 0.48 02:08:53 INFO - mozfile == 1.2 02:08:53 INFO - mozhttpd == 0.7 02:08:53 INFO - mozinfo == 0.9 02:08:53 INFO - mozleak == 0.1 02:08:53 INFO - mozlog == 3.4 02:08:53 INFO - moznetwork == 0.27 02:08:53 INFO - mozprocess == 0.23 02:08:53 INFO - mozprofile == 0.28 02:08:53 INFO - mozrunner == 6.13 02:08:53 INFO - mozscreenshot == 0.1 02:08:53 INFO - mozsystemmonitor == 0.3 02:08:53 INFO - moztest == 0.7 02:08:53 INFO - mozversion == 1.4 02:08:53 INFO - psutil == 3.1.1 02:08:53 INFO - requests == 1.2.3 02:08:53 INFO - wsgiref == 0.1.2 02:08:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:53 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:53 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 02:08:53 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 02:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:53 INFO - 'HOME': '/Users/cltbld', 02:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:53 INFO - 'LOGNAME': 'cltbld', 02:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:53 INFO - 'MOZ_NO_REMOTE': '1', 02:08:53 INFO - 'NO_EM_RESTART': '1', 02:08:53 INFO - 'PAGER': '/bin/cat', 02:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:53 INFO - 'PWD': '/builds/slave/test', 02:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:53 INFO - 'SHELL': '/bin/bash', 02:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:53 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:53 INFO - 'USER': 'cltbld', 02:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:53 INFO - 'XPC_FLAGS': '0x0', 02:08:53 INFO - 'XPC_SERVICE_NAME': '0', 02:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 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)) 02:08:53 INFO - Cleaning up... 02:08:53 INFO - Return code: 0 02:08:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:53 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 0x10828b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8d9d63790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, '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.smtX91A9Qu/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.b6dZjhH069/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 02:08:53 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 02:08:53 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 02:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:53 INFO - 'HOME': '/Users/cltbld', 02:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:53 INFO - 'LOGNAME': 'cltbld', 02:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:53 INFO - 'MOZ_NO_REMOTE': '1', 02:08:53 INFO - 'NO_EM_RESTART': '1', 02:08:53 INFO - 'PAGER': '/bin/cat', 02:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:53 INFO - 'PWD': '/builds/slave/test', 02:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:53 INFO - 'SHELL': '/bin/bash', 02:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:08:53 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:53 INFO - 'USER': 'cltbld', 02:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:53 INFO - 'XPC_FLAGS': '0x0', 02:08:53 INFO - 'XPC_SERVICE_NAME': '0', 02:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:08:54 INFO - Cleaning up... 02:08:54 INFO - Return code: 0 02:08:54 INFO - Running post-action listener: _resource_record_post_action 02:08:54 INFO - Running post-action listener: _start_resource_monitoring 02:08:54 INFO - Starting resource monitoring. 02:08:54 INFO - [mozharness: 2017-01-03 10:08:54.056933Z] Finished create-virtualenv step (success) 02:08:54 INFO - [mozharness: 2017-01-03 10:08:54.057918Z] Running install step. 02:08:54 INFO - Running pre-action listener: _resource_record_pre_action 02:08:54 INFO - Running main action method: install 02:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:08:54 INFO - Reading from file tmpfile_stdout 02:08:54 INFO - Detecting whether we're running mozinstall >=1.0... 02:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:08:54 INFO - Reading from file tmpfile_stdout 02:08:54 INFO - Output received: 02:08:54 INFO - Usage: mozinstall [options] installer 02:08:54 INFO - Options: 02:08:54 INFO - -h, --help show this help message and exit 02:08:54 INFO - -d DEST, --destination=DEST 02:08:54 INFO - Directory to install application into. [default: 02:08:54 INFO - "/builds/slave/test"] 02:08:54 INFO - --app=APP Application being installed. [default: firefox] 02:08:54 INFO - mkdir: /builds/slave/test/build/application 02:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:09:26 INFO - Reading from file tmpfile_stdout 02:09:26 INFO - Output received: 02:09:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:09:26 INFO - Running post-action listener: _resource_record_post_action 02:09:26 INFO - [mozharness: 2017-01-03 10:09:26.244970Z] Finished install step (success) 02:09:26 INFO - [mozharness: 2017-01-03 10:09:26.245131Z] Running run-tests step. 02:09:26 INFO - Running pre-action listener: _resource_record_pre_action 02:09:26 INFO - Running main action method: run_tests 02:09:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:09:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:09:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:09:26 INFO - 2017-01-03 02:09:26.259 screenresolution[1631:8309] starting screenresolution argv=screenresolution get 02:09:26 INFO - 2017-01-03 02:09:26.262 screenresolution[1631:8309] Display 0: 1600x1200x32@60 02:09:26 INFO - 2017-01-03 02:09:26.267 screenresolution[1632:8312] starting screenresolution argv=screenresolution list 02:09:26 INFO - Available Modes on Display 0 02:09:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:09:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:09:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:09:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:09:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:09:26 INFO - Intel Iris: 02:09:26 INFO - Chipset Model: Intel Iris 02:09:26 INFO - Type: GPU 02:09:26 INFO - Bus: Built-In 02:09:26 INFO - VRAM (Dynamic, Max): 1536 MB 02:09:26 INFO - Vendor: Intel (0x8086) 02:09:26 INFO - Device ID: 0x0a2e 02:09:26 INFO - Revision ID: 0x0009 02:09:26 INFO - Displays: 02:09:26 INFO - Display: 02:09:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:09:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:09:26 INFO - Display Serial Number: 1600x1200 60 02:09:26 INFO - Main Display: Yes 02:09:26 INFO - Mirror: Off 02:09:26 INFO - Online: Yes 02:09:26 INFO - Rotation: Supported 02:09:26 INFO - Return code: 0 02:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:09:26 INFO - Python 2.7.3 02:09:26 INFO - Return code: 0 02:09:26 INFO - grabbing minidump binary from tooltool 02:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:26 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 0x10809fd30>, '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 0x107f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108097b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:09:26 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 02:09:26 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 02:09:26 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 02:09:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:09:26 INFO - Return code: 0 02:09:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:09:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:09:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:09:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:09:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 02:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0315 --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 02:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smtX91A9Qu/Render', 02:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:26 INFO - 'HOME': '/Users/cltbld', 02:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:26 INFO - 'LOGNAME': 'cltbld', 02:09:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:09:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:26 INFO - 'MOZ_NO_REMOTE': '1', 02:09:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:09:26 INFO - 'NO_EM_RESTART': '1', 02:09:26 INFO - 'PAGER': '/bin/cat', 02:09:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:26 INFO - 'PWD': '/builds/slave/test', 02:09:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:26 INFO - 'SHELL': '/bin/bash', 02:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b6dZjhH069/Listeners', 02:09:26 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 02:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:26 INFO - 'USER': 'cltbld', 02:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:26 INFO - 'XPC_FLAGS': '0x0', 02:09:26 INFO - 'XPC_SERVICE_NAME': '0', 02:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 02:09:26 INFO - mozversion application_buildid: 20170103004005 02:09:26 INFO - mozversion application_changeset: a6f6cf4180980a74083cc03f611cc510eb15b5cc 02:09:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:09:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:09:26 INFO - mozversion application_name: Firefox 02:09:26 INFO - mozversion application_remotingname: firefox-dev 02:09:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:09:26 INFO - mozversion application_vendor: Mozilla 02:09:26 INFO - mozversion application_version: 52.0a2 02:09:26 INFO - mozversion platform_buildid: 20170103004005 02:09:26 INFO - mozversion platform_changeset: a6f6cf4180980a74083cc03f611cc510eb15b5cc 02:09:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:09:26 INFO - mozversion platform_version: 52.0a2 02:09:26 INFO - using testdate: 1483438166 02:09:26 INFO - actual date: 1483438166 02:09:26 INFO - starting webserver on 'localhost:49244' 02:09:26 INFO - SUITE-START | Running 2 tests 02:09:26 INFO - TEST-START | dromaeo_css 02:09:26 INFO - operating with platform_type : mac_ 02:09:26 INFO - Initialising browser for dromaeo_css test... 02:09:26 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmp1qwOqa/profile http://localhost:49244/getInfo.html) 02:09:30 INFO - PROCESS | 1642 | 2017-01-03 02:09:30.132 plugin-container[1646:8505] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 02:09:30 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:30 INFO - PROCESS | 1642 | 2017-01-03 02:09:30.133 plugin-container[1646:8505] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1f, name = 'com.apple.CFPasteboardClient' 02:09:30 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:30 INFO - PROCESS | 1642 | 2017-01-03 02:09:30.133 plugin-container[1646:8505] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:09:30 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 02:09:30 INFO - PROCESS | 1642 | colorDepth:24 02:09:30 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 02:09:30 INFO - PROCESS | 1642 | __metrics 02:09:30 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:09:31 INFO - TEST-INFO | 1642: exit 0 02:09:31 INFO - Browser initialized. 02:09:31 INFO - Running cycle 1/1 for dromaeo_css test... 02:09:31 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmp1qwOqa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:09:33 INFO - PROCESS | 1647 | 2017-01-03 02:09:33.989 plugin-container[1648:8635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 02:09:33 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:33 INFO - PROCESS | 1647 | 2017-01-03 02:09:33.989 plugin-container[1648:8635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.CFPasteboardClient' 02:09:33 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:33 INFO - PROCESS | 1647 | 2017-01-03 02:09:33.989 plugin-container[1648:8635] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:09:43 INFO - PROCESS | 1647 | RSS: Main: 229842944 02:09:43 INFO - PROCESS | 1647 | 02:10:33 INFO - PROCESS | 1647 | 1483438233298 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:12:47 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 02:12:49 INFO - PROCESS | 1647 | RSS: Main: 234352640 02:12:49 INFO - PROCESS | 1647 | 02:15:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 02:15:52 INFO - PROCESS | 1647 | RSS: Main: 243539968 02:15:52 INFO - PROCESS | 1647 | 02:18:54 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 02:18:54 INFO - PROCESS | 1647 | RSS: Main: 246370304 02:18:54 INFO - PROCESS | 1647 | 02:21:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 02:21:57 INFO - PROCESS | 1647 | RSS: Main: 245608448 02:21:57 INFO - PROCESS | 1647 | 02:24:59 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 02:25:00 INFO - PROCESS | 1647 | RSS: Main: 249950208 02:25:00 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 02:28:03 INFO - PROCESS | 1647 | RSS: Main: 251670528 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | __start_tp_report 02:28:03 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 02:28:03 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 02:28:03 INFO - PROCESS | 1647 | |i|pagename|runs| 02:28:03 INFO - PROCESS | 1647 | |0;dojo.html;2799.6920338762743;3136.2159460134967;3409.1136304560805;3427.5201471793953;3462.896821437835;5795.739191736371;5817.73820178092;5825.970870145643;5880.441358070987;5899;4680.368150299709;5620.932022915643;5809.2738407699035;5860.648361098337;5877.9412205877925;5239.554637855777;5319.707416092133;5321.760520776555;5329.786808527654;5347.893042139155;5350.892982140354;5367.973160134194;5369.087255166612;5381.596380271476;5416.945830541703;5275;5304.549113325372;5358.142697168454;5361.222622719703;5364.5708343332635;5294.232336311233;5344.118220493633;5352.330958630171;5366.356037275552;5369.221462887898;5217.399999000126;5292.1003429416705;5292.576593872481;5293.4971177738435;5331.573474122061;5826.766929322865;5893.17495550619;5908.379620139866;5916.911246331309;5918.348981612019;6108.755649774003;6178.474829639486;6243.531735119885;6297.118403423525;6300.369963003709;6257.061440783873;6278.121063051177;6293.1504246927;6297.244330680302;6301.212348456443;5921.970390148064;5948.940510594882;5983.132445795379;5987.161797348331;5999.340072592011;6285.622862628257;6332.651704156316;6375.521835862236;6393.808185754435;6411.871762564722;6226.844328891814;6268.091126786681;6280.811575652738;6281.8743625127245;6283.528735344776;6165.136880836686;6270.184875966095;6288.5598008139905;6337.397947195009;6351.618902865843;5010.599152067899;6198.380161983766;6232.688365581703;6236.688165591702;6254.781082662176;6216.067589861557;6299.149614801943;6301.96849015752;6309.810705678837;6312.274088479791;4970.254461830755;5018.071656743514;5018.99620075979;5023.974880125649;5027.4469808321055;4411.536788637211;4427.380166776654;4429.579189976947;4457.977710111493;4515.029268707243;4877.6341774367065;5738.938296415093;5873.823785286449;5973.148324019568;5976.760929562855;5163.948360516347;6260.217472815898;6284.308726040132;6291.591046581958;6301.212348456443;4772.117158325721;4907.877303067452;5055.747212639353;5951.940480595225;5965.926133296049;4143.7306575072525;5374.5700343972385;5479.397266300703;5484.588655850827;5494.09347457665;5115.718635475085;6205.069239614094;6218.129461875432;6222.937770622236;6283.842903927439;6271.78048768291;6280.811575652738;6287.905681414691;6302.149209856794;6302.653354065387;5202.531772140525;5724.803502139223;6345.302016778243;6350.174477317919;6358.618482891041;5548.501904485847;5996.310424301087;6121.361519882508;6170;6264.373562643699;5127.692338459704;6282.340354262919;6315.936840631535;6319.747210111728;6358.205224346957;4846.636502262274;6196.659183744757;6228.408301211378;6253.5622506424115;6255.906161407674;6058.303295121117;6113.263474096151;6259.684225735239;6262.968685156545;6278.058291256348;5979.4917432018765;6007.87984240304;6144.617461071223;6162.322144564005;6183.443490085914;5156.355455568054;6232.937670623236;6233.2208473940755;6247.250329960434;6267.310595834363;4628.2363410038315;6099.146119543178;6151.876962460815;6152.169457123231;6170.629762214281 02:28:03 INFO - PROCESS | 1647 | |1;ext.html;474.14653623477733;476.59013248606203;508.30362403507195;511.14893701986193;513.8150265904276;38251;39666.603333966654;39821.4026789598;41803.58196418035;41868;40537.59462405375;40772.18455630886;40914.59085409145;41048.38427423591;41071.76784696464;9253.814923701522;9375;9381.15569598736;9393.01373355798;9421.576029078673;9327.30045246606;9346.766330841716;9431.339806213567;9438.858417123714;9472.763180920463;7990.440669153161;9410.153086222239;9441.103095205945;9451.716448506555;9467.668631597895;9372.39079459833;9377.156055954963;9414.952925235399;9452.385594936375;9467.95266023669;9137.908620913839;9215.493147876865;9226.630934762601;9237.261019118454;9244.214241789456;38505.80747096275;38683.646072387615;38759.61240387616;38830;38944.02639933996;3311.2880730642787;3317.3696997570382;3409.232922592422;3422.418188907867;3803.9809800951093;28386.864525418958;29059.56410653842;29329.120126396243;29846.701532984825;30305.787768489234;2624.58006718926;3189.2983543620367;3202;3208.4385232584204;3296.126526470487;405799.8840023183;455413;480255;484671;485719.57140214427;61816.69091654513;61983.690081549306;62346.19442125115;62353.37646623566;62669.68665156645;63515.68242158852;64221.35778642247;64391.03413448802;64445.67777161178;64801.70396592042;16284.67430651404;16425.1787410629;16427.58931026734;16462.259198335865;16487.505374838773;11042.834357484644;12503.74992500145;12629.11596188258;12647.87352126467;12667.30329831868;17560.37057901792;18894.905525472284;18932.62134757297;18994.335198267996;19026.714599281022;6509.381608747161;6649.368310010541;6690.264070952191;7805.843883122419;7834.804129896798;698.3971784754002;967.2213867836459;987.4322264697828;992.0426788149471;1036.1400037968467;29056.709432905824;29376.41247175087;29751.107466776033;30293.091207263817;30861.537076943863;790.833924875771;969.7139343893568;1009.4649835587151;1032.808930347888;1094.3269889018356;14766.852331476763;15511.069335839886;15906.363745450284;16232.269547870137;16264.349426023064;663356;677666;679682;682345;682880;46683;56222.43777562172;56856;56979.715101425056;57515.71242143762;1210.667066556704;1388.5417812121975;1399.2723783632455;1430.9642258943818;1434.8278206615273;23753.52492950166;28473.430531388844;28796.568051478826;29356.266093347836;30045.248868778457;50440.49559504358;51003.489965101355;51075.2338714927;51189.232161518354;51280;629237;635658;636884;637265;637655;38470;41472;41919.74240772782;42236;42290.154196915275;44761.10477790361;45335.77332113318;45528.54471455243;45587;46510.53489465062;37356.62643373531;37701.811490943466;37946.43080353742;38675.61324386833;39289.80355098206;32074.83962580172;32345.867894623574;37543;37817;38977.6102238974;36102.91691249267;36660.266794664494;37238.8138059308;37682.43476347912;37709.622903771706;32661;33631;33869.49195762115;34349;34440.655593444746;25178.74821251764;25791.355216119748;26011;26214.475710486066;26615.733842661324 02:28:03 INFO - PROCESS | 1647 | |2;jquery.html;367.90802299425144;369.65252662497267;376.0129659643436;382.91193025604093;397.0114415106384;15411.922940385297;19412.126454309553;19444.611107777837;20265.898670506664;20337.49156271094;18530.166142523583;18764.71852922207;19470;20012.799872001277;20042.799572004275;5604.187392828037;5604.915926261109;5835.708214589275;5842.53259739222;5843.328017278003;5094.541491265785;5643.492085712285;5645.520130788888;5802.5938184327115;5809.9709501452435;5532.612717109804;5730.512906402961;5731.856703582402;5753.453421924911;5765.509931655814;5727.799527016556;5773.538116950634;5776.422357764232;5788.27646544182;5792.710364481801;4989.725565093919;5019.698818070928;5051.823186188466;5053.2420136979745;5080.79676812927;23748.168814091598;23895.761042389702;24074;24711.640859752708;24806.503869922682;2258.9435264118365;2368.336865677613;2515.5220508103585;2720.986395068032;2813.535766598509;11113.944175303333;14112.576622701336;14335.42658293667;15445.30496127677;15773.921130394388;1826.8447181989548;1850.7131394633852;1858.2288350334595;1910.9140088696042;1957.8335841453493;92553;108567;112393;112563;114122;8135.75592732219;10419.843702344471;10493.737656558647;10946.671599852018;11168.329900205852;13429.26139062361;13436.596902092953;13455.730885382432;13459;13469;8142.267195952274;8351.624176911931;8358.791030224293;8447.197516235949;8661.263792577576;7201.92798072023;8570.78573035679;8608.14924791173;8609.44038637499;8732.301415886714;5858.443447872445;6451.516136289797;6622.072909792633;6633.402993730588;6638.933610663928;1669.7244954582359;1722.8707846911532;1800.2079085202731;1842.484104450756;1845.049799353334;859.9613017414231;885.6324625280565;916.5829547555846;997.7306127345576;1013.3717095400899;19803.10886010133;21107.83906885105;21292.25477108294;21331.573368532543;21374.14503419877;836.7155167243167;924.2559739409705;948.3314263444255;962.9422234665942;1000.9549570269355;8610.647062614245;11415.543378264776;11498.137639677057;11518.827217591743;11550.306981581132;596135;628313;630388.8480557568;647624;650971;104111.91776164435;117152;122837;136870;137064.62935370518;942.5240253671852;970.2868391732212;991.4618576444182;992.5855655690611;994.6618149829093;18409.90795046016;18615.720764188278;18724.221198986073;18868.905655471634;18910;48799.0240195187;50940;52949.735251323495;55460;55819;74411.62794185994;75831.62084189543;76432.61783691046;76604.61697691475;77422.61288693521;7179.533330333512;7997.320227780471;8065.9596702016115;8079.596020199084;8166.673333066611;11021.724456888322;12928.612141635389;12975.221486710827;13055.412506437424;13073.280969546766;10557.260991730505;10718.89152145869;10984.835227471434;11171;11202;11045.28205666629;11062.668119956414;11072.224944253825;11126.554937802397;11162.720931976766;9263.953680231556;11417;11481.770364592829;11649.475773590038;11717.589884354007;7625.389968802593;11664.766704666028;11667.766644667228;11879.584214552795;11879.940600296943;9564.904350956402;11569.537218511501;11624.6512604622;11977.161598688008;12027.579034733742 02:28:03 INFO - PROCESS | 1647 | |3;mootools.html;1466.3796504862128;1507.7813717011034;1653.7271350227213;1762.770839790827;1809.9004554749492;7500.5874676892745;7733.922660773391;8030.397720170989;8454.365922555802;8470.364722645803;8004.759857204279;8261.421700480969;8294.294984926288;8319.708810191645;8375.162483751621;2305.3890718959533;2345.9061637534564;2347.14329269817;2351.376885125443;2359.6814430051895;2327.3018094571644;2358.7287461941924;2383.177803657744;2386.940326491834;2387.546366190418;2014.7179394884654;2293.747687754346;2315.4211447138214;2329.6738456616085;2333.988330058356;2291.9885400573057;2298.310506847947;2310.849794763343;2320.2691152286975;2327.9301620951314;2329.918452854142;2345.0971376020184;2347.166755801683;2347.3662111229874;2355.7997570206553;8251.422400431971;8320.126386729367;8355.916440835575;8404.11756765543;8419.410641255115;5113.795448182068;5170.405403378622;5213;5275.736213189325;5281.392639846428;3281.950770738441;3392.2537041850755;3444.7071998880124;3593.982030089859;3607.657272559129;1012.8531362952405;1013.1388319928002;1043.6712435582767;1045.9477026148738;1100.1034157161887;10854.34873907568;10889.673309800719;10911.290766100177;10970.012698857061;10976;4562.247229207143;4626.86119416418;4703.0593881223895;4729.881752956135;4779;9426.151646351862;9504.04959504044;9511.857322140173;9525.761855953519;9556.331056825955;5228.529432351107;5234.005538947587;5264.394594621667;5279.392869819943;5281.181416880389;4287.97856010718;4293.141371725667;4765.332853400527;4796.952030479721;4825.710457372569;3824.2351529694174;3837.9808100959317;3887.8056097195026;3929.2730844793805;3932.9016774580864;1271.4786937355825;1282.198625858838;1282.640860559045;1292.6768307923019;1297.241113948332;773.1302284929454;834.3742193354984;871.1810897756089;879.3536750488386;912.6896855069308;4648.0809852138245;5177.800626859614;5356.0894647909945;5551.417101204396;5751.424857514299;429.5661382004198;438.4497455693085;451.76508215727637;452.60171049476253;465.3461886050105;3831.3869780834934;3832.904177395532;3841.5966323536186;3848.4804551385764;3873.9225215496094;7587.810304742426;10108.483727440796;10371.585136594602;10379.84430233547;10429.68710938673;8486.859861851155;10237.436940968319;10309.226807989431;10321.948390258001;10351.741206469898;7450.96274518624;7734.729284475129;7795.80510487231;7954.72158474439;7957.7612671619945;5418.295621569171;5502.752376143003;5643.661380317194;5687.857803554944;5701.315842098975;7290.4896657233485;8318.667253310043;8775.473471591726;9113.863292050757;9205.803245578032;9198.95400522993;9362.20421264173;9433.575489103145;9597;9600.183984361129;6521;6730.8653826924165;6968.581885086708;7092.326229008236;7213.783586492414;7388.6675099621725;7763.262490063435;7904.960475197818;7913.841723165619;7914.089879663682;7041.647917603997;7219.711211551479;7224.844024955982;7229.349358557755;7314.6708398121145;6982.025440458537;7016.789496315118;7054.858902821812;7136.393406560501;7193.280671932765;6744.123263975652;6745.392914637706;6770.695318710768;6805.659717014229;6815.2162501311;4900.852974410774;4924.433690125538;4934.309196712535;4962.975185124052;4964.528369804862;4866.343043689057;4888.804447822047;4917.6311776616185;4944.703317800943;5024.522670346311 02:28:03 INFO - PROCESS | 1647 | |4;prototype.html;294.64495283183754;304.05590641059507;305.51880787759285;311.59803853029587;314.46069989967214;4858.829940952063;5219.0856857356985;5397.649152805065;5429.809956651518;5639.492445679883;5164.244156986624;5413.160960051188;5546.667199967995;5561.721913904299;5568.7215639217975;4155.750654960697;4161.854335098272;4166.79163042718;4185.623293903548;4195.4545909031785;4234.534201237861;4244.384564238184;4245.893852653693;4260.190563792885;4275.558175668585;3974.4782005440507;4116.403121547374;4176.081262122329;4209.284421648327;4244.448221731171;3553.7867727936405;3911.3155197840547;4151.730137541081;4189.539150693421;4216.325387937915;3628.3853685110134;3664.670179683841;3666.3400587894166;3682.2819550187724;3686.3364594373;5252.579793616502;5396.730163491809;5409.513143817036;5416.566674666017;5455.990641731253;4739.4075740532435;4775.116603428377;4809.591184749301;4834.613230941516;4854.68444551103;5188.584913206934;5456.053730985263;5756.309242890838;5792.8841423171725;5834.533237341003;3353.144948038259;3464.359093567698;3502.141975216088;3566.6433356664384;3993.800309984489;5008.549230569266;5266.499682530153;5844.620099693507;5922.466977972003;6111.485895609998;3675.1363429594026;3961.4652021976663;4008.7372477670024;4093.406456063854;4105.158442519277;3341.8830340937952;3428.8114153721285;4157.8960525987095;4219.480986844734;4234.38601402801;3609.888215393971;4814.229723244334;4814.542618451242;4822.734749588806;4829.275608658659;5539.723013849291;5780.393058728857;5818.817943271273;5931.40685931412;5952.464278214981;1045.2578669144991;1121.7324423401503;1184.5025089462447;1288.6198571421453;1320.2672516753294;829.9004119505698;847.4999750147442;865.6407590849852;897.3449381951082;899.6536333511646;4360.542143074995;4828.275758636232;5129.9743501283;5186.896262074737;5230.843074707765;5069.340985671839;5247.737613119329;5665.365604993698;5680.245081135646;5764.5676574257095;4007.238624661305;4814.494478079821;5143.379835351852;5144.897102057937;5193.480651934852;2583.186296316654;2732.521808683512;2752.4632696623967;2841.6447944007;2960;5355.259540649305;5769;5795.609053827051;5802.506786923159;5804.332501762267;5129.717865517432;5248.895022099613;5575.916361254584;5718.571107166979;5843.941560584425;5606.243157173811;5815.33123690764;5844.532437404998;5865.530757539386;5892.440218179266;4724.858254252378;5095.312132862016;5516.806911758069;5518.9304010995875;5578.693171875586;5104.33643626341;5449;5567.387587365306;5577.577717681965;5642.98426283273;5278.973605132103;5713.457221563945;5732.942670573408;5879.617824841372;5892.263467066617;3209.3902158589794;3898.7855667938534;3942.6254505821903;3976.6818654508147;3997.420374045805;5088.567471764942;5451.945480545144;5712.88598723245;5997.370276120944;6035.637861728311;5178.300929374486;5546.4453554644215;5805.767769289181;5863.595925670455;5936.703164841654;5852.566121300139;5856.3558008618165;5859.882802343844;5888.234529511136;5925.288965324189;5515.200295957144;5713.885722285448;5750.194972703741;5753.713483454853;5756.683382414008;4898.632602554894;5047.747612619428;5063.443021267732;5786.566007549535;5902.911456328073;5705.888821136206;5752.28096487939;5759.56803239767;5787.990245072455;5788.507976822018 02:28:03 INFO - PROCESS | 1647 | |5;yui.html;675.4292622733789;752.6086435053772;754.3814072460584;765.1690323471264;773.8452309538087;11721.472533735981;14353.928230358846;15066.472673456417;15070.945033847634;15088.019278746873;13367.131136476122;13419.19484830911;13457.730845383086;13467.93266033671;13513;784.945053846231;796.303234669664;819.8975128108987;822.8560001999656;827.9691783729265;803.4616806739499;811.5901469757794;814.7840822182127;815.2458975447717;817.709713051867;853.9829203415928;857.2884505860152;857.34413173922;858.2061593026425;858.3519442820661;839.1943734015322;845.8181490979407;847.5338563789921;847.6651722569582;848.0798333807764;636.3000699230809;636.5830381100386;637.4263163153153;637.5568979559208;637.7672149665385;12415.503379864795;12452.688682782913;12509.124361294622;12525.185862918972;13469.86530134696;12854.742905141939;13144.60566183016;13157.736845263042;13200.603981880558;13278.800817987738;11000.669979900616;11010.669679909615;11224.66326010221;11292.717682057932;11410.315381077162;3541.8406171722336;3573.94639080414;3578.731595130376;3664.8167591620313;3699.1676872703697;60478.69760651213;73527;84361.15638843595;85611.57194214051;86687;7858.607069646495;9596;9666.661666841626;9948.35335703177;10044.648437304659;18756.81243187578;19068.33260835864;19160.616787664447;19281.13234904433;19288.90355548213;6986.266442023616;7438.256174382518;7478.887816682645;7546.24537546241;7623.618819059025;4223.598758117974;4264.658827293809;4357.586029327209;4365.847195348148;4460.420145381079;3311.4701647736247;3395.7453191010773;3429.5884493860895;3442.3115376924716;3466.6013408457848;566.8582854286428;629.902365133405;636.78667646339;641.8587910659741;649.4285029174297;552.3785741041329;628.3967391304399;651.205529254309;656.1273506236694;678.6199728152152;8837.734867953843;9003.909960900439;9008.47850929935;9160.17558419732;9405;559.5495626021011;583.3874431846544;617.7961272779971;639.2393052267787;669.2432365041766;4277.700560960703;4444.8444304449185;4446.377507149002;4458.821647134078;4481.887952801206;93054.53472732592;107471;114455.42772286086;116790.41604791922;117906;97316;99263;100962;101692;101807;82138.3572328562;85801.57099214464;86751.56624216838;87027;88279.55860220658;8562.828743424972;8696.608652610521;8735.825283494167;8779.780505487413;8839.442276853455;47751;56398.43601564096;57052.71473642605;57748.133777994204;58347.12479312729;77621.61189194018;80430;82024.58987705024;82625;84493.73259400991;10630.468476575985;10817.40504272241;11021.486683818788;11256.943715281699;11347.773044538897;10723.892761072602;11873.406329683654;11885.524579016743;12431.316277604457;12761.170523915916;11736.94131529371;11949.76100478003;11995.220310679779;12429.316387598768;12437.315947622968;11951.282923024646;12010;12287.877121228674;12443.502259909865;12450.256964025224;11487.712807179887;11801.940990294994;11835.408229588314;12316.938415307866;12360.320182390054;8081.980467141974;8085.272325490499;9670.56482458305;9710.271729620448;10037.849432258668;7983.136610438597;8501.659933602834;10139.493025348556;10157.288989770645;10159.644412445528 02:28:03 INFO - PROCESS | 1647 | __end_tp_report 02:28:03 INFO - PROCESS | 1647 | __start_cc_report 02:28:03 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,373 02:28:03 INFO - PROCESS | 1647 | __end_cc_report 02:28:03 INFO - PROCESS | 1647 | __startTimestamp1483439283490__endTimestamp 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | ------- Summary: start ------- 02:28:03 INFO - PROCESS | 1647 | Number of tests: 6 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5730.73 Median:5979.49 stddev:697.14 (11.7%) stddev-sans-first:660.27 02:28:03 INFO - PROCESS | 1647 | Values: 2799.7 3136.2 3409.1 3427.5 3462.9 5795.7 5817.7 5826.0 5880.4 5899.0 4680.4 5620.9 5809.3 5860.6 5877.9 5239.6 5319.7 5321.8 5329.8 5347.9 5350.9 5368.0 5369.1 5381.6 5416.9 5275.0 5304.5 5358.1 5361.2 5364.6 5294.2 5344.1 5352.3 5366.4 5369.2 5217.4 5292.1 5292.6 5293.5 5331.6 5826.8 5893.2 5908.4 5916.9 5918.3 6108.8 6178.5 6243.5 6297.1 6300.4 6257.1 6278.1 6293.2 6297.2 6301.2 5922.0 5948.9 5983.1 5987.2 5999.3 6285.6 6332.7 6375.5 6393.8 6411.9 6226.8 6268.1 6280.8 6281.9 6283.5 6165.1 6270.2 6288.6 6337.4 6351.6 5010.6 6198.4 6232.7 6236.7 6254.8 6216.1 6299.1 6302.0 6309.8 6312.3 4970.3 5018.1 5019.0 5024.0 5027.4 4411.5 4427.4 4429.6 4458.0 4515.0 4877.6 5738.9 5873.8 5973.1 5976.8 5163.9 6260.2 6284.3 6291.6 6301.2 4772.1 4907.9 5055.7 5951.9 5965.9 4143.7 5374.6 5479.4 5484.6 5494.1 5115.7 6205.1 6218.1 6222.9 6283.8 6271.8 6280.8 6287.9 6302.1 6302.7 5202.5 5724.8 6345.3 6350.2 6358.6 5548.5 5996.3 6121.4 6170.0 6264.4 5127.7 6282.3 6315.9 6319.7 6358.2 4846.6 6196.7 6228.4 6253.6 6255.9 6058.3 6113.3 6259.7 6263.0 6278.1 5979.5 6007.9 6144.6 6162.3 6183.4 5156.4 6232.9 6233.2 6247.3 6267.3 4628.2 6099.1 6151.9 6152.2 6170.6 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:72200.34 Median:40843.39 stddev:160443.54 (392.8%) stddev-sans-first:160803.25 02:28:03 INFO - PROCESS | 1647 | Values: 474.1 476.6 508.3 511.1 513.8 38251.0 39666.6 39821.4 41803.6 41868.0 40537.6 40772.2 40914.6 41048.4 41071.8 9253.8 9375.0 9381.2 9393.0 9421.6 9327.3 9346.8 9431.3 9438.9 9472.8 7990.4 9410.2 9441.1 9451.7 9467.7 9372.4 9377.2 9415.0 9452.4 9468.0 9137.9 9215.5 9226.6 9237.3 9244.2 38505.8 38683.6 38759.6 38830.0 38944.0 3311.3 3317.4 3409.2 3422.4 3804.0 28386.9 29059.6 29329.1 29846.7 30305.8 2624.6 3189.3 3202.0 3208.4 3296.1 405799.9 455413.0 480255.0 484671.0 485719.6 61816.7 61983.7 62346.2 62353.4 62669.7 63515.7 64221.4 64391.0 64445.7 64801.7 16284.7 16425.2 16427.6 16462.3 16487.5 11042.8 12503.7 12629.1 12647.9 12667.3 17560.4 18894.9 18932.6 18994.3 19026.7 6509.4 6649.4 6690.3 7805.8 7834.8 698.4 967.2 987.4 992.0 1036.1 29056.7 29376.4 29751.1 30293.1 30861.5 790.8 969.7 1009.5 1032.8 1094.3 14766.9 15511.1 15906.4 16232.3 16264.3 663356.0 677666.0 679682.0 682345.0 682880.0 46683.0 56222.4 56856.0 56979.7 57515.7 1210.7 1388.5 1399.3 1431.0 1434.8 23753.5 28473.4 28796.6 29356.3 30045.2 50440.5 51003.5 51075.2 51189.2 51280.0 629237.0 635658.0 636884.0 637265.0 637655.0 38470.0 41472.0 41919.7 42236.0 42290.2 44761.1 45335.8 45528.5 45587.0 46510.5 37356.6 37701.8 37946.4 38675.6 39289.8 32074.8 32345.9 37543.0 37817.0 38977.6 36102.9 36660.3 37238.8 37682.4 37709.6 32661.0 33631.0 33869.5 34349.0 34440.7 25178.7 25791.4 26011.0 26214.5 26615.7 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35519.03 Median:24392.82 stddev:104696.02 (429.2%) stddev-sans-first:104956.45 02:28:03 INFO - PROCESS | 1647 | Values: 367.9 369.7 376.0 382.9 397.0 15411.9 19412.1 19444.6 20265.9 20337.5 18530.2 18764.7 19470.0 20012.8 20042.8 5604.2 5604.9 5835.7 5842.5 5843.3 5094.5 5643.5 5645.5 5802.6 5810.0 5532.6 5730.5 5731.9 5753.5 5765.5 5727.8 5773.5 5776.4 5788.3 5792.7 4989.7 5019.7 5051.8 5053.2 5080.8 23748.2 23895.8 24074.0 24711.6 24806.5 2258.9 2368.3 2515.5 2721.0 2813.5 11113.9 14112.6 14335.4 15445.3 15773.9 1826.8 1850.7 1858.2 1910.9 1957.8 92553.0 108567.0 112393.0 112563.0 114122.0 8135.8 10419.8 10493.7 10946.7 11168.3 13429.3 13436.6 13455.7 13459.0 13469.0 8142.3 8351.6 8358.8 8447.2 8661.3 7201.9 8570.8 8608.1 8609.4 8732.3 5858.4 6451.5 6622.1 6633.4 6638.9 1669.7 1722.9 1800.2 1842.5 1845.0 860.0 885.6 916.6 997.7 1013.4 19803.1 21107.8 21292.3 21331.6 21374.1 836.7 924.3 948.3 962.9 1001.0 8610.6 11415.5 11498.1 11518.8 11550.3 596135.0 628313.0 630388.8 647624.0 650971.0 104111.9 117152.0 122837.0 136870.0 137064.6 942.5 970.3 991.5 992.6 994.7 18409.9 18615.7 18724.2 18868.9 18910.0 48799.0 50940.0 52949.7 55460.0 55819.0 74411.6 75831.6 76432.6 76604.6 77422.6 7179.5 7997.3 8066.0 8079.6 8166.7 11021.7 12928.6 12975.2 13055.4 13073.3 10557.3 10718.9 10984.8 11171.0 11202.0 11045.3 11062.7 11072.2 11126.6 11162.7 9264.0 11417.0 11481.8 11649.5 11717.6 7625.4 11664.8 11667.8 11879.6 11879.9 9564.9 11569.5 11624.7 11977.2 12027.6 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5412.83 Median:4953.84 stddev:2962.37 (59.8%) stddev-sans-first:2955.83 02:28:03 INFO - PROCESS | 1647 | Values: 1466.4 1507.8 1653.7 1762.8 1809.9 7500.6 7733.9 8030.4 8454.4 8470.4 8004.8 8261.4 8294.3 8319.7 8375.2 2305.4 2345.9 2347.1 2351.4 2359.7 2327.3 2358.7 2383.2 2386.9 2387.5 2014.7 2293.7 2315.4 2329.7 2334.0 2292.0 2298.3 2310.8 2320.3 2327.9 2329.9 2345.1 2347.2 2347.4 2355.8 8251.4 8320.1 8355.9 8404.1 8419.4 5113.8 5170.4 5213.0 5275.7 5281.4 3282.0 3392.3 3444.7 3594.0 3607.7 1012.9 1013.1 1043.7 1045.9 1100.1 10854.3 10889.7 10911.3 10970.0 10976.0 4562.2 4626.9 4703.1 4729.9 4779.0 9426.2 9504.0 9511.9 9525.8 9556.3 5228.5 5234.0 5264.4 5279.4 5281.2 4288.0 4293.1 4765.3 4797.0 4825.7 3824.2 3838.0 3887.8 3929.3 3932.9 1271.5 1282.2 1282.6 1292.7 1297.2 773.1 834.4 871.2 879.4 912.7 4648.1 5177.8 5356.1 5551.4 5751.4 429.6 438.4 451.8 452.6 465.3 3831.4 3832.9 3841.6 3848.5 3873.9 7587.8 10108.5 10371.6 10379.8 10429.7 8486.9 10237.4 10309.2 10321.9 10351.7 7451.0 7734.7 7795.8 7954.7 7957.8 5418.3 5502.8 5643.7 5687.9 5701.3 7290.5 8318.7 8775.5 9113.9 9205.8 9199.0 9362.2 9433.6 9597.0 9600.2 6521.0 6730.9 6968.6 7092.3 7213.8 7388.7 7763.3 7905.0 7913.8 7914.1 7041.6 7219.7 7224.8 7229.3 7314.7 6982.0 7016.8 7054.9 7136.4 7193.3 6744.1 6745.4 6770.7 6805.7 6815.2 4900.9 4924.4 4934.3 4963.0 4964.5 4866.3 4888.8 4917.6 4944.7 5024.5 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4609.22 Median:5187.74 stddev:1428.33 (27.5%) stddev-sans-first:1395.14 02:28:03 INFO - PROCESS | 1647 | Values: 294.6 304.1 305.5 311.6 314.5 4858.8 5219.1 5397.6 5429.8 5639.5 5164.2 5413.2 5546.7 5561.7 5568.7 4155.8 4161.9 4166.8 4185.6 4195.5 4234.5 4244.4 4245.9 4260.2 4275.6 3974.5 4116.4 4176.1 4209.3 4244.4 3553.8 3911.3 4151.7 4189.5 4216.3 3628.4 3664.7 3666.3 3682.3 3686.3 5252.6 5396.7 5409.5 5416.6 5456.0 4739.4 4775.1 4809.6 4834.6 4854.7 5188.6 5456.1 5756.3 5792.9 5834.5 3353.1 3464.4 3502.1 3566.6 3993.8 5008.5 5266.5 5844.6 5922.5 6111.5 3675.1 3961.5 4008.7 4093.4 4105.2 3341.9 3428.8 4157.9 4219.5 4234.4 3609.9 4814.2 4814.5 4822.7 4829.3 5539.7 5780.4 5818.8 5931.4 5952.5 1045.3 1121.7 1184.5 1288.6 1320.3 829.9 847.5 865.6 897.3 899.7 4360.5 4828.3 5130.0 5186.9 5230.8 5069.3 5247.7 5665.4 5680.2 5764.6 4007.2 4814.5 5143.4 5144.9 5193.5 2583.2 2732.5 2752.5 2841.6 2960.0 5355.3 5769.0 5795.6 5802.5 5804.3 5129.7 5248.9 5575.9 5718.6 5843.9 5606.2 5815.3 5844.5 5865.5 5892.4 4724.9 5095.3 5516.8 5518.9 5578.7 5104.3 5449.0 5567.4 5577.6 5643.0 5279.0 5713.5 5732.9 5879.6 5892.3 3209.4 3898.8 3942.6 3976.7 3997.4 5088.6 5451.9 5712.9 5997.4 6035.6 5178.3 5546.4 5805.8 5863.6 5936.7 5852.6 5856.4 5859.9 5888.2 5925.3 5515.2 5713.9 5750.2 5753.7 5756.7 4898.6 5047.7 5063.4 5786.6 5902.9 5705.9 5752.3 5759.6 5788.0 5788.5 02:28:03 INFO - PROCESS | 1647 | 02:28:03 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20270.40 Median:28482.65 stddev:30459.14 (106.9%) stddev-sans-first:30509.06 02:28:03 INFO - PROCESS | 1647 | Values: 675.4 752.6 754.4 765.2 773.8 11721.5 14353.9 15066.5 15070.9 15088.0 13367.1 13419.2 13457.7 13467.9 13513.0 784.9 796.3 819.9 822.9 828.0 803.5 811.6 814.8 815.2 817.7 854.0 857.3 857.3 858.2 858.4 839.2 845.8 847.5 847.7 848.1 636.3 636.6 637.4 637.6 637.8 12415.5 12452.7 12509.1 12525.2 13469.9 12854.7 13144.6 13157.7 13200.6 13278.8 11000.7 11010.7 11224.7 11292.7 11410.3 3541.8 3573.9 3578.7 3664.8 3699.2 60478.7 73527.0 84361.2 85611.6 86687.0 7858.6 9596.0 9666.7 9948.4 10044.6 18756.8 19068.3 19160.6 19281.1 19288.9 6986.3 7438.3 7478.9 7546.2 7623.6 4223.6 4264.7 4357.6 4365.8 4460.4 3311.5 3395.7 3429.6 3442.3 3466.6 566.9 629.9 636.8 641.9 649.4 552.4 628.4 651.2 656.1 678.6 8837.7 9003.9 9008.5 9160.2 9405.0 559.5 583.4 617.8 639.2 669.2 4277.7 4444.8 4446.4 4458.8 4481.9 93054.5 107471.0 114455.4 116790.4 117906.0 97316.0 99263.0 100962.0 101692.0 101807.0 82138.4 85801.6 86751.6 87027.0 88279.6 8562.8 8696.6 8735.8 8779.8 8839.4 47751.0 56398.4 57052.7 57748.1 58347.1 77621.6 80430.0 82024.6 82625.0 84493.7 10630.5 10817.4 11021.5 11256.9 11347.8 10723.9 11873.4 11885.5 12431.3 12761.2 11736.9 11949.8 11995.2 12429.3 12437.3 11951.3 12010.0 12287.9 12443.5 12450.3 11487.7 11801.9 11835.4 12316.9 12360.3 8082.0 8085.3 9670.6 9710.3 10037.8 7983.1 8501.7 10139.5 10157.3 10159.6 02:28:03 INFO - PROCESS | 1647 | -------- Summary: end -------- 02:28:03 INFO - PROCESS | 1647 | 02:28:04 INFO - TEST-INFO | 1647: exit 0 02:28:04 INFO - TEST-OK | dromaeo_css | took 1117345ms 02:28:04 INFO - TEST-START | kraken 02:28:04 INFO - operating with platform_type : mac_ 02:28:04 INFO - Initialising browser for kraken test... 02:28:04 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmp0k2NHF/profile http://localhost:49244/getInfo.html) 02:28:07 INFO - PROCESS | 1654 | 2017-01-03 02:28:07.261 plugin-container[1656:10756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 02:28:07 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:07 INFO - PROCESS | 1654 | 2017-01-03 02:28:07.262 plugin-container[1656:10756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 02:28:07 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:07 INFO - PROCESS | 1654 | 2017-01-03 02:28:07.262 plugin-container[1656:10756] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:07 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 02:28:07 INFO - PROCESS | 1654 | colorDepth:24 02:28:07 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 02:28:07 INFO - PROCESS | 1654 | __metrics 02:28:07 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:28:08 INFO - TEST-INFO | 1654: exit 0 02:28:08 INFO - Browser initialized. 02:28:08 INFO - Running cycle 1/1 for kraken test... 02:28:08 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmp0k2NHF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:10 INFO - PROCESS | 1657 | 2017-01-03 02:28:10.872 plugin-container[1658:10881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 02:28:10 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:10 INFO - PROCESS | 1657 | 2017-01-03 02:28:10.873 plugin-container[1658:10881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 02:28:10 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:10 INFO - PROCESS | 1657 | 2017-01-03 02:28:10.873 plugin-container[1658:10881] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:20 INFO - PROCESS | 1657 | RSS: Main: 229752832 02:28:20 INFO - PROCESS | 1657 | 02:28:23 INFO - PROCESS | 1657 | 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) 02:28:24 INFO - PROCESS | 1657 | RSS: Main: 241541120 02:28:24 INFO - PROCESS | 1657 | 02:28:27 INFO - PROCESS | 1657 | 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) 02:28:27 INFO - PROCESS | 1657 | RSS: Main: 249245696 02:28:27 INFO - PROCESS | 1657 | 02:28:30 INFO - PROCESS | 1657 | 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) 02:28:31 INFO - PROCESS | 1657 | RSS: Main: 252817408 02:28:31 INFO - PROCESS | 1657 | 02:28:33 INFO - PROCESS | 1657 | 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) 02:28:34 INFO - PROCESS | 1657 | RSS: Main: 253337600 02:28:34 INFO - PROCESS | 1657 | 02:28:36 INFO - PROCESS | 1657 | 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) 02:28:36 INFO - PROCESS | 1657 | RSS: Main: 253108224 02:28:36 INFO - PROCESS | 1657 | 02:28:39 INFO - PROCESS | 1657 | 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) 02:28:40 INFO - PROCESS | 1657 | RSS: Main: 257687552 02:28:40 INFO - PROCESS | 1657 | 02:28:43 INFO - PROCESS | 1657 | 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) 02:28:44 INFO - PROCESS | 1657 | RSS: Main: 257961984 02:28:44 INFO - PROCESS | 1657 | 02:28:46 INFO - PROCESS | 1657 | 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) 02:28:47 INFO - PROCESS | 1657 | RSS: Main: 258031616 02:28:47 INFO - PROCESS | 1657 | 02:28:49 INFO - PROCESS | 1657 | 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) 02:28:49 INFO - PROCESS | 1657 | RSS: Main: 257040384 02:28:49 INFO - PROCESS | 1657 | 02:28:51 INFO - PROCESS | 1657 | 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) 02:28:52 INFO - PROCESS | 1657 | RSS: Main: 257093632 02:28:52 INFO - PROCESS | 1657 | 02:28:54 INFO - PROCESS | 1657 | 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) 02:28:55 INFO - PROCESS | 1657 | RSS: Main: 257126400 02:28:55 INFO - PROCESS | 1657 | 02:28:58 INFO - PROCESS | 1657 | 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) 02:28:59 INFO - PROCESS | 1657 | RSS: Main: 257413120 02:28:59 INFO - PROCESS | 1657 | 02:29:02 INFO - PROCESS | 1657 | 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) 02:29:02 INFO - PROCESS | 1657 | RSS: Main: 257404928 02:29:02 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | 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) 02:29:04 INFO - PROCESS | 1657 | RSS: Main: 258985984 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | __start_tp_report 02:29:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 02:29:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 02:29:04 INFO - PROCESS | 1657 | |i|pagename|runs| 02:29:04 INFO - PROCESS | 1657 | |0;ai-astar;107;118;119;119;125;118;119;119;117;120 02:29:04 INFO - PROCESS | 1657 | |1;audio-beat-detection;144;113;151;143;119;112;127;147;142;140 02:29:04 INFO - PROCESS | 1657 | |2;audio-dft;192;199;193;202;194;198;200;192;202;223 02:29:04 INFO - PROCESS | 1657 | |3;audio-fft;78;56;57;83;52;65;86;84;75;59 02:29:04 INFO - PROCESS | 1657 | |4;audio-oscillator;87;68;91;71;70;73;121;88;70;90 02:29:04 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;109;89;91;100;129;88;108;130;135;88 02:29:04 INFO - PROCESS | 1657 | |6;imaging-darkroom;101;102;102;101;155;112;173;177;101;101 02:29:04 INFO - PROCESS | 1657 | |7;imaging-desaturate;87;88;86;89;91;87;89;94;87;87 02:29:04 INFO - PROCESS | 1657 | |8;json-parse-financial;60;57;57;59;59;59;60;61;59;60 02:29:04 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;67;74;89;116;116;73;70;70;72;72 02:29:04 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;83;84;87;86;76;93;102;103;102;77 02:29:04 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;166;136;159;135;161;165;155;161;249;147 02:29:04 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;234;226;162;159;163;178;143;220;233;155 02:29:04 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;69;67;70;68;62;84;85;88;61 02:29:04 INFO - PROCESS | 1657 | __end_tp_report 02:29:04 INFO - PROCESS | 1657 | __start_cc_report 02:29:04 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,974 02:29:04 INFO - PROCESS | 1657 | __end_cc_report 02:29:04 INFO - PROCESS | 1657 | __startTimestamp1483439344880__endTimestamp 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | ------- Summary: start ------- 02:29:04 INFO - PROCESS | 1657 | Number of tests: 14 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:118.10 Median:119.00 stddev:4.46 (3.7%) stddev-sans-first:2.29 02:29:04 INFO - PROCESS | 1657 | Values: 107.0 118.0 119.0 119.0 125.0 118.0 119.0 119.0 117.0 120.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:142.50 stddev:14.67 (10.3%) stddev-sans-first:15.09 02:29:04 INFO - PROCESS | 1657 | Values: 144.0 113.0 151.0 143.0 119.0 112.0 127.0 147.0 142.0 140.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:199.50 Median:199.50 stddev:9.14 (4.6%) stddev-sans-first:9.29 02:29:04 INFO - PROCESS | 1657 | Values: 192.0 199.0 193.0 202.0 194.0 198.0 200.0 192.0 202.0 223.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:69.50 Median:76.50 stddev:13.09 (17.1%) stddev-sans-first:13.52 02:29:04 INFO - PROCESS | 1657 | Values: 78.0 56.0 57.0 83.0 52.0 65.0 86.0 84.0 75.0 59.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:80.00 stddev:16.35 (20.4%) stddev-sans-first:17.27 02:29:04 INFO - PROCESS | 1657 | Values: 87.0 68.0 91.0 71.0 70.0 73.0 121.0 88.0 70.0 90.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:106.70 Median:111.50 stddev:18.71 (16.8%) stddev-sans-first:19.83 02:29:04 INFO - PROCESS | 1657 | Values: 109.0 89.0 91.0 100.0 129.0 88.0 108.0 130.0 135.0 88.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:122.50 Median:107.00 stddev:32.28 (30.2%) stddev-sans-first:33.28 02:29:04 INFO - PROCESS | 1657 | Values: 101.0 102.0 102.0 101.0 155.0 112.0 173.0 177.0 101.0 101.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:88.50 Median:88.50 stddev:2.42 (2.7%) stddev-sans-first:2.50 02:29:04 INFO - PROCESS | 1657 | Values: 87.0 88.0 86.0 89.0 91.0 87.0 89.0 94.0 87.0 87.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:59.50 stddev:1.29 (2.2%) stddev-sans-first:1.32 02:29:04 INFO - PROCESS | 1657 | Values: 60.0 57.0 57.0 59.0 59.0 59.0 60.0 61.0 59.0 60.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:81.90 Median:72.00 stddev:18.91 (26.3%) stddev-sans-first:19.28 02:29:04 INFO - PROCESS | 1657 | Values: 67.0 74.0 89.0 116.0 116.0 73.0 70.0 70.0 72.0 72.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:83.50 stddev:10.20 (12.2%) stddev-sans-first:10.56 02:29:04 INFO - PROCESS | 1657 | Values: 83.0 84.0 87.0 86.0 76.0 93.0 102.0 103.0 102.0 77.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:163.40 Median:161.00 stddev:32.08 (19.9%) stddev-sans-first:34.02 02:29:04 INFO - PROCESS | 1657 | Values: 166.0 136.0 159.0 135.0 161.0 165.0 155.0 161.0 249.0 147.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:199.00 stddev:36.45 (18.3%) stddev-sans-first:34.53 02:29:04 INFO - PROCESS | 1657 | Values: 234.0 226.0 162.0 159.0 163.0 178.0 143.0 220.0 233.0 155.0 02:29:04 INFO - PROCESS | 1657 | 02:29:04 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:69.50 stddev:9.77 (14.1%) stddev-sans-first:10.25 02:29:04 INFO - PROCESS | 1657 | Values: 68.0 69.0 67.0 70.0 68.0 62.0 84.0 85.0 88.0 61.0 02:29:04 INFO - PROCESS | 1657 | -------- Summary: end -------- 02:29:04 INFO - PROCESS | 1657 | 02:29:05 INFO - TEST-INFO | 1657: exit 0 02:29:05 INFO - TEST-OK | kraken | took 61739ms 02:29:05 INFO - SUITE-END | took 1179s 02:29:06 INFO - Completed test suite (00:19:39) 02:29:06 INFO - javascript benchmark 02:29:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7188.618109724596, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2799.6920338762743, 3136.2159460134967, 3409.1136304560805, 3427.5201471793953, 3462.896821437835, 5795.739191736371, 5817.73820178092, 5825.970870145643, 5880.441358070987, 5899.0, 4680.368150299709, 5620.932022915643, 5809.2738407699035, 5860.648361098337, 5877.9412205877925, 5239.554637855777, 5319.707416092133, 5321.760520776555, 5329.786808527654, 5347.893042139155, 5350.892982140354, 5367.973160134194, 5369.087255166612, 5381.596380271476, 5416.945830541703, 5275.0, 5304.549113325372, 5358.142697168454, 5361.222622719703, 5364.5708343332635, 5294.232336311233, 5344.118220493633, 5352.330958630171, 5366.356037275552, 5369.221462887898, 5217.399999000126, 5292.1003429416705, 5292.576593872481, 5293.4971177738435, 5331.573474122061, 5826.766929322865, 5893.17495550619, 5908.379620139866, 5916.911246331309, 5918.348981612019, 6108.755649774003, 6178.474829639486, 6243.531735119885, 6297.118403423525, 6300.369963003709, 6257.061440783873, 6278.121063051177, 6293.1504246927, 6297.244330680302, 6301.212348456443, 5921.970390148064, 5948.940510594882, 5983.132445795379, 5987.161797348331, 5999.340072592011, 6285.622862628257, 6332.651704156316, 6375.521835862236, 6393.808185754435, 6411.871762564722, 6226.844328891814, 6268.091126786681, 6280.811575652738, 6281.8743625127245, 6283.528735344776, 6165.136880836686, 6270.184875966095, 6288.5598008139905, 6337.397947195009, 6351.618902865843, 5010.599152067899, 6198.380161983766, 6232.688365581703, 6236.688165591702, 6254.781082662176, 6216.067589861557, 6299.149614801943, 6301.96849015752, 6309.810705678837, 6312.274088479791, 4970.254461830755, 5018.071656743514, 5018.99620075979, 5023.974880125649, 5027.4469808321055, 4411.536788637211, 4427.380166776654, 4429.579189976947, 4457.977710111493, 4515.029268707243, 4877.6341774367065, 5738.938296415093, 5873.823785286449, 5973.148324019568, 5976.760929562855, 5163.948360516347, 6260.217472815898, 6284.308726040132, 6291.591046581958, 6301.212348456443, 4772.117158325721, 4907.877303067452, 5055.747212639353, 5951.940480595225, 5965.926133296049, 4143.7306575072525, 5374.5700343972385, 5479.397266300703, 5484.588655850827, 5494.09347457665, 5115.718635475085, 6205.069239614094, 6218.129461875432, 6222.937770622236, 6283.842903927439, 6271.78048768291, 6280.811575652738, 6287.905681414691, 6302.149209856794, 6302.653354065387, 5202.531772140525, 5724.803502139223, 6345.302016778243, 6350.174477317919, 6358.618482891041, 5548.501904485847, 5996.310424301087, 6121.361519882508, 6170.0, 6264.373562643699, 5127.692338459704, 6282.340354262919, 6315.936840631535, 6319.747210111728, 6358.205224346957, 4846.636502262274, 6196.659183744757, 6228.408301211378, 6253.5622506424115, 6255.906161407674, 6058.303295121117, 6113.263474096151, 6259.684225735239, 6262.968685156545, 6278.058291256348, 5979.4917432018765, 6007.87984240304, 6144.617461071223, 6162.322144564005, 6183.443490085914, 5156.355455568054, 6232.937670623236, 6233.2208473940755, 6247.250329960434, 6267.310595834363, 4628.2363410038315, 6099.146119543178, 6151.876962460815, 6152.169457123231, 6170.629762214281], "value": 5689.074782395799, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.14653623477733, 476.59013248606203, 508.30362403507195, 511.14893701986193, 513.8150265904276, 38251.0, 39666.603333966654, 39821.4026789598, 41803.58196418035, 41868.0, 40537.59462405375, 40772.18455630886, 40914.59085409145, 41048.38427423591, 41071.76784696464, 9253.814923701522, 9375.0, 9381.15569598736, 9393.01373355798, 9421.576029078673, 9327.30045246606, 9346.766330841716, 9431.339806213567, 9438.858417123714, 9472.763180920463, 7990.440669153161, 9410.153086222239, 9441.103095205945, 9451.716448506555, 9467.668631597895, 9372.39079459833, 9377.156055954963, 9414.952925235399, 9452.385594936375, 9467.95266023669, 9137.908620913839, 9215.493147876865, 9226.630934762601, 9237.261019118454, 9244.214241789456, 38505.80747096275, 38683.646072387615, 38759.61240387616, 38830.0, 38944.02639933996, 3311.2880730642787, 3317.3696997570382, 3409.232922592422, 3422.418188907867, 3803.9809800951093, 28386.864525418958, 29059.56410653842, 29329.120126396243, 29846.701532984825, 30305.787768489234, 2624.58006718926, 3189.2983543620367, 3202.0, 3208.4385232584204, 3296.126526470487, 405799.8840023183, 455413.0, 480255.0, 484671.0, 485719.57140214427, 61816.69091654513, 61983.690081549306, 62346.19442125115, 62353.37646623566, 62669.68665156645, 63515.68242158852, 64221.35778642247, 64391.03413448802, 64445.67777161178, 64801.70396592042, 16284.67430651404, 16425.1787410629, 16427.58931026734, 16462.259198335865, 16487.505374838773, 11042.834357484644, 12503.74992500145, 12629.11596188258, 12647.87352126467, 12667.30329831868, 17560.37057901792, 18894.905525472284, 18932.62134757297, 18994.335198267996, 19026.714599281022, 6509.381608747161, 6649.368310010541, 6690.264070952191, 7805.843883122419, 7834.804129896798, 698.3971784754002, 967.2213867836459, 987.4322264697828, 992.0426788149471, 1036.1400037968467, 29056.709432905824, 29376.41247175087, 29751.107466776033, 30293.091207263817, 30861.537076943863, 790.833924875771, 969.7139343893568, 1009.4649835587151, 1032.808930347888, 1094.3269889018356, 14766.852331476763, 15511.069335839886, 15906.363745450284, 16232.269547870137, 16264.349426023064, 663356.0, 677666.0, 679682.0, 682345.0, 682880.0, 46683.0, 56222.43777562172, 56856.0, 56979.715101425056, 57515.71242143762, 1210.667066556704, 1388.5417812121975, 1399.2723783632455, 1430.9642258943818, 1434.8278206615273, 23753.52492950166, 28473.430531388844, 28796.568051478826, 29356.266093347836, 30045.248868778457, 50440.49559504358, 51003.489965101355, 51075.2338714927, 51189.232161518354, 51280.0, 629237.0, 635658.0, 636884.0, 637265.0, 637655.0, 38470.0, 41472.0, 41919.74240772782, 42236.0, 42290.154196915275, 44761.10477790361, 45335.77332113318, 45528.54471455243, 45587.0, 46510.53489465062, 37356.62643373531, 37701.811490943466, 37946.43080353742, 38675.61324386833, 39289.80355098206, 32074.83962580172, 32345.867894623574, 37543.0, 37817.0, 38977.6102238974, 36102.91691249267, 36660.266794664494, 37238.8138059308, 37682.43476347912, 37709.622903771706, 32661.0, 33631.0, 33869.49195762115, 34349.0, 34440.655593444746, 25178.74821251764, 25791.355216119748, 26011.0, 26214.475710486066, 26615.733842661324], "value": 19905.964626353223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.90802299425144, 369.65252662497267, 376.0129659643436, 382.91193025604093, 397.0114415106384, 15411.922940385297, 19412.126454309553, 19444.611107777837, 20265.898670506664, 20337.49156271094, 18530.166142523583, 18764.71852922207, 19470.0, 20012.799872001277, 20042.799572004275, 5604.187392828037, 5604.915926261109, 5835.708214589275, 5842.53259739222, 5843.328017278003, 5094.541491265785, 5643.492085712285, 5645.520130788888, 5802.5938184327115, 5809.9709501452435, 5532.612717109804, 5730.512906402961, 5731.856703582402, 5753.453421924911, 5765.509931655814, 5727.799527016556, 5773.538116950634, 5776.422357764232, 5788.27646544182, 5792.710364481801, 4989.725565093919, 5019.698818070928, 5051.823186188466, 5053.2420136979745, 5080.79676812927, 23748.168814091598, 23895.761042389702, 24074.0, 24711.640859752708, 24806.503869922682, 2258.9435264118365, 2368.336865677613, 2515.5220508103585, 2720.986395068032, 2813.535766598509, 11113.944175303333, 14112.576622701336, 14335.42658293667, 15445.30496127677, 15773.921130394388, 1826.8447181989548, 1850.7131394633852, 1858.2288350334595, 1910.9140088696042, 1957.8335841453493, 92553.0, 108567.0, 112393.0, 112563.0, 114122.0, 8135.75592732219, 10419.843702344471, 10493.737656558647, 10946.671599852018, 11168.329900205852, 13429.26139062361, 13436.596902092953, 13455.730885382432, 13459.0, 13469.0, 8142.267195952274, 8351.624176911931, 8358.791030224293, 8447.197516235949, 8661.263792577576, 7201.92798072023, 8570.78573035679, 8608.14924791173, 8609.44038637499, 8732.301415886714, 5858.443447872445, 6451.516136289797, 6622.072909792633, 6633.402993730588, 6638.933610663928, 1669.7244954582359, 1722.8707846911532, 1800.2079085202731, 1842.484104450756, 1845.049799353334, 859.9613017414231, 885.6324625280565, 916.5829547555846, 997.7306127345576, 1013.3717095400899, 19803.10886010133, 21107.83906885105, 21292.25477108294, 21331.573368532543, 21374.14503419877, 836.7155167243167, 924.2559739409705, 948.3314263444255, 962.9422234665942, 1000.9549570269355, 8610.647062614245, 11415.543378264776, 11498.137639677057, 11518.827217591743, 11550.306981581132, 596135.0, 628313.0, 630388.8480557568, 647624.0, 650971.0, 104111.91776164435, 117152.0, 122837.0, 136870.0, 137064.62935370518, 942.5240253671852, 970.2868391732212, 991.4618576444182, 992.5855655690611, 994.6618149829093, 18409.90795046016, 18615.720764188278, 18724.221198986073, 18868.905655471634, 18910.0, 48799.0240195187, 50940.0, 52949.735251323495, 55460.0, 55819.0, 74411.62794185994, 75831.62084189543, 76432.61783691046, 76604.61697691475, 77422.61288693521, 7179.533330333512, 7997.320227780471, 8065.9596702016115, 8079.596020199084, 8166.673333066611, 11021.724456888322, 12928.612141635389, 12975.221486710827, 13055.412506437424, 13073.280969546766, 10557.260991730505, 10718.89152145869, 10984.835227471434, 11171.0, 11202.0, 11045.28205666629, 11062.668119956414, 11072.224944253825, 11126.554937802397, 11162.720931976766, 9263.953680231556, 11417.0, 11481.770364592829, 11649.475773590038, 11717.589884354007, 7625.389968802593, 11664.766704666028, 11667.766644667228, 11879.584214552795, 11879.940600296943, 9564.904350956402, 11569.537218511501, 11624.6512604622, 11977.161598688008, 12027.579034733742], "value": 9651.149450953384, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.3796504862128, 1507.7813717011034, 1653.7271350227213, 1762.770839790827, 1809.9004554749492, 7500.5874676892745, 7733.922660773391, 8030.397720170989, 8454.365922555802, 8470.364722645803, 8004.759857204279, 8261.421700480969, 8294.294984926288, 8319.708810191645, 8375.162483751621, 2305.3890718959533, 2345.9061637534564, 2347.14329269817, 2351.376885125443, 2359.6814430051895, 2327.3018094571644, 2358.7287461941924, 2383.177803657744, 2386.940326491834, 2387.546366190418, 2014.7179394884654, 2293.747687754346, 2315.4211447138214, 2329.6738456616085, 2333.988330058356, 2291.9885400573057, 2298.310506847947, 2310.849794763343, 2320.2691152286975, 2327.9301620951314, 2329.918452854142, 2345.0971376020184, 2347.166755801683, 2347.3662111229874, 2355.7997570206553, 8251.422400431971, 8320.126386729367, 8355.916440835575, 8404.11756765543, 8419.410641255115, 5113.795448182068, 5170.405403378622, 5213.0, 5275.736213189325, 5281.392639846428, 3281.950770738441, 3392.2537041850755, 3444.7071998880124, 3593.982030089859, 3607.657272559129, 1012.8531362952405, 1013.1388319928002, 1043.6712435582767, 1045.9477026148738, 1100.1034157161887, 10854.34873907568, 10889.673309800719, 10911.290766100177, 10970.012698857061, 10976.0, 4562.247229207143, 4626.86119416418, 4703.0593881223895, 4729.881752956135, 4779.0, 9426.151646351862, 9504.04959504044, 9511.857322140173, 9525.761855953519, 9556.331056825955, 5228.529432351107, 5234.005538947587, 5264.394594621667, 5279.392869819943, 5281.181416880389, 4287.97856010718, 4293.141371725667, 4765.332853400527, 4796.952030479721, 4825.710457372569, 3824.2351529694174, 3837.9808100959317, 3887.8056097195026, 3929.2730844793805, 3932.9016774580864, 1271.4786937355825, 1282.198625858838, 1282.640860559045, 1292.6768307923019, 1297.241113948332, 773.1302284929454, 834.3742193354984, 871.1810897756089, 879.3536750488386, 912.6896855069308, 4648.0809852138245, 5177.800626859614, 5356.0894647909945, 5551.417101204396, 5751.424857514299, 429.5661382004198, 438.4497455693085, 451.76508215727637, 452.60171049476253, 465.3461886050105, 3831.3869780834934, 3832.904177395532, 3841.5966323536186, 3848.4804551385764, 3873.9225215496094, 7587.810304742426, 10108.483727440796, 10371.585136594602, 10379.84430233547, 10429.68710938673, 8486.859861851155, 10237.436940968319, 10309.226807989431, 10321.948390258001, 10351.741206469898, 7450.96274518624, 7734.729284475129, 7795.80510487231, 7954.72158474439, 7957.7612671619945, 5418.295621569171, 5502.752376143003, 5643.661380317194, 5687.857803554944, 5701.315842098975, 7290.4896657233485, 8318.667253310043, 8775.473471591726, 9113.863292050757, 9205.803245578032, 9198.95400522993, 9362.20421264173, 9433.575489103145, 9597.0, 9600.183984361129, 6521.0, 6730.8653826924165, 6968.581885086708, 7092.326229008236, 7213.783586492414, 7388.6675099621725, 7763.262490063435, 7904.960475197818, 7913.841723165619, 7914.089879663682, 7041.647917603997, 7219.711211551479, 7224.844024955982, 7229.349358557755, 7314.6708398121145, 6982.025440458537, 7016.789496315118, 7054.858902821812, 7136.393406560501, 7193.280671932765, 6744.123263975652, 6745.392914637706, 6770.695318710768, 6805.659717014229, 6815.2162501311, 4900.852974410774, 4924.433690125538, 4934.309196712535, 4962.975185124052, 4964.528369804862, 4866.343043689057, 4888.804447822047, 4917.6311776616185, 4944.703317800943, 5024.522670346311], "value": 4329.5987034904565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.64495283183754, 304.05590641059507, 305.51880787759285, 311.59803853029587, 314.46069989967214, 4858.829940952063, 5219.0856857356985, 5397.649152805065, 5429.809956651518, 5639.492445679883, 5164.244156986624, 5413.160960051188, 5546.667199967995, 5561.721913904299, 5568.7215639217975, 4155.750654960697, 4161.854335098272, 4166.79163042718, 4185.623293903548, 4195.4545909031785, 4234.534201237861, 4244.384564238184, 4245.893852653693, 4260.190563792885, 4275.558175668585, 3974.4782005440507, 4116.403121547374, 4176.081262122329, 4209.284421648327, 4244.448221731171, 3553.7867727936405, 3911.3155197840547, 4151.730137541081, 4189.539150693421, 4216.325387937915, 3628.3853685110134, 3664.670179683841, 3666.3400587894166, 3682.2819550187724, 3686.3364594373, 5252.579793616502, 5396.730163491809, 5409.513143817036, 5416.566674666017, 5455.990641731253, 4739.4075740532435, 4775.116603428377, 4809.591184749301, 4834.613230941516, 4854.68444551103, 5188.584913206934, 5456.053730985263, 5756.309242890838, 5792.8841423171725, 5834.533237341003, 3353.144948038259, 3464.359093567698, 3502.141975216088, 3566.6433356664384, 3993.800309984489, 5008.549230569266, 5266.499682530153, 5844.620099693507, 5922.466977972003, 6111.485895609998, 3675.1363429594026, 3961.4652021976663, 4008.7372477670024, 4093.406456063854, 4105.158442519277, 3341.8830340937952, 3428.8114153721285, 4157.8960525987095, 4219.480986844734, 4234.38601402801, 3609.888215393971, 4814.229723244334, 4814.542618451242, 4822.734749588806, 4829.275608658659, 5539.723013849291, 5780.393058728857, 5818.817943271273, 5931.40685931412, 5952.464278214981, 1045.2578669144991, 1121.7324423401503, 1184.5025089462447, 1288.6198571421453, 1320.2672516753294, 829.9004119505698, 847.4999750147442, 865.6407590849852, 897.3449381951082, 899.6536333511646, 4360.542143074995, 4828.275758636232, 5129.9743501283, 5186.896262074737, 5230.843074707765, 5069.340985671839, 5247.737613119329, 5665.365604993698, 5680.245081135646, 5764.5676574257095, 4007.238624661305, 4814.494478079821, 5143.379835351852, 5144.897102057937, 5193.480651934852, 2583.186296316654, 2732.521808683512, 2752.4632696623967, 2841.6447944007, 2960.0, 5355.259540649305, 5769.0, 5795.609053827051, 5802.506786923159, 5804.332501762267, 5129.717865517432, 5248.895022099613, 5575.916361254584, 5718.571107166979, 5843.941560584425, 5606.243157173811, 5815.33123690764, 5844.532437404998, 5865.530757539386, 5892.440218179266, 4724.858254252378, 5095.312132862016, 5516.806911758069, 5518.9304010995875, 5578.693171875586, 5104.33643626341, 5449.0, 5567.387587365306, 5577.577717681965, 5642.98426283273, 5278.973605132103, 5713.457221563945, 5732.942670573408, 5879.617824841372, 5892.263467066617, 3209.3902158589794, 3898.7855667938534, 3942.6254505821903, 3976.6818654508147, 3997.420374045805, 5088.567471764942, 5451.945480545144, 5712.88598723245, 5997.370276120944, 6035.637861728311, 5178.300929374486, 5546.4453554644215, 5805.767769289181, 5863.595925670455, 5936.703164841654, 5852.566121300139, 5856.3558008618165, 5859.882802343844, 5888.234529511136, 5925.288965324189, 5515.200295957144, 5713.885722285448, 5750.194972703741, 5753.713483454853, 5756.683382414008, 4898.632602554894, 5047.747612619428, 5063.443021267732, 5786.566007549535, 5902.911456328073, 5705.888821136206, 5752.28096487939, 5759.56803239767, 5787.990245072455, 5788.507976822018], "value": 4140.429304006084, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.4292622733789, 752.6086435053772, 754.3814072460584, 765.1690323471264, 773.8452309538087, 11721.472533735981, 14353.928230358846, 15066.472673456417, 15070.945033847634, 15088.019278746873, 13367.131136476122, 13419.19484830911, 13457.730845383086, 13467.93266033671, 13513.0, 784.945053846231, 796.303234669664, 819.8975128108987, 822.8560001999656, 827.9691783729265, 803.4616806739499, 811.5901469757794, 814.7840822182127, 815.2458975447717, 817.709713051867, 853.9829203415928, 857.2884505860152, 857.34413173922, 858.2061593026425, 858.3519442820661, 839.1943734015322, 845.8181490979407, 847.5338563789921, 847.6651722569582, 848.0798333807764, 636.3000699230809, 636.5830381100386, 637.4263163153153, 637.5568979559208, 637.7672149665385, 12415.503379864795, 12452.688682782913, 12509.124361294622, 12525.185862918972, 13469.86530134696, 12854.742905141939, 13144.60566183016, 13157.736845263042, 13200.603981880558, 13278.800817987738, 11000.669979900616, 11010.669679909615, 11224.66326010221, 11292.717682057932, 11410.315381077162, 3541.8406171722336, 3573.94639080414, 3578.731595130376, 3664.8167591620313, 3699.1676872703697, 60478.69760651213, 73527.0, 84361.15638843595, 85611.57194214051, 86687.0, 7858.607069646495, 9596.0, 9666.661666841626, 9948.35335703177, 10044.648437304659, 18756.81243187578, 19068.33260835864, 19160.616787664447, 19281.13234904433, 19288.90355548213, 6986.266442023616, 7438.256174382518, 7478.887816682645, 7546.24537546241, 7623.618819059025, 4223.598758117974, 4264.658827293809, 4357.586029327209, 4365.847195348148, 4460.420145381079, 3311.4701647736247, 3395.7453191010773, 3429.5884493860895, 3442.3115376924716, 3466.6013408457848, 566.8582854286428, 629.902365133405, 636.78667646339, 641.8587910659741, 649.4285029174297, 552.3785741041329, 628.3967391304399, 651.205529254309, 656.1273506236694, 678.6199728152152, 8837.734867953843, 9003.909960900439, 9008.47850929935, 9160.17558419732, 9405.0, 559.5495626021011, 583.3874431846544, 617.7961272779971, 639.2393052267787, 669.2432365041766, 4277.700560960703, 4444.8444304449185, 4446.377507149002, 4458.821647134078, 4481.887952801206, 93054.53472732592, 107471.0, 114455.42772286086, 116790.41604791922, 117906.0, 97316.0, 99263.0, 100962.0, 101692.0, 101807.0, 82138.3572328562, 85801.57099214464, 86751.56624216838, 87027.0, 88279.55860220658, 8562.828743424972, 8696.608652610521, 8735.825283494167, 8779.780505487413, 8839.442276853455, 47751.0, 56398.43601564096, 57052.71473642605, 57748.133777994204, 58347.12479312729, 77621.61189194018, 80430.0, 82024.58987705024, 82625.0, 84493.73259400991, 10630.468476575985, 10817.40504272241, 11021.486683818788, 11256.943715281699, 11347.773044538897, 10723.892761072602, 11873.406329683654, 11885.524579016743, 12431.316277604457, 12761.170523915916, 11736.94131529371, 11949.76100478003, 11995.220310679779, 12429.316387598768, 12437.315947622968, 11951.282923024646, 12010.0, 12287.877121228674, 12443.502259909865, 12450.256964025224, 11487.712807179887, 11801.940990294994, 11835.408229588314, 12316.938415307866, 12360.320182390054, 8081.980467141974, 8085.272325490499, 9670.56482458305, 9710.271729620448, 10037.849432258668, 7983.136610438597, 8501.659933602834, 10139.493025348556, 10157.288989770645, 10159.644412445528], "value": 7042.518916338809, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1574.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 119.0, 119.0, 125.0, 118.0, 119.0, 119.0, 117.0, 120.0], "value": 118.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 151.0, 143.0, 119.0, 112.0, 127.0, 147.0, 142.0, 140.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 199.0, 193.0, 202.0, 194.0, 198.0, 200.0, 192.0, 202.0, 223.0], "value": 199.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 56.0, 57.0, 83.0, 52.0, 65.0, 86.0, 84.0, 75.0, 59.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 68.0, 91.0, 71.0, 70.0, 73.0, 121.0, 88.0, 70.0, 90.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 89.0, 91.0, 100.0, 129.0, 88.0, 108.0, 130.0, 135.0, 88.0], "value": 106.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 102.0, 101.0, 155.0, 112.0, 173.0, 177.0, 101.0, 101.0], "value": 122.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 89.0, 91.0, 87.0, 89.0, 94.0, 87.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 57.0, 59.0, 59.0, 59.0, 60.0, 61.0, 59.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 89.0, 116.0, 116.0, 73.0, 70.0, 70.0, 72.0, 72.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 87.0, 86.0, 76.0, 93.0, 102.0, 103.0, 102.0, 77.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 136.0, 159.0, 135.0, 161.0, 165.0, 155.0, 161.0, 249.0, 147.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [234.0, 226.0, 162.0, 159.0, 163.0, 178.0, 143.0, 220.0, 233.0, 155.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 70.0, 68.0, 62.0, 84.0, 85.0, 88.0, 61.0], "value": 72.2, "unit": "score"}]}]} 02:29:06 INFO - Return code: 0 02:29:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:29:06 ERROR - # TBPL SUCCESS # 02:29:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:06 INFO - 2017-01-03 02:29:06.239 screenresolution[1662:11259] starting screenresolution argv=screenresolution get 02:29:06 INFO - 2017-01-03 02:29:06.241 screenresolution[1662:11259] Display 0: 1600x1200x32@60 02:29:06 INFO - 2017-01-03 02:29:06.247 screenresolution[1663:11262] starting screenresolution argv=screenresolution list 02:29:06 INFO - Available Modes on Display 0 02:29:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:06 INFO - Intel Iris: 02:29:06 INFO - Chipset Model: Intel Iris 02:29:06 INFO - Type: GPU 02:29:06 INFO - Bus: Built-In 02:29:06 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:06 INFO - Vendor: Intel (0x8086) 02:29:06 INFO - Device ID: 0x0a2e 02:29:06 INFO - Revision ID: 0x0009 02:29:06 INFO - Displays: 02:29:06 INFO - Display: 02:29:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:06 INFO - Display Serial Number: 1600x1200 60 02:29:06 INFO - Main Display: Yes 02:29:06 INFO - Mirror: Off 02:29:06 INFO - Online: Yes 02:29:06 INFO - Rotation: Supported 02:29:06 INFO - Return code: 0 02:29:06 INFO - Running post-action listener: _resource_record_post_action 02:29:06 INFO - [mozharness: 2017-01-03 10:29:06.489643Z] Finished run-tests step (success) 02:29:06 INFO - Running post-run listener: _resource_record_post_run 02:29:06 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 33959936; Write bytes: 658162176; Read time: 218; Write time: 1917 02:29:06 INFO - TinderboxPrint: CPU usage
54.3% 02:29:06 INFO - TinderboxPrint: I/O read bytes / time
33,959,936 / 218 02:29:06 INFO - TinderboxPrint: I/O write bytes / time
658,162,176 / 1,917 02:29:06 INFO - TinderboxPrint: CPU idle
2,213.0 (45.7%) 02:29:06 INFO - TinderboxPrint: CPU system
977.6 (20.2%) 02:29:06 INFO - TinderboxPrint: CPU user
1,655.0 (34.2%) 02:29:06 INFO - TinderboxPrint: Swap in / out
378,785,792 / 0 02:29:06 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348432896; Write bytes: 347083776; Read time: 27678; Write time: 733 02:29:06 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31375360; Write bytes: 303803904; Read time: 182; Write time: 1156 02:29:06 INFO - Running post-run listener: _upload_blobber_files 02:29:06 INFO - Blob upload gear active. 02:29:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:29:06 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 02:29:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:29:07 INFO - (blobuploader) - INFO - Open directory for files ... 02:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:29:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:08 INFO - (blobuploader) - INFO - Done attempting. 02:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:29:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:09 INFO - (blobuploader) - INFO - Done attempting. 02:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:11 INFO - (blobuploader) - INFO - Done attempting. 02:29:11 INFO - (blobuploader) - INFO - Iteration through files over. 02:29:11 INFO - Return code: 0 02:29:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:29:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:29:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bd9f0e346320f927c213d4db2d8a7f3885fef079e9df9d4efce17d33eac4b9ec038e9c510817677f48958e97e1990b8b8bd0006910852a768e807f2deddf99e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a96b342364471641838d7ddbaf40fd596ef00f66dbf6895192832a54bc1e496cba579741f292944dca0a94c9b6faa201b552892528bf45d7f5614e05084db45", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0fa5fe5c4649c251e56792e908b7c89085ecd64dee19838235b7a81c2497fee3e669269754e8080134ae2572ae51cf11ff6efc27e0e7c34251e04b9ffca56e"} 02:29:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:29:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:29:11 INFO - Contents: 02:29:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bd9f0e346320f927c213d4db2d8a7f3885fef079e9df9d4efce17d33eac4b9ec038e9c510817677f48958e97e1990b8b8bd0006910852a768e807f2deddf99e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a96b342364471641838d7ddbaf40fd596ef00f66dbf6895192832a54bc1e496cba579741f292944dca0a94c9b6faa201b552892528bf45d7f5614e05084db45", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0fa5fe5c4649c251e56792e908b7c89085ecd64dee19838235b7a81c2497fee3e669269754e8080134ae2572ae51cf11ff6efc27e0e7c34251e04b9ffca56e"} 02:29:11 INFO - Running post-run listener: copy_logs_to_upload_dir 02:29:11 INFO - Copying logs to upload dir... 02:29:11 INFO - mkdir: /builds/slave/test/build/upload/logs 02:29:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.092695 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-03 02:29:11.597435) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 02:29:11.600842) ========= 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.smtX91A9Qu/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.b6dZjhH069/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-Aurora/sha512/8bd9f0e346320f927c213d4db2d8a7f3885fef079e9df9d4efce17d33eac4b9ec038e9c510817677f48958e97e1990b8b8bd0006910852a768e807f2deddf99e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a96b342364471641838d7ddbaf40fd596ef00f66dbf6895192832a54bc1e496cba579741f292944dca0a94c9b6faa201b552892528bf45d7f5614e05084db45", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0fa5fe5c4649c251e56792e908b7c89085ecd64dee19838235b7a81c2497fee3e669269754e8080134ae2572ae51cf11ff6efc27e0e7c34251e04b9ffca56e"} build_url:https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011034 build_url: 'https://queue.taskcluster.net/v1/task/KsvLwnACQACNXfaDAedDOw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bd9f0e346320f927c213d4db2d8a7f3885fef079e9df9d4efce17d33eac4b9ec038e9c510817677f48958e97e1990b8b8bd0006910852a768e807f2deddf99e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a96b342364471641838d7ddbaf40fd596ef00f66dbf6895192832a54bc1e496cba579741f292944dca0a94c9b6faa201b552892528bf45d7f5614e05084db45", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd0fa5fe5c4649c251e56792e908b7c89085ecd64dee19838235b7a81c2497fee3e669269754e8080134ae2572ae51cf11ff6efc27e0e7c34251e04b9ffca56e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 02:29:11.634366) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 02:29:11.634686) ========= 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.smtX91A9Qu/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.b6dZjhH069/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.005227 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 02:29:11.682916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 02:29:11.683255) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 02:29:11.683608) ========= ========= Total master_lag: 0.18 =========