builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0216 starttime: 1479500919.39 results: success (0) buildid: 20161118075229 builduid: 6156f27cae584ebea3ab11db32eb26e9 revision: a103e1713a2eda3b4e88d71df82d140487c8db87 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.386772) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.387246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.387527) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.415348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.415731) ========= 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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-18 12:28:39-- 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% 20.7M=0.001s 2016-11-18 12:28:39 (20.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085897 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.523862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.524222) ========= 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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.019615 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.573173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:39.573550) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a103e1713a2eda3b4e88d71df82d140487c8db87 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a103e1713a2eda3b4e88d71df82d140487c8db87 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fn6be3jF8A/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.hmN3mwY6bi/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-18 12:28:39,658 truncating revision to first 12 chars 2016-11-18 12:28:39,658 Setting DEBUG logging. 2016-11-18 12:28:39,658 attempt 1/10 2016-11-18 12:28:39,658 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a103e1713a2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 12:28:40,277 unpacking tar archive at: mozilla-central-a103e1713a2e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.876322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:40.472312) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:40.472656) ========= script_repo_revision: a103e1713a2eda3b4e88d71df82d140487c8db87 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:40.473065) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:40.473334) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 12:28:40.492871) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-18 12:28:40.493444) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fn6be3jF8A/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.hmN3mwY6bi/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:28:40 INFO - MultiFileLogger online at 20161118 12:28:40 in /builds/slave/test 12:28:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:28:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:28:40 INFO - {'append_to_log': False, 12:28:40 INFO - 'base_work_dir': '/builds/slave/test', 12:28:40 INFO - 'blob_upload_branch': 'Firefox', 12:28:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:28:40 INFO - 'branch': 'Firefox', 12:28:40 INFO - 'buildbot_json_path': 'buildprops.json', 12:28:40 INFO - 'config_files': ('talos/mac_config.py',), 12:28:40 INFO - 'default_actions': ('clobber', 12:28:40 INFO - 'read-buildbot-config', 12:28:40 INFO - 'download-and-extract', 12:28:40 INFO - 'populate-webroot', 12:28:40 INFO - 'create-virtualenv', 12:28:40 INFO - 'install', 12:28:40 INFO - 'run-tests'), 12:28:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:28:40 INFO - 'download_minidump_stackwalk': True, 12:28:40 INFO - 'download_symbols': 'ondemand', 12:28:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:28:40 INFO - 'tooltool.py': '/tools/tooltool.py', 12:28:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:28:40 INFO - '/tools/misc-python/virtualenv.py')}, 12:28:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:28:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:28:40 INFO - 'installer_path': 'installer.exe', 12:28:40 INFO - 'log_level': 'info', 12:28:40 INFO - 'log_name': 'talos', 12:28:40 INFO - 'log_to_console': True, 12:28:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:28:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:28:40 INFO - 'opt_config_files': (), 12:28:40 INFO - 'pip_index': False, 12:28:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:28:40 INFO - 'cmd': ('bash', 12:28:40 INFO - '-c', 12:28:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:28:40 INFO - 'enabled': True, 12:28:40 INFO - 'halt_on_failure': False, 12:28:40 INFO - 'name': 'check_screen_resolution'},), 12:28:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:28:40 INFO - 'cmd': ('bash', 12:28:40 INFO - '-c', 12:28:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:28:40 INFO - 'enabled': True, 12:28:40 INFO - 'halt_on_failure': False, 12:28:40 INFO - 'name': 'check_screen_resolution'},), 12:28:40 INFO - 'run_cmd_checks_enabled': True, 12:28:40 INFO - 'sps_profile': False, 12:28:40 INFO - 'sps_profile_interval': 0, 12:28:40 INFO - 'suite': 'dromaeojs-e10s', 12:28:40 INFO - 'system_bits': '32', 12:28:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:28:40 INFO - 'title': 't-yosemite-r7-0216', 12:28:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:28:40 INFO - 'use_talos_json': True, 12:28:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:28:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:28:40 INFO - 'work_dir': 'build'} 12:28:40 INFO - [mozharness: 2016-11-18 20:28:40.693283Z] Running clobber step. 12:28:40 INFO - Running pre-action listener: _resource_record_pre_action 12:28:40 INFO - Running main action method: clobber 12:28:40 INFO - rmtree: /builds/slave/test/build 12:28:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:28:42 INFO - Running post-action listener: _resource_record_post_action 12:28:42 INFO - [mozharness: 2016-11-18 20:28:42.775412Z] Finished clobber step (success) 12:28:42 INFO - [mozharness: 2016-11-18 20:28:42.775538Z] Running read-buildbot-config step. 12:28:42 INFO - Running pre-action listener: _resource_record_pre_action 12:28:42 INFO - Running main action method: read_buildbot_config 12:28:42 INFO - Using buildbot properties: 12:28:42 INFO - { 12:28:42 INFO - "project": "", 12:28:42 INFO - "product": "firefox", 12:28:42 INFO - "script_repo_revision": "production", 12:28:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:28:42 INFO - "repository": "", 12:28:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:28:42 INFO - "buildid": "20161118075229", 12:28:42 INFO - "pgo_build": "False", 12:28:42 INFO - "basedir": "/builds/slave/test", 12:28:42 INFO - "buildnumber": 31, 12:28:42 INFO - "slavename": "t-yosemite-r7-0216", 12:28:42 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:28:42 INFO - "platform": "yosemite_r7", 12:28:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:28:42 INFO - "branch": "mozilla-central", 12:28:42 INFO - "repo_path": "mozilla-central", 12:28:42 INFO - "revision": "a103e1713a2eda3b4e88d71df82d140487c8db87", 12:28:42 INFO - "stage_platform": "macosx64", 12:28:42 INFO - "builduid": "6156f27cae584ebea3ab11db32eb26e9", 12:28:42 INFO - "slavebuilddir": "test" 12:28:42 INFO - } 12:28:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:28:42 INFO - Running post-action listener: _resource_record_post_action 12:28:42 INFO - [mozharness: 2016-11-18 20:28:42.778864Z] Finished read-buildbot-config step (success) 12:28:42 INFO - [mozharness: 2016-11-18 20:28:42.778982Z] Running download-and-extract step. 12:28:42 INFO - Running pre-action listener: _resource_record_pre_action 12:28:42 INFO - Running main action method: download_and_extract 12:28:42 INFO - mkdir: /builds/slave/test/build/tests 12:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:42 INFO - trying https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:28:42 INFO - Downloading https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:28:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 12:28:43 INFO - Downloaded 1199 bytes. 12:28:43 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:28:43 INFO - Using the following test package requirements: 12:28:43 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:28:43 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:28:43 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:28:43 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'jsshell-mac.zip'], 12:28:43 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:28:43 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:28:43 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:28:43 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:28:43 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:28:43 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:28:43 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:28:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:28:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:28:43 INFO - Fetch https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:28:44 INFO - Http code: 200 12:28:44 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 12:28:44 INFO - x-amz-cf-id: IXAXFMd2Cp09gthFhS40V0m0Ut232h9ytsJVqqTNWpuRtxMpvF-KIg== 12:28:44 INFO - x-amz-version-id: RUc9GKYjTFNw9.T0hVQu7_6GVJy_1CbL 12:28:44 INFO - Expected file size: 16233422 12:28:44 INFO - Obtained file size: 16233422 12:28:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:28:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:28:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:28:51 INFO - Fetch https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:28:51 INFO - Http code: 200 12:28:51 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 12:28:51 INFO - x-amz-cf-id: r61Sv8E52mvxYPRwxjkjxr7vtmv8vHUbK0OlKfU3VIz_WXD1luIDVw== 12:28:51 INFO - x-amz-version-id: RUc9GKYjTFNw9.T0hVQu7_6GVJy_1CbL 12:28:52 INFO - Expected file size: 16233422 12:28:52 INFO - Obtained file size: 16233422 12:28:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:28:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:28:58 INFO - Fetch https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:28:58 INFO - Http code: 200 12:28:58 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 12:28:58 INFO - x-amz-cf-id: sDAjVwXsDaPIaIMT00hwCufciouN_XDnzslmCMT3zzIKgItIWVVkxA== 12:28:58 INFO - x-amz-version-id: bO6aCAH2wfanU98QfeOOplkF0MlQfgsy 12:28:59 INFO - Expected file size: 14409392 12:28:59 INFO - Obtained file size: 14409392 12:28:59 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')]} 12:28:59 INFO - trying https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:28:59 INFO - Downloading https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:28:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 12:29:02 INFO - Downloaded 107384071 bytes. 12:29:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:29:02 INFO - mkdir: /builds/slave/test/properties 12:29:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:29:02 INFO - Writing to file /builds/slave/test/properties/build_url 12:29:02 INFO - Contents: 12:29:02 INFO - build_url:https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:29:03 INFO - Running post-action listener: _resource_record_post_action 12:29:03 INFO - Running post-action listener: set_extra_try_arguments 12:29:03 INFO - [mozharness: 2016-11-18 20:29:03.231860Z] Finished download-and-extract step (success) 12:29:03 INFO - [mozharness: 2016-11-18 20:29:03.232008Z] Running populate-webroot step. 12:29:03 INFO - Running pre-action listener: _resource_record_pre_action 12:29:03 INFO - Running main action method: populate_webroot 12:29:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:29:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:29:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:29:03 INFO - '--tppagecycles', 12:29:03 INFO - '7'], 12:29:03 INFO - 'tests': ['tsvgm']}}, 12:29:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:29:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:29:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:29:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:29:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:29:03 INFO - 'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:29:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:29:03 INFO - 'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['damp', 'tps']}, 12:29:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:29:03 INFO - 'tests': ['damp', 'tps']}, 12:29:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['dromaeo_dom']}, 12:29:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:29:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['basic_compositor_video']}, 12:29:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:29:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['a11yr', 12:29:03 INFO - 'ts_paint', 12:29:03 INFO - 'tpaint', 12:29:03 INFO - 'sessionrestore', 12:29:03 INFO - 'sessionrestore_no_auto_restore', 12:29:03 INFO - 'tabpaint']}, 12:29:03 INFO - 'other-e10s': {'tests': ['a11yr', 12:29:03 INFO - 'ts_paint', 12:29:03 INFO - 'tpaint', 12:29:03 INFO - 'sessionrestore', 12:29:03 INFO - 'sessionrestore_no_auto_restore', 12:29:03 INFO - 'tabpaint']}, 12:29:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:29:03 INFO - 'ts_paint', 12:29:03 INFO - 'tpaint', 12:29:03 INFO - 'sessionrestore', 12:29:03 INFO - 'sessionrestore_no_auto_restore', 12:29:03 INFO - 'tabpaint']}, 12:29:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:29:03 INFO - 'ts_paint', 12:29:03 INFO - 'tpaint', 12:29:03 INFO - 'sessionrestore', 12:29:03 INFO - 'sessionrestore_no_auto_restore', 12:29:03 INFO - 'tabpaint']}, 12:29:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['a11yr', 12:29:03 INFO - 'ts_paint', 12:29:03 INFO - 'tpaint', 12:29:03 INFO - 'sessionrestore', 12:29:03 INFO - 'sessionrestore_no_auto_restore', 12:29:03 INFO - 'tabpaint']}, 12:29:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['a11yr', 12:29:03 INFO - 'ts_paint', 12:29:03 INFO - 'tpaint', 12:29:03 INFO - 'sessionrestore', 12:29:03 INFO - 'sessionrestore_no_auto_restore', 12:29:03 INFO - 'tabpaint']}, 12:29:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['tsvgx', 12:29:03 INFO - 'tsvgr_opacity', 12:29:03 INFO - 'tart', 12:29:03 INFO - 'tscrollx', 12:29:03 INFO - 'cart']}, 12:29:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:29:03 INFO - 'tsvgr_opacity', 12:29:03 INFO - 'tart', 12:29:03 INFO - 'tscrollx', 12:29:03 INFO - 'cart']}, 12:29:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:29:03 INFO - 'talos_options': ['--disable-e10s'], 12:29:03 INFO - 'tests': ['tp5o']}, 12:29:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:29:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:29:03 INFO - 'talos_options': ['--disable-e10s', 12:29:03 INFO - '--xperf_path', 12:29:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:03 INFO - 'tests': ['tp5n']}, 12:29:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:29:03 INFO - 'talos_options': ['--xperf_path', 12:29:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:03 INFO - 'tests': ['tp5n']}}, 12:29:03 INFO - 'talos.zip': {'path': '', 12:29:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:29:03 INFO - Running post-action listener: _resource_record_post_action 12:29:03 INFO - [mozharness: 2016-11-18 20:29:03.250319Z] Finished populate-webroot step (success) 12:29:03 INFO - [mozharness: 2016-11-18 20:29:03.250448Z] Running create-virtualenv step. 12:29:03 INFO - Running pre-action listener: _resource_record_pre_action 12:29:03 INFO - Running main action method: create_virtualenv 12:29:03 INFO - Creating virtualenv /builds/slave/test/build/venv 12:29:03 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 12:29:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:29:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:29:03 INFO - Using real prefix '/tools/python27' 12:29:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:29:04 INFO - Installing distribute.............................................................................................................................................................................................done. 12:29:06 INFO - Installing pip.................done. 12:29:06 INFO - Return code: 0 12:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:29:07 INFO - Reading from file tmpfile_stdout 12:29:07 INFO - Output received: 12:29:07 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) 12:29:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:07 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:07 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 12:29:07 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 12:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:07 INFO - 'HOME': '/Users/cltbld', 12:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:07 INFO - 'LOGNAME': 'cltbld', 12:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:07 INFO - 'MOZ_NO_REMOTE': '1', 12:29:07 INFO - 'NO_EM_RESTART': '1', 12:29:07 INFO - 'PAGER': '/bin/cat', 12:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:07 INFO - 'PWD': '/builds/slave/test', 12:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:07 INFO - 'SHELL': '/bin/bash', 12:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:07 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:07 INFO - 'USER': 'cltbld', 12:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:07 INFO - 'XPC_FLAGS': '0x0', 12:29:07 INFO - 'XPC_SERVICE_NAME': '0', 12:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:07 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 12:29:07 INFO - Cleaning up... 12:29:07 INFO - Return code: 0 12:29:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:07 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:07 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 12:29:07 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 12:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:07 INFO - 'HOME': '/Users/cltbld', 12:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:07 INFO - 'LOGNAME': 'cltbld', 12:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:07 INFO - 'MOZ_NO_REMOTE': '1', 12:29:07 INFO - 'NO_EM_RESTART': '1', 12:29:07 INFO - 'PAGER': '/bin/cat', 12:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:07 INFO - 'PWD': '/builds/slave/test', 12:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:07 INFO - 'SHELL': '/bin/bash', 12:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:07 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:07 INFO - 'USER': 'cltbld', 12:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:07 INFO - 'XPC_FLAGS': '0x0', 12:29:07 INFO - 'XPC_SERVICE_NAME': '0', 12:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:07 INFO - Downloading/unpacking psutil>=3.1.1 12:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:29:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:11 INFO - Installing collected packages: psutil 12:29:11 INFO - Running setup.py install for psutil 12:29:11 INFO - building 'psutil._psutil_osx' extension 12:29:11 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 12:29:11 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 12:29:11 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 12:29:11 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 12:29:11 INFO - building 'psutil._psutil_posix' extension 12:29:11 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 12:29:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:29:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:29:11 INFO - ^ 12:29:11 INFO - 1 warning generated. 12:29:11 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 12:29:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:11 INFO - Successfully installed psutil 12:29:11 INFO - Cleaning up... 12:29:12 INFO - Return code: 0 12:29:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:29:12 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')]} 12:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:12 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')]} 12:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:12 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:12 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 12:29:12 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 12:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:12 INFO - 'HOME': '/Users/cltbld', 12:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:12 INFO - 'LOGNAME': 'cltbld', 12:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:12 INFO - 'MOZ_NO_REMOTE': '1', 12:29:12 INFO - 'NO_EM_RESTART': '1', 12:29:12 INFO - 'PAGER': '/bin/cat', 12:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:12 INFO - 'PWD': '/builds/slave/test', 12:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:12 INFO - 'SHELL': '/bin/bash', 12:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:12 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:12 INFO - 'USER': 'cltbld', 12:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:12 INFO - 'XPC_FLAGS': '0x0', 12:29:12 INFO - 'XPC_SERVICE_NAME': '0', 12:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:29:12 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 12:29:12 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 12:29:12 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 12:29:12 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 12:29:12 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 12:29:12 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 12:29:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:29:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:29:14 INFO - Installing collected packages: mozsystemmonitor 12:29:14 INFO - Running setup.py install for mozsystemmonitor 12:29:15 INFO - Successfully installed mozsystemmonitor 12:29:15 INFO - Cleaning up... 12:29:15 INFO - Return code: 0 12:29:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:29:15 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')]} 12:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:15 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')]} 12:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:15 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:15 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 12:29:15 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 12:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:15 INFO - 'HOME': '/Users/cltbld', 12:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:15 INFO - 'LOGNAME': 'cltbld', 12:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:15 INFO - 'MOZ_NO_REMOTE': '1', 12:29:15 INFO - 'NO_EM_RESTART': '1', 12:29:15 INFO - 'PAGER': '/bin/cat', 12:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:15 INFO - 'PWD': '/builds/slave/test', 12:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:15 INFO - 'SHELL': '/bin/bash', 12:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:15 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:15 INFO - 'USER': 'cltbld', 12:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:15 INFO - 'XPC_FLAGS': '0x0', 12:29:15 INFO - 'XPC_SERVICE_NAME': '0', 12:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:15 INFO - Downloading/unpacking jsonschema==2.5.1 12:29:15 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 12:29:15 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 12:29:15 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 12:29:15 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 12:29:15 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 12:29:15 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 12:29:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:29:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:29:17 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 12:29:17 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 12:29:17 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 12:29:17 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 12:29:17 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 12:29:17 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 12:29:18 INFO - Downloading functools32-3.2.3-2.tar.gz 12:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:29:18 INFO - warning: no files found matching '*.txt' 12:29:18 INFO - no previously-included directories found matching 'build' 12:29:18 INFO - no previously-included directories found matching 'dist' 12:29:18 INFO - no previously-included directories found matching '.git*' 12:29:18 INFO - Installing collected packages: jsonschema, functools32 12:29:18 INFO - Running setup.py install for functools32 12:29:18 INFO - warning: no files found matching '*.txt' 12:29:18 INFO - no previously-included directories found matching 'build' 12:29:18 INFO - no previously-included directories found matching 'dist' 12:29:18 INFO - no previously-included directories found matching '.git*' 12:29:18 INFO - Successfully installed jsonschema functools32 12:29:18 INFO - Cleaning up... 12:29:18 INFO - Return code: 0 12:29:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:18 INFO - 'HOME': '/Users/cltbld', 12:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:18 INFO - 'LOGNAME': 'cltbld', 12:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:18 INFO - 'MOZ_NO_REMOTE': '1', 12:29:18 INFO - 'NO_EM_RESTART': '1', 12:29:18 INFO - 'PAGER': '/bin/cat', 12:29:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:18 INFO - 'PWD': '/builds/slave/test', 12:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:18 INFO - 'SHELL': '/bin/bash', 12:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:18 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:18 INFO - 'USER': 'cltbld', 12:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:18 INFO - 'XPC_FLAGS': '0x0', 12:29:18 INFO - 'XPC_SERVICE_NAME': '0', 12:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:29:19 INFO - Cleaning up... 12:29:19 INFO - Return code: 0 12:29:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:19 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:19 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 12:29:19 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 12:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:19 INFO - 'HOME': '/Users/cltbld', 12:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:19 INFO - 'LOGNAME': 'cltbld', 12:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:19 INFO - 'MOZ_NO_REMOTE': '1', 12:29:19 INFO - 'NO_EM_RESTART': '1', 12:29:19 INFO - 'PAGER': '/bin/cat', 12:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:19 INFO - 'PWD': '/builds/slave/test', 12:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:19 INFO - 'SHELL': '/bin/bash', 12:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:19 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:19 INFO - 'USER': 'cltbld', 12:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:19 INFO - 'XPC_FLAGS': '0x0', 12:29:19 INFO - 'XPC_SERVICE_NAME': '0', 12:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:19 INFO - Downloading/unpacking blobuploader==1.2.4 12:29:19 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 12:29:19 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 12:29:19 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 12:29:19 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 12:29:19 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 12:29:19 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 12:29:21 INFO - Downloading blobuploader-1.2.4.tar.gz 12:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:29:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:29:21 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 12:29:21 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 12:29:21 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 12:29:21 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 12:29:21 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 12:29:21 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 12:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:29:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:29:22 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 12:29:22 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 12:29:22 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 12:29:22 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 12:29:22 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 12:29:22 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 12:29:22 INFO - Downloading docopt-0.6.1.tar.gz 12:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:29:23 INFO - Installing collected packages: blobuploader, requests, docopt 12:29:23 INFO - Running setup.py install for blobuploader 12:29:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:29:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:29:23 INFO - Running setup.py install for requests 12:29:23 INFO - Running setup.py install for docopt 12:29:23 INFO - Successfully installed blobuploader requests docopt 12:29:23 INFO - Cleaning up... 12:29:23 INFO - Return code: 0 12:29:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:23 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')]} 12:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:23 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')]} 12:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:23 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:23 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 12:29:23 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 12:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:23 INFO - 'HOME': '/Users/cltbld', 12:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:23 INFO - 'LOGNAME': 'cltbld', 12:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:23 INFO - 'MOZ_NO_REMOTE': '1', 12:29:23 INFO - 'NO_EM_RESTART': '1', 12:29:23 INFO - 'PAGER': '/bin/cat', 12:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:23 INFO - 'PWD': '/builds/slave/test', 12:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:23 INFO - 'SHELL': '/bin/bash', 12:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:23 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:23 INFO - 'USER': 'cltbld', 12:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:23 INFO - 'XPC_FLAGS': '0x0', 12:29:23 INFO - 'XPC_SERVICE_NAME': '0', 12:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-y_fFvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-FI5Un4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-JsrqJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-GId7lV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-XBXasC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-bJwSww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-pa0j5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-c18ir5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-8pTgs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-uSvNci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-DETr9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-VPoJrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-aFCiFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-ByEgLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-0CIgjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-aB6Jot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-j8DzUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:29:25 INFO - Running setup.py install for manifestparser 12:29:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:29:26 INFO - Running setup.py install for mozcrash 12:29:26 INFO - Running setup.py install for mozdebug 12:29:26 INFO - Running setup.py install for mozdevice 12:29:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:29:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:29:26 INFO - Running setup.py install for mozfile 12:29:26 INFO - Running setup.py install for mozhttpd 12:29:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:29:26 INFO - Running setup.py install for mozinfo 12:29:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:29:26 INFO - Running setup.py install for mozInstall 12:29:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Running setup.py install for mozleak 12:29:27 INFO - Running setup.py install for mozlog 12:29:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Running setup.py install for moznetwork 12:29:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Running setup.py install for mozprocess 12:29:27 INFO - Running setup.py install for mozprofile 12:29:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:29:27 INFO - Running setup.py install for mozrunner 12:29:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:29:28 INFO - Running setup.py install for mozscreenshot 12:29:28 INFO - Running setup.py install for moztest 12:29:28 INFO - Running setup.py install for mozversion 12:29:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:29:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:29:28 INFO - Cleaning up... 12:29:28 INFO - Return code: 0 12:29:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:28 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')]} 12:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:28 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')]} 12:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:28 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:28 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 12:29:28 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 12:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:28 INFO - 'HOME': '/Users/cltbld', 12:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:28 INFO - 'LOGNAME': 'cltbld', 12:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:28 INFO - 'MOZ_NO_REMOTE': '1', 12:29:28 INFO - 'NO_EM_RESTART': '1', 12:29:28 INFO - 'PAGER': '/bin/cat', 12:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:28 INFO - 'PWD': '/builds/slave/test', 12:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:28 INFO - 'SHELL': '/bin/bash', 12:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:28 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:28 INFO - 'USER': 'cltbld', 12:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:28 INFO - 'XPC_FLAGS': '0x0', 12:29:28 INFO - 'XPC_SERVICE_NAME': '0', 12:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:28 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-2m338c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:28 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)) 12:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:28 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-300Z_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-RlLBx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-6HwHBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-o13i9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-xz_Vv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-QolmZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-bct5gG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-NeMpSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-HPpBSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-aFFyfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:29 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)) 12:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Ipa8Dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:30 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)) 12:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-tji2Fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:30 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)) 12:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-xiENaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:30 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)) 12:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-46TBlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:30 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)) 12:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-XRenQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:30 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)) 12:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-pP_Vnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:30 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)) 12:29:30 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)) 12:29:30 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)) 12:29:30 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)) 12:29:30 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:29:30 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:29:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29:30 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 12:29:30 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 12:29:30 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 12:29:30 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 12:29:30 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 12:29:30 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 12:29:33 INFO - Downloading blessings-1.6.tar.gz 12:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:29:33 INFO - Installing collected packages: blessings 12:29:33 INFO - Running setup.py install for blessings 12:29:33 INFO - Successfully installed blessings 12:29:33 INFO - Cleaning up... 12:29:33 INFO - Return code: 0 12:29:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:29:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:33 INFO - Reading from file tmpfile_stdout 12:29:33 INFO - Current package versions: 12:29:33 INFO - blessings == 1.6 12:29:33 INFO - blobuploader == 1.2.4 12:29:33 INFO - docopt == 0.6.1 12:29:33 INFO - functools32 == 3.2.3-2 12:29:33 INFO - jsonschema == 2.5.1 12:29:33 INFO - manifestparser == 1.1 12:29:33 INFO - mozInstall == 1.12 12:29:33 INFO - mozcrash == 1.0 12:29:33 INFO - mozdebug == 0.1 12:29:33 INFO - mozdevice == 0.49 12:29:33 INFO - mozfile == 1.2 12:29:33 INFO - mozhttpd == 0.7 12:29:33 INFO - mozinfo == 0.9 12:29:33 INFO - mozleak == 0.1 12:29:33 INFO - mozlog == 3.4 12:29:33 INFO - moznetwork == 0.27 12:29:33 INFO - mozprocess == 0.23 12:29:33 INFO - mozprofile == 0.28 12:29:33 INFO - mozrunner == 6.13 12:29:33 INFO - mozscreenshot == 0.1 12:29:33 INFO - mozsystemmonitor == 0.3 12:29:33 INFO - moztest == 0.7 12:29:33 INFO - mozversion == 1.4 12:29:33 INFO - psutil == 3.1.1 12:29:33 INFO - requests == 1.2.3 12:29:33 INFO - wsgiref == 0.1.2 12:29:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:33 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')]} 12:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:33 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')]} 12:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:33 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 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29:33 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 12:29:33 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 12:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:33 INFO - 'HOME': '/Users/cltbld', 12:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:33 INFO - 'LOGNAME': 'cltbld', 12:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:33 INFO - 'MOZ_NO_REMOTE': '1', 12:29:33 INFO - 'NO_EM_RESTART': '1', 12:29:33 INFO - 'PAGER': '/bin/cat', 12:29:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:33 INFO - 'PWD': '/builds/slave/test', 12:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:33 INFO - 'SHELL': '/bin/bash', 12:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:33 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:33 INFO - 'USER': 'cltbld', 12:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:33 INFO - 'XPC_FLAGS': '0x0', 12:29:33 INFO - 'XPC_SERVICE_NAME': '0', 12:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 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)) 12:29:34 INFO - Cleaning up... 12:29:34 INFO - Return code: 0 12:29:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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')]} 12:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109eb13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e27570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd81d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, '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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 12:29: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'] in /builds/slave/test/build 12:29: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 12:29:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:29:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:34 INFO - 'HOME': '/Users/cltbld', 12:29:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:34 INFO - 'LOGNAME': 'cltbld', 12:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:34 INFO - 'MOZ_NO_REMOTE': '1', 12:29:34 INFO - 'NO_EM_RESTART': '1', 12:29:34 INFO - 'PAGER': '/bin/cat', 12:29:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:34 INFO - 'PWD': '/builds/slave/test', 12:29:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:34 INFO - 'SHELL': '/bin/bash', 12:29:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:29:34 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:29:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:34 INFO - 'USER': 'cltbld', 12:29:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:34 INFO - 'XPC_FLAGS': '0x0', 12:29:34 INFO - 'XPC_SERVICE_NAME': '0', 12:29:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:29:34 INFO - Cleaning up... 12:29:34 INFO - Return code: 0 12:29:34 INFO - Running post-action listener: _resource_record_post_action 12:29:34 INFO - Running post-action listener: _start_resource_monitoring 12:29:34 INFO - Starting resource monitoring. 12:29:34 INFO - [mozharness: 2016-11-18 20:29:34.350901Z] Finished create-virtualenv step (success) 12:29:34 INFO - [mozharness: 2016-11-18 20:29:34.351888Z] Running install step. 12:29:34 INFO - Running pre-action listener: _resource_record_pre_action 12:29:34 INFO - Running main action method: install 12:29:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:34 INFO - Reading from file tmpfile_stdout 12:29:34 INFO - Detecting whether we're running mozinstall >=1.0... 12:29:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:29:34 INFO - Reading from file tmpfile_stdout 12:29:34 INFO - Output received: 12:29:34 INFO - Usage: mozinstall [options] installer 12:29:34 INFO - Options: 12:29:34 INFO - -h, --help show this help message and exit 12:29:34 INFO - -d DEST, --destination=DEST 12:29:34 INFO - Directory to install application into. [default: 12:29:34 INFO - "/builds/slave/test"] 12:29:34 INFO - --app=APP Application being installed. [default: firefox] 12:29:34 INFO - mkdir: /builds/slave/test/build/application 12:29:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:30:07 INFO - Reading from file tmpfile_stdout 12:30:07 INFO - Output received: 12:30:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:30:07 INFO - Running post-action listener: _resource_record_post_action 12:30:07 INFO - [mozharness: 2016-11-18 20:30:07.108511Z] Finished install step (success) 12:30:07 INFO - [mozharness: 2016-11-18 20:30:07.108671Z] Running run-tests step. 12:30:07 INFO - Running pre-action listener: _resource_record_pre_action 12:30:07 INFO - Running main action method: run_tests 12:30:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:30:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:30:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:30:07 INFO - 2016-11-18 12:30:07.122 screenresolution[2159:18181] starting screenresolution argv=screenresolution get 12:30:07 INFO - 2016-11-18 12:30:07.125 screenresolution[2159:18181] Display 0: 1600x1200x32@60 12:30:07 INFO - 2016-11-18 12:30:07.130 screenresolution[2160:18184] starting screenresolution argv=screenresolution list 12:30:07 INFO - Available Modes on Display 0 12:30:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:30:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:30:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:30:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:30:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:30:07 INFO - Intel Iris: 12:30:07 INFO - Chipset Model: Intel Iris 12:30:07 INFO - Type: GPU 12:30:07 INFO - Bus: Built-In 12:30:07 INFO - VRAM (Dynamic, Max): 1536 MB 12:30:07 INFO - Vendor: Intel (0x8086) 12:30:07 INFO - Device ID: 0x0a2e 12:30:07 INFO - Revision ID: 0x0009 12:30:07 INFO - Displays: 12:30:07 INFO - Display: 12:30:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:30:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:30:07 INFO - Display Serial Number: 1600x1200 60 12:30:07 INFO - Main Display: Yes 12:30:07 INFO - Mirror: Off 12:30:07 INFO - Online: Yes 12:30:07 INFO - Rotation: Supported 12:30:07 INFO - Return code: 0 12:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:30:07 INFO - Python 2.7.3 12:30:07 INFO - Return code: 0 12:30:07 INFO - grabbing minidump binary from tooltool 12:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:07 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 0x109cc7d30>, '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 0x109b2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cbfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:30:07 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 12:30:07 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 12:30:07 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 12:30:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:30:07 INFO - Return code: 0 12:30:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:30:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:30:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:30:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:30:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 12:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0216 --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 12:30:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fn6be3jF8A/Render', 12:30:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:07 INFO - 'HOME': '/Users/cltbld', 12:30:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:07 INFO - 'LOGNAME': 'cltbld', 12:30:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:07 INFO - 'MOZ_NO_REMOTE': '1', 12:30:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:07 INFO - 'NO_EM_RESTART': '1', 12:30:07 INFO - 'PAGER': '/bin/cat', 12:30:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:07 INFO - 'PWD': '/builds/slave/test', 12:30:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:07 INFO - 'SHELL': '/bin/bash', 12:30:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hmN3mwY6bi/Listeners', 12:30:07 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:30:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:07 INFO - 'USER': 'cltbld', 12:30:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:07 INFO - 'XPC_FLAGS': '0x0', 12:30:07 INFO - 'XPC_SERVICE_NAME': '0', 12:30:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 12:30:07 INFO - mozversion application_buildid: 20161118075229 12:30:07 INFO - mozversion application_changeset: a103e1713a2eda3b4e88d71df82d140487c8db87 12:30:07 INFO - mozversion application_display_name: Nightly 12:30:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:30:07 INFO - mozversion application_name: Firefox 12:30:07 INFO - mozversion application_remotingname: firefox 12:30:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:30:07 INFO - mozversion application_vendor: Mozilla 12:30:07 INFO - mozversion application_version: 53.0a1 12:30:07 INFO - mozversion platform_buildid: 20161118075229 12:30:07 INFO - mozversion platform_changeset: a103e1713a2eda3b4e88d71df82d140487c8db87 12:30:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:30:07 INFO - mozversion platform_version: 53.0a1 12:30:07 INFO - using testdate: 1479501007 12:30:07 INFO - actual date: 1479501007 12:30:07 INFO - starting webserver on 'localhost:56516' 12:30:07 INFO - SUITE-START | Running 2 tests 12:30:07 INFO - TEST-START | dromaeo_css 12:30:07 INFO - operating with platform_type : mac_ 12:30:07 INFO - Initialising browser for dromaeo_css test... 12:30:07 INFO - TEST-INFO | started process 2170 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpT0julP/profile http://localhost:56516/getInfo.html) 12:30:10 INFO - PROCESS | 2170 | 2016-11-18 12:30:10.803 plugin-container[2172:18337] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 12:30:10 INFO - PROCESS | 2170 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:10 INFO - PROCESS | 2170 | 2016-11-18 12:30:10.806 plugin-container[2172:18337] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.CFPasteboardClient' 12:30:10 INFO - PROCESS | 2170 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:10 INFO - PROCESS | 2170 | 2016-11-18 12:30:10.806 plugin-container[2172:18337] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:30:10 INFO - PROCESS | 2170 | __metrics Screen width/height:1600/1200 12:30:10 INFO - PROCESS | 2170 | colorDepth:24 12:30:10 INFO - PROCESS | 2170 | Browser inner width/height: 1024/689 12:30:10 INFO - PROCESS | 2170 | __metrics 12:30:11 ERROR - PROCESS | 2170 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 12:30:11 INFO - TEST-INFO | 2170: exit 0 12:30:11 INFO - Browser initialized. 12:30:11 INFO - Running cycle 1/1 for dromaeo_css test... 12:30:11 INFO - TEST-INFO | started process 2174 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpT0julP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:14 INFO - PROCESS | 2174 | 2016-11-18 12:30:14.739 plugin-container[2175:18452] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8933, name = 'com.apple.tsm.portname' 12:30:14 INFO - PROCESS | 2174 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:14 INFO - PROCESS | 2174 | 2016-11-18 12:30:14.740 plugin-container[2175:18452] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 12:30:14 INFO - PROCESS | 2174 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:14 INFO - PROCESS | 2174 | 2016-11-18 12:30:14.740 plugin-container[2175:18452] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:30:24 INFO - PROCESS | 2174 | RSS: Main: 243036160 12:30:24 INFO - PROCESS | 2174 | 12:31:13 INFO - PROCESS | 2174 | 1479501073848 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] 12:33:28 INFO - PROCESS | 2174 | Cycle 1(1): loaded http://localhost:56516/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56516/tests/dromaeo/cssquery-ext.html) 12:33:30 INFO - PROCESS | 2174 | RSS: Main: 248705024 12:33:30 INFO - PROCESS | 2174 | 12:36:32 INFO - PROCESS | 2174 | Cycle 1(1): loaded http://localhost:56516/tests/dromaeo/cssquery-ext.html (next: http://localhost:56516/tests/dromaeo/cssquery-jquery.html) 12:36:33 INFO - PROCESS | 2174 | RSS: Main: 259874816 12:36:33 INFO - PROCESS | 2174 | 12:39:35 INFO - PROCESS | 2174 | Cycle 1(1): loaded http://localhost:56516/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56516/tests/dromaeo/cssquery-mootools.html) 12:39:35 INFO - PROCESS | 2174 | RSS: Main: 264331264 12:39:35 INFO - PROCESS | 2174 | 12:42:37 INFO - PROCESS | 2174 | Cycle 1(1): loaded http://localhost:56516/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56516/tests/dromaeo/cssquery-prototype.html) 12:42:38 INFO - PROCESS | 2174 | RSS: Main: 262836224 12:42:38 INFO - PROCESS | 2174 | 12:45:41 INFO - PROCESS | 2174 | Cycle 1(1): loaded http://localhost:56516/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56516/tests/dromaeo/cssquery-yui.html) 12:45:42 INFO - PROCESS | 2174 | RSS: Main: 267223040 12:45:42 INFO - PROCESS | 2174 | 12:48:44 INFO - PROCESS | 2174 | Cycle 1(1): loaded http://localhost:56516/tests/dromaeo/cssquery-yui.html (next: http://localhost:56516/tests/dromaeo/cssquery-dojo.html) 12:48:45 INFO - PROCESS | 2174 | RSS: Main: 269332480 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | __start_tp_report 12:48:45 INFO - PROCESS | 2174 | _x_x_mozilla_page_load 12:48:45 INFO - PROCESS | 2174 | _x_x_mozilla_page_load_details 12:48:45 INFO - PROCESS | 2174 | |i|pagename|runs| 12:48:45 INFO - PROCESS | 2174 | |0;dojo.html;3315.966840331596;3337.2991671748937;3382.594088709355;3383.813890236036;3393.33829903169;5605.635633683818;5638.379778224392;5653.689047102408;5718.828435146942;5765.567582431314;5591.4688104630095;5669.9433005669935;5671.177679236519;5715.028445164322;5734.741936612852;5085.720285384303;5137.2037334213255;5216.634835561512;5238.947610523884;5268.367795864482;5184.300119483859;5273.947260527384;5275.419703832595;5286.471352864722;5355.410904800468;4400.427944367244;5044.965782014679;5136.614753893473;5194.117000109972;5256.395514515842;5137.33214682089;5164.509371609691;5179.611529135293;5207.6354655174155;5244.921326180111;5130.07658621448;5148.073346797575;5148.3564554430695;5160.12277912752;5192.1692529195525;5479.479449452296;5543.6119471637;5587.7485513152005;5615.775368985236;5665.291838520185;6121.326654068049;6147.538934579879;6148.262208534959;6155.107509411155;6163.506919446433;6246;6252.968735156341;6255.40573645503;6270.153529273535;6293.087502312139;5753.367129615739;5753.942460575383;5774.249347584871;5797.9420205797405;5824.970875145597;6120.877582448326;6213.943629582982;6236.126942228092;6269.968650156811;6292.842678933063;6085.08723691441;6114.755409783648;6127.754889804447;6169.198004259507;6172.876542469216;6225.657588832658;6238.594491358047;6252;6261;6273.905891411632;6063.818085457443;6102.974033066861;6119.8681629442035;6125.174407016092;6192.040233763774;6106.816795496142;6140.416660417253;6251.12484252205;6278;6278.340774218733;4414.602685758297;4920.40955085392;4959.578435832923;4969.27945447908;4997.9750101249265;4370.8033138508845;4394.780260986938;4401.779911004437;4423.18171138334;4429.955700443019;4802.591779698695;4827.324174615556;5444.673319600836;5683.147527870852;5819.243498345273;5237.476252374732;6141.393954534099;6150.876982460326;6198.442140207313;6210.844728881723;5120.564751996048;5595.916061259084;5721.141828725725;5783.508401785895;5784.681842498703;4534.319852022223;5054.974725126351;5389.595780316492;5401.1358182691365;5434.510894019557;6237.656928868956;6253.812385628438;6304.369563043659;6327.873442531216;6398.872022559616;6159.507239420746;6197.25632924052;6229.813105606839;6274.309825919054;6322.083297921868;6315.5263355247625;6346.365363463617;6347.523935704933;6368.77709280173;6374.681265936777;5446.591505637172;6023.096535519532;6066;6217.844553886189;6252.874942501216;6086.934786412448;6110.175126357884;6133.089845874549;6164.260288765377;6220.066989951543;5925.555583331354;5936.13925980739;6009.429104235091;6109.11417844419;6141.785037523665;6038.607490513103;6041.214642096676;6047.697615119138;6119.694015299128;6136.570440069152;5034.773435195499;5925.377835327228;5948.524118070457;5965.224520812267;5975.492083172805;5892.7642894283745;5973.223480947449;5992.700364981821;6084.60450070744;6135.14108024868;5811.360750317546;5857.912131318119;5875.853103672443;5881.794137205177;5884.235049443545 12:48:45 INFO - PROCESS | 2174 | |1;ext.html;480.14534129249927;488.7018918459741;503.5920904067704;509.6738087623919;524.1744252801838;36148;39626.20747585047;39674.40488392673;39744.60255397445;41757.79121104398;40565.79717101418;41241.90685698577;41315.58684413155;41514.37728434076;41519;9045.728628141167;9354.204892584105;9365.76585585359;9366.297527685418;9377.718668439958;9219.769505762342;9312.534373281344;9344.345895787297;9364.204042656382;9375.062493750638;9166.495842728647;9301.627934882597;9347.018563050884;9358.204552613037;9395;9230.93844207918;9283.72148835536;9310.999999999965;9327.020662830373;9354.06459354066;9137.543122843897;9197.494137822418;9214.216791572657;9223.446593204362;9308.394954328014;39115.804420978;39500.60499395027;39565.823879686606;39668.801655991825;39708;3319.767616266862;3326.9833650831833;3355.261842394669;3510.0347404463723;3685.815709214555;28490.287742806388;29770.66032028564;30104.24739381533;30258.84870575655;30334.241643958856;3168.2237851726245;3206.3266713990092;3237.546743455918;3243.1567792373917;3304.1574398528223;482540;492996;497468;499266.503667483;500296;49592.75203624031;59579.10631340533;59804.70097649484;60037.39962600318;60086.39913600896;54932.450675493536;60823;62697.37302626915;63132;64155.67922160359;15750.291236894367;15949.760753588705;16006.759898601531;16008.91995540038;16035.278412471467;12258.509659613694;12302.876971230353;12363.196392234477;12379.195352302251;12402.25586464815;15972.20138993069;17577.560560986076;17612.29550817953;17618.735718964224;17658.381956631456;6218.849512840139;6391.808245752635;6501.374656335916;6853.554518956252;6875.243723190445;822.5928165558087;931.3503083726787;1010.4493051287067;1027.4349107990574;1098.5715570927346;26346.473070538483;29100.417991640472;29697.703022969494;30060.24849378783;30279.09162725122;870.6604424274411;913.7806926337764;1010.9191264698807;1054.2356791326226;1093.9945300273448;13288.73422531544;14680.412783488755;15098;15217.847821521864;15500.9149359546;629278;634460;637223;637391;639169;56260;56617.71691141518;56959.71520142456;57003.714981424826;57153;1302.47900839663;1319.3073636340998;1342.8187194728587;1343.328335832084;1346.2864681718704;27517.86241068782;28554;28618.141455756362;29492.977029816077;30139.849300753354;46809.06381872411;50950.74524627353;51024.74487627538;51081.23378149256;51203.48796511987;581250;582522;583578;585145;585145;39508;40231.798841005606;40429.989250267805;40492.59507405006;40495.59504405036;45389.54610453853;46177.307340390595;46327.76836115798;46737.2989405166;46822;32778.52496637609;35764;37229.81385093057;37391.25217495689;38691;37131.443028355134;37399.25201496009;38327;38551;39545.80227098846;36363.81818090893;36780.89657310285;36837.44743828791;37850;37933.620663794114;33026.66973330236;33557.167695905344;33580.664193357756;34029.31941361109;34265;20585.79414205839;22151.778482214973;24343.78281085974;24888.253352399457;25258.74741252564 12:48:45 INFO - PROCESS | 2174 | |2;jquery.html;337.9136077510803;365.786015181119;367.68379193893253;380.38567713143283;395.8224282758793;15857.524274271776;17845.929244245337;19440.708389374155;19463;19657.31199408021;16763.161841907917;18178.4546463606;19019.714704279446;19216.71174932377;19453.70819437706;5409.675419474825;5657.801976930808;5673.063944449161;5687.687177205252;5690.231818704463;4874.561289483946;5382.9730851345685;5611.80358687446;5687.17535957286;5688.715564221793;5565.2486914266665;5609.52319052881;5623.26897503326;5640.153976903477;5657.179708942201;5393.59548033897;5568.66588004719;5640.153976903456;5640.379558248589;5642.351129620064;4889.144399730033;4912.6561140719805;4914.754262286907;4921.040397122562;4922.458529561745;22801;22971.655425168636;23241.76758232413;23521.76478235213;23838.761612383827;2357.504923965952;2472.8763561822016;2488.1789009626777;2594.8313359631566;2847.957280640792;9397.295202859812;10075.445850478221;14491.130532168103;14626.488072917504;15143.242837858174;1654.2225154177518;1657.3536320834885;1666.758320043599;1826.8173182681758;1841.6224673941945;117471.4126429371;117532.12339382997;119739.40130299379;121111;121451;8943.288535257925;9370.437773733598;9657.46883921377;9719.222462203006;9827.459489728133;12098.576549820715;12171.939140304241;12173.756524869454;12187.39063046844;12211.328376939176;7481.214472480311;7691.30778229962;7699.153093159748;7831.960840195762;7836.098848632366;7969.123396426388;8008.15914328987;8098;8100.675972961133;8141.552214628134;3664.4136938089778;3765.9058523537133;3823.1397935464297;3891.20230352777;3893.377059670439;1589.4834178891906;1700.7363858601936;1834.6422447622851;1887.0941947865101;1888.0654076232354;852.882723632044;860.1355637584188;927.6892241099173;941.9293552983416;976.2287792643874;19726.802731972784;19852.60294794096;19966.90016549937;20000.59998800016;20124.396268111686;894.4052205283414;918.4626993208914;968.3608818179968;984.4979060678962;1001.484235618657;8304.792380190424;10482.842757358645;10518.579256829642;10562.788744225227;10571.788564228673;512145;519628;525777;534983;538962;77490;89723;118389.4080529609;120783;122700.38649806693;872.4111224923151;882.8322618702426;983.5918093991056;1011.8667092856049;1013.4932533733133;17147.399841005506;17494.96640950485;17511.949283043057;17566.38517651876;17755.822441775417;53820;54024.45975540194;54991.725041374535;55123.17315240194;55506.72246638741;60585;76569.85145222937;76614.61692691501;77187;78326.60836695781;5405.189221616789;6346.841328966812;6721.798346049626;6915.066466027022;7013.158420989514;11480.425978701198;11943.343116128697;12170.75658486843;12279.263244205376;12457.1902826316;7560.697572097055;9955.303128781206;10271.79492290685;10601.628942986958;10796.696082172024;7263.418926485974;9832.803343932937;9891.604335826774;10627.787444251226;10809.945950270197;8739.694110706096;9234.492102934135;10282.691519254435;10730.61985474573;10813.053650343014;6634;6926.757563485254;7024.508284420246;7732.149463559116;7993.6003199838615;10503.84457709636;10812.45937703096;10899.56401743953;11048.116150708085;11143.387113708824 12:48:45 INFO - PROCESS | 2174 | |3;mootools.html;1451.818522684664;1500.6848561802021;1672.1472049254876;1779.652967671303;1814.7822261328643;7556.735514256989;7791.298783109519;8045.275925166734;8508.446950948184;8541.957290213548;8004;8302.501849889011;8305.792355191123;8394.62224199911;8421.410501264914;2073.6682130859067;2176.0751680535786;2287.0965968442497;2340.5552944940405;2352.5059737455153;2328.1967721136266;2342.6603142544323;2356.222446592629;2362.244081893795;2382.9285121446383;2311.895964681585;2323.4772176260376;2338.240071976606;2351.4826738117586;2352.4706940938236;2288.8512246704076;2302.0331460786424;2306.7693230676964;2322.1640209524644;2329.173143534055;2326.2439707095264;2348.0490401387465;2354.376090336062;2356.092904231883;2356.104680221505;8286.70996515125;8445.957770211171;8484.32125429964;8491.278241349493;8545.529995850226;5085.23721441783;5242.79028838846;5257.605679574047;5278.996990571817;5279.63042587019;3269.100997225758;3408.8466019028956;3512.297540491912;3610.548681414823;3616.258666973264;985.9309848310621;1025.2361990317188;1072.7425417899726;1080.481368942904;1085.4952447112053;10828.350298982086;10985.395803230818;11031.779364412747;11036.951489608555;11126.165537584713;3770.472133901256;4571.497135315112;4677.812887484531;4695.671303008757;4716.622670186377;9048.32605967898;9350.953245233866;9446.763830904283;9473.810523789485;9490.762730931781;5187.792488300426;5197.1684530475695;5212.139996900545;5217.295665085241;5231.712255825966;4246.7027308088755;4565.292379681155;4716.339712440261;4757.26262429324;4799.66402351832;3599.6220396858475;3745.85016599333;3817.4655548223272;3875.2249550090096;3900.531936167678;973.8295798235281;1069.3102948598112;1142.5886680794906;1202.1705023533734;1270.8284381608548;777.7316825695126;821.0926925746963;867.5835598912557;877.7322916510398;910.3536489092818;4474.88127968008;5144.845654630367;5356.384015838151;5665.745041473144;5732.627379220337;354.96704589644014;419.77542015021817;420.3190830854036;423.1030215942161;425.78497858581835;3532.3465158945164;3679.7240206984584;3706.870259540903;3799.145192331703;3812.599677033927;9920.751981200528;10137.493125343703;10223.386596804216;10317.071463568165;10342.948285258675;9497.14525692691;9929.2056635469;9985.500724963867;10232.02795734404;10234.385936843813;7619.276168763959;7821.491603045783;7838.529688218725;7853.960730196427;7871.842563148705;5488.341399032141;5501.642393244426;5504.366997795305;5547.528460080777;5855.443732845373;9294.674686385944;9462.952685236805;9481.952590237004;9499.43003419797;9503.429794212369;9047.411918225294;9385.343025988388;9386.342955993288;9527.09492598202;9543.665971690958;6617.371349721769;6819.283975182534;7010.544314619533;7126.786196414116;7313.268673132644;7876.330511906552;8016.478928869605;8091.87862182056;8106.148854370442;8110.797230069295;6938.687759050956;7066.929330706833;7193.4604904631315;7211.764353050571;7310.451716121419;6873.562185926601;7003.01957725929;7021.262767409553;7133.82165445868;7140.571565706075;6259.436650701458;6623.966880165761;6636.867262654817;6728.764493242713;6743.325667433218;4778.331033655221;4792.496787837366;4832.855014349575;4860.295257187618;4901.534354236342;4106.809025382606;4706.694064885772;4748.691335063209;4927.187014142626;5015.595633222703 12:48:45 INFO - PROCESS | 2174 | |4;prototype.html;256.74197431581257;291.07438346059536;301.690766963862;309.5264245704072;314.9622045354558;4807.302941073546;5257.475332153671;5429.945700542993;5454.754536045877;5543.473370029851;4339.806553197872;4929.876753081175;5144.459831717662;5238.947610523894;5379.435159308275;3505.8605953065166;4077.2660921034203;4116.835326586933;4122.484689413824;4140.585941405865;3870.3033453978273;3977.196076765873;4033.5159780826343;4095.7747323897174;4139.7930103494855;3742.719296052793;4019.336809426441;4040.515138183422;4051.493563304587;4052.53395859477;3665.798381089039;3772.1889793694486;4054.189162167578;4104.384342348641;4115.506139263278;2673.9064132755457;2942.9411411771644;3101.5347697845277;3553.431450967833;3559.982200088983;4874.805007799684;5183.45573714762;5187.8184263550975;5199.376074871002;5258.684478931238;3791.1802334879135;3916.8041597920273;4638.79125439356;4640.373549570832;4652.674312798105;5293.3647962244395;5656.91514627281;5667.603267771257;5700.743466544015;5711.515490761532;3308.97421799243;3567.2865426914464;3600.4959305697225;3726.5900750917376;3943.8225279862477;5481.095619222823;5572.749226284827;5924.637333413339;5927.9703601481715;6077.662914158922;3345.6821601947777;3946.145311703495;4008.8596899108975;4011.5587285398583;4023.4970628671417;3774.9433758493647;3952.150287688161;4018.7340987588814;4030.5969403059457;4066.4916885389325;3877.728559000899;4437.312216606431;4656.348111264426;4686.266081549848;4713.841726043559;4536.387587675687;5656.8868622627315;5698.2592263005545;5716.884752641466;5742.7415766290615;1022.412113034993;1241.2366394667215;1261.7099963566097;1274.6494713953737;1284.229462322599;829.8009376450971;843.4770442325674;868.8834847221291;891.4339394484549;902.5306840443064;4366.868993930188;4481.551844815557;4664.440267167962;5120.134298604604;5146.886133889018;5090.9236361454605;5279.386703581752;5316.3354580677415;5575.884823035409;5636.802711905064;4604.677672562955;4640.814367425265;5043.36957880265;5481.12302031681;5504.504639553912;2620.2663254288827;2742.4103817679293;2754.944901101967;2967.6438827340858;3010.9397812043635;5089.796408143707;5536.446355364431;5538.113901775697;5739.569532285095;5873.471387575139;5157.767900444489;5167.974160129175;5526.970456884216;5534.063077922695;5796.478316951495;5114.769835357343;5464.699441530674;5828.854278642983;5854.970725146432;5897.675627840425;5407.458494507575;5510.531604813636;5545.861353466034;5556.388797232382;5607.719614019201;4521.728696278234;5293.735313234246;5542.842867849113;5564.721763911707;5596.425644203549;4683.929741053955;5470.409333399937;5704.229928959538;5787.8553036174435;5893.351731309467;3371.8819841305435;3400.8607116616213;3411.5223868657913;3594.3350480161253;3693.8153092344737;4544.614631661491;4730.668853180245;5051.570616497639;5561.3882472927135;5651.378348381757;4362.411074504901;4845;5014;5252.238425428368;5272.470983414767;4161.938705630045;5096.955124199456;5437.564994800486;5634.549236061023;5750.482456578928;5039.773210205476;5232.529072383504;5370.758315875873;5479.643823151482;5636.379998200113;4914.7542622867995;4915.311856340187;5714.885702285847;5747.367789543064;5885.941140588539;4494.50560438345;5044.830722789236;5173.094708425935;5441.374241962067;5619.297587801525 12:48:45 INFO - PROCESS | 2174 | |5;yui.html;630.6815058395507;633.7940169444931;643.2538255623475;655.567325565127;796.9838455968641;12399.814002789966;13870;14156.362963666634;14400.639984000405;14503;13103.968884822843;13341.066125371228;13392.66518337042;13444.126131801451;13503.324833758323;812.6708682983386;835.6699103853974;837.2130197614254;837.7863644770575;839.7480755773274;652.3443938841482;669.4778073102963;804.5856383962264;822.8765685147216;834.3367023216557;834.7996480844583;862.1163307609687;863.7149740585623;865.1564724393729;885.707716453572;870.3907264914521;887.6138879587368;888.9644414223424;888.9954351582735;890.3589415620743;632.3265722006049;660.6960798032862;662.0830150241901;662.4303099334617;665.0722242471774;13798.310084495735;13980.091294065853;14059.086159399607;14167;14519.854801451957;13675.863241367559;13705.45178192871;13759.312034398341;14035.01754877159;14055.437782488689;11270.436478176058;11357.14821388391;11384.904702914888;11399.316041037564;11681.065514758884;3663.670269675742;3683.5027271318295;3691.1141326081547;3799.202167544819;3809.047738065484;70947;89623;89836.65245021264;90586.09413905842;95035.52482237613;10681.82612211083;10681.946590267155;10809.945950270197;10888.78222435547;10968.012878840942;21481.140754369964;21487.355379338645;21608.56782864334;21793.237236696958;21841;7325.816854578678;7417.295356941082;7459.552426854298;7588.089429268523;7830;3480.4083305838067;4043.251998380309;4076.062505623723;4153.7300075494995;4203.138356636882;3032;3115.704008119225;3123.1567476781597;3132.7180553750272;3136.81179129253;517.7806266242943;544.6187668631974;616.5653214483779;628.7642134199675;650.5283669339691;571.5256337240081;587.3363099697315;591.2432086928738;606.2452246952572;619.6282230661567;8427.662893484192;8548.615312310962;8596.957015214884;8645.26515246211;8797.47215167092;469.9741514216751;476.2403965674743;527.8487430042716;669.6138992285921;679.6058286193995;3294.341131773655;4068.2677118119022;4327.740335579875;4448.932256258475;4503.054358584668;72204;75966.10084747816;86159.13840861636;91477.54261228652;104740.95259047311;80282.5985870067;82205.53383398507;90271;91894;94530.52734736282;82509;82773.58613206896;83684;84953.99999999876;86101;9478.289128315266;9688.128068473588;9701.272404569827;9727.85408218863;9732.367396119229;38769.80615096907;40503;55976.720116399156;57092.714536427055;57200.42799571951;73306.26693733208;76524.4695106106;78840.21159788329;80269.19730802617;80432;11700.800190144084;12280;12459.813102803646;12502.624921252376;12545;13539.141344716709;13665.726685466036;14288;14373.425062997781;14599.489017884747;12808.807867882175;13386.933065334611;13660.180389176681;13700.24648644334;13846.584602461942;12351.444185011516;12482.375881206084;12588.307643079352;12775.552855650008;12939.676508087374;12886.935565322114;12887.548935787203;13010.80483792725;13038.804417933929;13231.213786139133;10883;11076.833847492133;11211.383373914514;11361.772764544497;11408.201425900439;7586.937828704096;10187.694369168938;10280.897191027994;11447;11522.423878806194 12:48:45 INFO - PROCESS | 2174 | __end_tp_report 12:48:45 INFO - PROCESS | 2174 | __start_cc_report 12:48:45 INFO - PROCESS | 2174 | _x_x_mozilla_cycle_collect,373 12:48:45 INFO - PROCESS | 2174 | __end_cc_report 12:48:45 INFO - PROCESS | 2174 | __startTimestamp1479502125162__endTimestamp 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | ------- Summary: start ------- 12:48:45 INFO - PROCESS | 2174 | Number of tests: 6 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | [#0] dojo.html Cycles:165 Average:5677.28 Median:5884.24 stddev:659.63 (11.2%) stddev-sans-first:635.11 12:48:45 INFO - PROCESS | 2174 | Values: 3316.0 3337.3 3382.6 3383.8 3393.3 5605.6 5638.4 5653.7 5718.8 5765.6 5591.5 5669.9 5671.2 5715.0 5734.7 5085.7 5137.2 5216.6 5238.9 5268.4 5184.3 5273.9 5275.4 5286.5 5355.4 4400.4 5045.0 5136.6 5194.1 5256.4 5137.3 5164.5 5179.6 5207.6 5244.9 5130.1 5148.1 5148.4 5160.1 5192.2 5479.5 5543.6 5587.7 5615.8 5665.3 6121.3 6147.5 6148.3 6155.1 6163.5 6246.0 6253.0 6255.4 6270.2 6293.1 5753.4 5753.9 5774.2 5797.9 5825.0 6120.9 6213.9 6236.1 6270.0 6292.8 6085.1 6114.8 6127.8 6169.2 6172.9 6225.7 6238.6 6252.0 6261.0 6273.9 6063.8 6103.0 6119.9 6125.2 6192.0 6106.8 6140.4 6251.1 6278.0 6278.3 4414.6 4920.4 4959.6 4969.3 4998.0 4370.8 4394.8 4401.8 4423.2 4430.0 4802.6 4827.3 5444.7 5683.1 5819.2 5237.5 6141.4 6150.9 6198.4 6210.8 5120.6 5595.9 5721.1 5783.5 5784.7 4534.3 5055.0 5389.6 5401.1 5434.5 6237.7 6253.8 6304.4 6327.9 6398.9 6159.5 6197.3 6229.8 6274.3 6322.1 6315.5 6346.4 6347.5 6368.8 6374.7 5446.6 6023.1 6066.0 6217.8 6252.9 6086.9 6110.2 6133.1 6164.3 6220.1 5925.6 5936.1 6009.4 6109.1 6141.8 6038.6 6041.2 6047.7 6119.7 6136.6 5034.8 5925.4 5948.5 5965.2 5975.5 5892.8 5973.2 5992.7 6084.6 6135.1 5811.4 5857.9 5875.9 5881.8 5884.2 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | [#1] ext.html Cycles:180 Average:70183.86 Median:39988.20 stddev:153445.75 (383.7%) stddev-sans-first:153786.96 12:48:45 INFO - PROCESS | 2174 | Values: 480.1 488.7 503.6 509.7 524.2 36148.0 39626.2 39674.4 39744.6 41757.8 40565.8 41241.9 41315.6 41514.4 41519.0 9045.7 9354.2 9365.8 9366.3 9377.7 9219.8 9312.5 9344.3 9364.2 9375.1 9166.5 9301.6 9347.0 9358.2 9395.0 9230.9 9283.7 9311.0 9327.0 9354.1 9137.5 9197.5 9214.2 9223.4 9308.4 39115.8 39500.6 39565.8 39668.8 39708.0 3319.8 3327.0 3355.3 3510.0 3685.8 28490.3 29770.7 30104.2 30258.8 30334.2 3168.2 3206.3 3237.5 3243.2 3304.2 482540.0 492996.0 497468.0 499266.5 500296.0 49592.8 59579.1 59804.7 60037.4 60086.4 54932.5 60823.0 62697.4 63132.0 64155.7 15750.3 15949.8 16006.8 16008.9 16035.3 12258.5 12302.9 12363.2 12379.2 12402.3 15972.2 17577.6 17612.3 17618.7 17658.4 6218.8 6391.8 6501.4 6853.6 6875.2 822.6 931.4 1010.4 1027.4 1098.6 26346.5 29100.4 29697.7 30060.2 30279.1 870.7 913.8 1010.9 1054.2 1094.0 13288.7 14680.4 15098.0 15217.8 15500.9 629278.0 634460.0 637223.0 637391.0 639169.0 56260.0 56617.7 56959.7 57003.7 57153.0 1302.5 1319.3 1342.8 1343.3 1346.3 27517.9 28554.0 28618.1 29493.0 30139.8 46809.1 50950.7 51024.7 51081.2 51203.5 581250.0 582522.0 583578.0 585145.0 585145.0 39508.0 40231.8 40430.0 40492.6 40495.6 45389.5 46177.3 46327.8 46737.3 46822.0 32778.5 35764.0 37229.8 37391.3 38691.0 37131.4 37399.3 38327.0 38551.0 39545.8 36363.8 36780.9 36837.4 37850.0 37933.6 33026.7 33557.2 33580.7 34029.3 34265.0 20585.8 22151.8 24343.8 24888.3 25258.7 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | [#2] jquery.html Cycles:180 Average:31791.66 Median:2144.60 stddev:88185.72 (4112.0%) stddev-sans-first:88401.48 12:48:45 INFO - PROCESS | 2174 | Values: 337.9 365.8 367.7 380.4 395.8 15857.5 17845.9 19440.7 19463.0 19657.3 16763.2 18178.5 19019.7 19216.7 19453.7 5409.7 5657.8 5673.1 5687.7 5690.2 4874.6 5383.0 5611.8 5687.2 5688.7 5565.2 5609.5 5623.3 5640.2 5657.2 5393.6 5568.7 5640.2 5640.4 5642.4 4889.1 4912.7 4914.8 4921.0 4922.5 22801.0 22971.7 23241.8 23521.8 23838.8 2357.5 2472.9 2488.2 2594.8 2848.0 9397.3 10075.4 14491.1 14626.5 15143.2 1654.2 1657.4 1666.8 1826.8 1841.6 117471.4 117532.1 119739.4 121111.0 121451.0 8943.3 9370.4 9657.5 9719.2 9827.5 12098.6 12171.9 12173.8 12187.4 12211.3 7481.2 7691.3 7699.2 7832.0 7836.1 7969.1 8008.2 8098.0 8100.7 8141.6 3664.4 3765.9 3823.1 3891.2 3893.4 1589.5 1700.7 1834.6 1887.1 1888.1 852.9 860.1 927.7 941.9 976.2 19726.8 19852.6 19966.9 20000.6 20124.4 894.4 918.5 968.4 984.5 1001.5 8304.8 10482.8 10518.6 10562.8 10571.8 512145.0 519628.0 525777.0 534983.0 538962.0 77490.0 89723.0 118389.4 120783.0 122700.4 872.4 882.8 983.6 1011.9 1013.5 17147.4 17495.0 17511.9 17566.4 17755.8 53820.0 54024.5 54991.7 55123.2 55506.7 60585.0 76569.9 76614.6 77187.0 78326.6 5405.2 6346.8 6721.8 6915.1 7013.2 11480.4 11943.3 12170.8 12279.3 12457.2 7560.7 9955.3 10271.8 10601.6 10796.7 7263.4 9832.8 9891.6 10627.8 10809.9 8739.7 9234.5 10282.7 10730.6 10813.1 6634.0 6926.8 7024.5 7732.1 7993.6 10503.8 10812.5 10899.6 11048.1 11143.4 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | [#3] mootools.html Cycles:180 Average:5426.72 Median:5115.04 stddev:3023.61 (59.1%) stddev-sans-first:3017.34 12:48:45 INFO - PROCESS | 2174 | Values: 1451.8 1500.7 1672.1 1779.7 1814.8 7556.7 7791.3 8045.3 8508.4 8542.0 8004.0 8302.5 8305.8 8394.6 8421.4 2073.7 2176.1 2287.1 2340.6 2352.5 2328.2 2342.7 2356.2 2362.2 2382.9 2311.9 2323.5 2338.2 2351.5 2352.5 2288.9 2302.0 2306.8 2322.2 2329.2 2326.2 2348.0 2354.4 2356.1 2356.1 8286.7 8446.0 8484.3 8491.3 8545.5 5085.2 5242.8 5257.6 5279.0 5279.6 3269.1 3408.8 3512.3 3610.5 3616.3 985.9 1025.2 1072.7 1080.5 1085.5 10828.4 10985.4 11031.8 11037.0 11126.2 3770.5 4571.5 4677.8 4695.7 4716.6 9048.3 9351.0 9446.8 9473.8 9490.8 5187.8 5197.2 5212.1 5217.3 5231.7 4246.7 4565.3 4716.3 4757.3 4799.7 3599.6 3745.9 3817.5 3875.2 3900.5 973.8 1069.3 1142.6 1202.2 1270.8 777.7 821.1 867.6 877.7 910.4 4474.9 5144.8 5356.4 5665.7 5732.6 355.0 419.8 420.3 423.1 425.8 3532.3 3679.7 3706.9 3799.1 3812.6 9920.8 10137.5 10223.4 10317.1 10342.9 9497.1 9929.2 9985.5 10232.0 10234.4 7619.3 7821.5 7838.5 7854.0 7871.8 5488.3 5501.6 5504.4 5547.5 5855.4 9294.7 9463.0 9482.0 9499.4 9503.4 9047.4 9385.3 9386.3 9527.1 9543.7 6617.4 6819.3 7010.5 7126.8 7313.3 7876.3 8016.5 8091.9 8106.1 8110.8 6938.7 7066.9 7193.5 7211.8 7310.5 6873.6 7003.0 7021.3 7133.8 7140.6 6259.4 6624.0 6636.9 6728.8 6743.3 4778.3 4792.5 4832.9 4860.3 4901.5 4106.8 4706.7 4748.7 4927.2 5015.6 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | [#4] prototype.html Cycles:180 Average:4437.59 Median:5026.89 stddev:1379.48 (27.4%) stddev-sans-first:1347.19 12:48:45 INFO - PROCESS | 2174 | Values: 256.7 291.1 301.7 309.5 315.0 4807.3 5257.5 5429.9 5454.8 5543.5 4339.8 4929.9 5144.5 5238.9 5379.4 3505.9 4077.3 4116.8 4122.5 4140.6 3870.3 3977.2 4033.5 4095.8 4139.8 3742.7 4019.3 4040.5 4051.5 4052.5 3665.8 3772.2 4054.2 4104.4 4115.5 2673.9 2942.9 3101.5 3553.4 3560.0 4874.8 5183.5 5187.8 5199.4 5258.7 3791.2 3916.8 4638.8 4640.4 4652.7 5293.4 5656.9 5667.6 5700.7 5711.5 3309.0 3567.3 3600.5 3726.6 3943.8 5481.1 5572.7 5924.6 5928.0 6077.7 3345.7 3946.1 4008.9 4011.6 4023.5 3774.9 3952.2 4018.7 4030.6 4066.5 3877.7 4437.3 4656.3 4686.3 4713.8 4536.4 5656.9 5698.3 5716.9 5742.7 1022.4 1241.2 1261.7 1274.6 1284.2 829.8 843.5 868.9 891.4 902.5 4366.9 4481.6 4664.4 5120.1 5146.9 5090.9 5279.4 5316.3 5575.9 5636.8 4604.7 4640.8 5043.4 5481.1 5504.5 2620.3 2742.4 2754.9 2967.6 3010.9 5089.8 5536.4 5538.1 5739.6 5873.5 5157.8 5168.0 5527.0 5534.1 5796.5 5114.8 5464.7 5828.9 5855.0 5897.7 5407.5 5510.5 5545.9 5556.4 5607.7 4521.7 5293.7 5542.8 5564.7 5596.4 4683.9 5470.4 5704.2 5787.9 5893.4 3371.9 3400.9 3411.5 3594.3 3693.8 4544.6 4730.7 5051.6 5561.4 5651.4 4362.4 4845.0 5014.0 5252.2 5272.5 4161.9 5097.0 5437.6 5634.5 5750.5 5039.8 5232.5 5370.8 5479.6 5636.4 4914.8 4915.3 5714.9 5747.4 5885.9 4494.5 5044.8 5173.1 5441.4 5619.3 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - PROCESS | 2174 | [#5] yui.html Cycles:180 Average:19612.51 Median:4475.99 stddev:27801.39 (621.1%) stddev-sans-first:27842.84 12:48:45 INFO - PROCESS | 2174 | Values: 630.7 633.8 643.3 655.6 797.0 12399.8 13870.0 14156.4 14400.6 14503.0 13104.0 13341.1 13392.7 13444.1 13503.3 812.7 835.7 837.2 837.8 839.7 652.3 669.5 804.6 822.9 834.3 834.8 862.1 863.7 865.2 885.7 870.4 887.6 889.0 889.0 890.4 632.3 660.7 662.1 662.4 665.1 13798.3 13980.1 14059.1 14167.0 14519.9 13675.9 13705.5 13759.3 14035.0 14055.4 11270.4 11357.1 11384.9 11399.3 11681.1 3663.7 3683.5 3691.1 3799.2 3809.0 70947.0 89623.0 89836.7 90586.1 95035.5 10681.8 10681.9 10809.9 10888.8 10968.0 21481.1 21487.4 21608.6 21793.2 21841.0 7325.8 7417.3 7459.6 7588.1 7830.0 3480.4 4043.3 4076.1 4153.7 4203.1 3032.0 3115.7 3123.2 3132.7 3136.8 517.8 544.6 616.6 628.8 650.5 571.5 587.3 591.2 606.2 619.6 8427.7 8548.6 8597.0 8645.3 8797.5 470.0 476.2 527.8 669.6 679.6 3294.3 4068.3 4327.7 4448.9 4503.1 72204.0 75966.1 86159.1 91477.5 104741.0 80282.6 82205.5 90271.0 91894.0 94530.5 82509.0 82773.6 83684.0 84954.0 86101.0 9478.3 9688.1 9701.3 9727.9 9732.4 38769.8 40503.0 55976.7 57092.7 57200.4 73306.3 76524.5 78840.2 80269.2 80432.0 11700.8 12280.0 12459.8 12502.6 12545.0 13539.1 13665.7 14288.0 14373.4 14599.5 12808.8 13386.9 13660.2 13700.2 13846.6 12351.4 12482.4 12588.3 12775.6 12939.7 12886.9 12887.5 13010.8 13038.8 13231.2 10883.0 11076.8 11211.4 11361.8 11408.2 7586.9 10187.7 10280.9 11447.0 11522.4 12:48:45 INFO - PROCESS | 2174 | -------- Summary: end -------- 12:48:45 INFO - PROCESS | 2174 | 12:48:45 INFO - TEST-INFO | 2174: exit 0 12:48:45 INFO - TEST-OK | dromaeo_css | took 1118130ms 12:48:45 INFO - TEST-START | kraken 12:48:45 INFO - operating with platform_type : mac_ 12:48:45 INFO - Initialising browser for kraken test... 12:48:45 INFO - TEST-INFO | started process 2289 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpgnF8Lx/profile http://localhost:56516/getInfo.html) 12:48:49 INFO - PROCESS | 2289 | 2016-11-18 12:48:49.074 plugin-container[2290:20630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 12:48:49 INFO - PROCESS | 2289 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:49 INFO - PROCESS | 2289 | 2016-11-18 12:48:49.079 plugin-container[2290:20630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 12:48:49 INFO - PROCESS | 2289 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:49 INFO - PROCESS | 2289 | 2016-11-18 12:48:49.080 plugin-container[2290:20630] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:48:49 INFO - PROCESS | 2289 | __metrics Screen width/height:1600/1200 12:48:49 INFO - PROCESS | 2289 | colorDepth:24 12:48:49 INFO - PROCESS | 2289 | Browser inner width/height: 1024/689 12:48:49 INFO - PROCESS | 2289 | __metrics 12:48:49 ERROR - PROCESS | 2289 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 12:48:50 INFO - TEST-INFO | 2289: exit 0 12:48:50 INFO - Browser initialized. 12:48:50 INFO - Running cycle 1/1 for kraken test... 12:48:50 INFO - TEST-INFO | started process 2291 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpgnF8Lx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:48:52 INFO - PROCESS | 2291 | 2016-11-18 12:48:52.867 plugin-container[2293:20748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8633, name = 'com.apple.tsm.portname' 12:48:52 INFO - PROCESS | 2291 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:52 INFO - PROCESS | 2291 | 2016-11-18 12:48:52.868 plugin-container[2293:20748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 12:48:52 INFO - PROCESS | 2291 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:48:52 INFO - PROCESS | 2291 | 2016-11-18 12:48:52.868 plugin-container[2293:20748] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:49:02 INFO - PROCESS | 2291 | RSS: Main: 243150848 12:49:02 INFO - PROCESS | 2291 | 12:49:05 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56516/tests/kraken/driver.html?testName=audio-beat-detection) 12:49:06 INFO - PROCESS | 2291 | RSS: Main: 255131648 12:49:06 INFO - PROCESS | 2291 | 12:49:09 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56516/tests/kraken/driver.html?testName=audio-dft) 12:49:09 INFO - PROCESS | 2291 | RSS: Main: 262811648 12:49:09 INFO - PROCESS | 2291 | 12:49:13 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56516/tests/kraken/driver.html?testName=audio-fft) 12:49:13 INFO - PROCESS | 2291 | RSS: Main: 265691136 12:49:13 INFO - PROCESS | 2291 | 12:49:15 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56516/tests/kraken/driver.html?testName=audio-oscillator) 12:49:16 INFO - PROCESS | 2291 | RSS: Main: 267808768 12:49:16 INFO - PROCESS | 2291 | 12:49:18 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56516/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:49:18 INFO - PROCESS | 2291 | RSS: Main: 268152832 12:49:18 INFO - PROCESS | 2291 | 12:49:22 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56516/tests/kraken/driver.html?testName=imaging-darkroom) 12:49:22 INFO - PROCESS | 2291 | RSS: Main: 273846272 12:49:22 INFO - PROCESS | 2291 | 12:49:25 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56516/tests/kraken/driver.html?testName=imaging-desaturate) 12:49:26 INFO - PROCESS | 2291 | RSS: Main: 273625088 12:49:26 INFO - PROCESS | 2291 | 12:49:29 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56516/tests/kraken/driver.html?testName=json-parse-financial) 12:49:30 INFO - PROCESS | 2291 | RSS: Main: 273780736 12:49:30 INFO - PROCESS | 2291 | 12:49:31 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56516/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:49:32 INFO - PROCESS | 2291 | RSS: Main: 272924672 12:49:32 INFO - PROCESS | 2291 | 12:49:34 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:49:34 INFO - PROCESS | 2291 | RSS: Main: 273002496 12:49:34 INFO - PROCESS | 2291 | 12:49:37 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:49:37 INFO - PROCESS | 2291 | RSS: Main: 273031168 12:49:37 INFO - PROCESS | 2291 | 12:49:40 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:49:41 INFO - PROCESS | 2291 | RSS: Main: 273289216 12:49:41 INFO - PROCESS | 2291 | 12:49:44 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:49:45 INFO - PROCESS | 2291 | RSS: Main: 272814080 12:49:45 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | Cycle 1(1): loaded http://localhost:56516/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56516/tests/kraken/driver.html?testName=ai-astar) 12:49:47 INFO - PROCESS | 2291 | RSS: Main: 274673664 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | __start_tp_report 12:49:47 INFO - PROCESS | 2291 | _x_x_mozilla_page_load 12:49:47 INFO - PROCESS | 2291 | _x_x_mozilla_page_load_details 12:49:47 INFO - PROCESS | 2291 | |i|pagename|runs| 12:49:47 INFO - PROCESS | 2291 | |0;ai-astar;105;117;118;116;120;119;122;119;124;174 12:49:47 INFO - PROCESS | 2291 | |1;audio-beat-detection;152;107;137;188;145;117;118;120;144;117 12:49:47 INFO - PROCESS | 2291 | |2;audio-dft;204;210;264;202;196;194;195;202;196;199 12:49:47 INFO - PROCESS | 2291 | |3;audio-fft;81;58;60;85;50;74;53;56;86;84 12:49:47 INFO - PROCESS | 2291 | |4;audio-oscillator;90;72;87;71;70;71;88;71;71;86 12:49:47 INFO - PROCESS | 2291 | |5;imaging-gaussian-blur;117;86;88;102;126;133;105;127;88;120 12:49:47 INFO - PROCESS | 2291 | |6;imaging-darkroom;99;105;117;173;104;98;99;98;117;96 12:49:47 INFO - PROCESS | 2291 | |7;imaging-desaturate;101;107;79;84;76;145;149;151;94;93 12:49:47 INFO - PROCESS | 2291 | |8;json-parse-financial;53;53;53;54;58;62;58;83;89;88 12:49:47 INFO - PROCESS | 2291 | |9;json-stringify-tinderbox;66;78;73;90;78;76;70;72;71;76 12:49:47 INFO - PROCESS | 2291 | |10;stanford-crypto-aes;92;89;93;91;90;90;105;88;78;79 12:49:47 INFO - PROCESS | 2291 | |11;stanford-crypto-ccm;153;149;148;151;163;168;163;166;212;172 12:49:47 INFO - PROCESS | 2291 | |12;stanford-crypto-pbkdf2;169;179;178;172;177;176;175;180;177;245 12:49:47 INFO - PROCESS | 2291 | |13;stanford-crypto-sha256-iterative;71;85;89;93;73;68;70;71;83;92 12:49:47 INFO - PROCESS | 2291 | __end_tp_report 12:49:47 INFO - PROCESS | 2291 | __start_cc_report 12:49:47 INFO - PROCESS | 2291 | _x_x_mozilla_cycle_collect,948 12:49:47 INFO - PROCESS | 2291 | __end_cc_report 12:49:47 INFO - PROCESS | 2291 | __startTimestamp1479502187355__endTimestamp 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | ------- Summary: start ------- 12:49:47 INFO - PROCESS | 2291 | Number of tests: 14 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#0] ai-astar Cycles:10 Average:123.40 Median:119.50 stddev:18.49 (15.5%) stddev-sans-first:18.37 12:49:47 INFO - PROCESS | 2291 | Values: 105.0 117.0 118.0 116.0 120.0 119.0 122.0 119.0 124.0 174.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:140.50 stddev:24.10 (17.2%) stddev-sans-first:24.71 12:49:47 INFO - PROCESS | 2291 | Values: 152.0 107.0 137.0 188.0 145.0 117.0 118.0 120.0 144.0 117.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#2] audio-dft Cycles:10 Average:206.20 Median:202.00 stddev:20.90 (10.3%) stddev-sans-first:22.15 12:49:47 INFO - PROCESS | 2291 | Values: 204.0 210.0 264.0 202.0 196.0 194.0 195.0 202.0 196.0 199.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#3] audio-fft Cycles:10 Average:68.70 Median:77.50 stddev:14.63 (18.9%) stddev-sans-first:14.82 12:49:47 INFO - PROCESS | 2291 | Values: 81.0 58.0 60.0 85.0 50.0 74.0 53.0 56.0 86.0 84.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.00 stddev:8.72 (11.0%) stddev-sans-first:8.03 12:49:47 INFO - PROCESS | 2291 | Values: 90.0 72.0 87.0 71.0 70.0 71.0 88.0 71.0 71.0 86.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:130.00 stddev:17.79 (13.7%) stddev-sans-first:18.65 12:49:47 INFO - PROCESS | 2291 | Values: 117.0 86.0 88.0 102.0 126.0 133.0 105.0 127.0 88.0 120.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:97.00 stddev:23.21 (23.9%) stddev-sans-first:24.24 12:49:47 INFO - PROCESS | 2291 | Values: 99.0 105.0 117.0 173.0 104.0 98.0 99.0 98.0 117.0 96.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#7] imaging-desaturate Cycles:10 Average:107.90 Median:77.50 stddev:29.46 (38.0%) stddev-sans-first:31.14 12:49:47 INFO - PROCESS | 2291 | Values: 101.0 107.0 79.0 84.0 76.0 145.0 149.0 151.0 94.0 93.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:60.00 stddev:15.23 (25.4%) stddev-sans-first:15.52 12:49:47 INFO - PROCESS | 2291 | Values: 53.0 53.0 53.0 54.0 58.0 62.0 58.0 83.0 89.0 88.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#9] json-stringify-tinderbox Cycles:10 Average:75.00 Median:76.00 stddev:6.50 (8.5%) stddev-sans-first:6.02 12:49:47 INFO - PROCESS | 2291 | Values: 66.0 78.0 73.0 90.0 78.0 76.0 70.0 72.0 71.0 76.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:90.00 stddev:7.50 (8.3%) stddev-sans-first:7.90 12:49:47 INFO - PROCESS | 2291 | Values: 92.0 89.0 93.0 91.0 90.0 90.0 105.0 88.0 78.0 79.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#11] stanford-crypto-ccm Cycles:10 Average:164.50 Median:164.50 stddev:18.73 (11.4%) stddev-sans-first:19.40 12:49:47 INFO - PROCESS | 2291 | Values: 153.0 149.0 148.0 151.0 163.0 168.0 163.0 166.0 212.0 172.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:177.50 stddev:22.10 (12.5%) stddev-sans-first:22.87 12:49:47 INFO - PROCESS | 2291 | Values: 169.0 179.0 178.0 172.0 177.0 176.0 175.0 180.0 177.0 245.0 12:49:47 INFO - PROCESS | 2291 | 12:49:47 INFO - PROCESS | 2291 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.50 Median:84.00 stddev:9.89 (11.8%) stddev-sans-first:10.00 12:49:47 INFO - PROCESS | 2291 | Values: 71.0 85.0 89.0 93.0 73.0 68.0 70.0 71.0 83.0 92.0 12:49:47 INFO - PROCESS | 2291 | -------- Summary: end -------- 12:49:47 INFO - PROCESS | 2291 | 12:49:48 INFO - TEST-INFO | 2291: exit 0 12:49:48 INFO - TEST-OK | kraken | took 62606ms 12:49:48 INFO - SUITE-END | took 1180s 12:49:48 INFO - Completed test suite (00:19:40) 12:49:48 INFO - javascript benchmark 12:49:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7032.712041516568, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.966840331596, 3337.2991671748937, 3382.594088709355, 3383.813890236036, 3393.33829903169, 5605.635633683818, 5638.379778224392, 5653.689047102408, 5718.828435146942, 5765.567582431314, 5591.4688104630095, 5669.9433005669935, 5671.177679236519, 5715.028445164322, 5734.741936612852, 5085.720285384303, 5137.2037334213255, 5216.634835561512, 5238.947610523884, 5268.367795864482, 5184.300119483859, 5273.947260527384, 5275.419703832595, 5286.471352864722, 5355.410904800468, 4400.427944367244, 5044.965782014679, 5136.614753893473, 5194.117000109972, 5256.395514515842, 5137.33214682089, 5164.509371609691, 5179.611529135293, 5207.6354655174155, 5244.921326180111, 5130.07658621448, 5148.073346797575, 5148.3564554430695, 5160.12277912752, 5192.1692529195525, 5479.479449452296, 5543.6119471637, 5587.7485513152005, 5615.775368985236, 5665.291838520185, 6121.326654068049, 6147.538934579879, 6148.262208534959, 6155.107509411155, 6163.506919446433, 6246.0, 6252.968735156341, 6255.40573645503, 6270.153529273535, 6293.087502312139, 5753.367129615739, 5753.942460575383, 5774.249347584871, 5797.9420205797405, 5824.970875145597, 6120.877582448326, 6213.943629582982, 6236.126942228092, 6269.968650156811, 6292.842678933063, 6085.08723691441, 6114.755409783648, 6127.754889804447, 6169.198004259507, 6172.876542469216, 6225.657588832658, 6238.594491358047, 6252.0, 6261.0, 6273.905891411632, 6063.818085457443, 6102.974033066861, 6119.8681629442035, 6125.174407016092, 6192.040233763774, 6106.816795496142, 6140.416660417253, 6251.12484252205, 6278.0, 6278.340774218733, 4414.602685758297, 4920.40955085392, 4959.578435832923, 4969.27945447908, 4997.9750101249265, 4370.8033138508845, 4394.780260986938, 4401.779911004437, 4423.18171138334, 4429.955700443019, 4802.591779698695, 4827.324174615556, 5444.673319600836, 5683.147527870852, 5819.243498345273, 5237.476252374732, 6141.393954534099, 6150.876982460326, 6198.442140207313, 6210.844728881723, 5120.564751996048, 5595.916061259084, 5721.141828725725, 5783.508401785895, 5784.681842498703, 4534.319852022223, 5054.974725126351, 5389.595780316492, 5401.1358182691365, 5434.510894019557, 6237.656928868956, 6253.812385628438, 6304.369563043659, 6327.873442531216, 6398.872022559616, 6159.507239420746, 6197.25632924052, 6229.813105606839, 6274.309825919054, 6322.083297921868, 6315.5263355247625, 6346.365363463617, 6347.523935704933, 6368.77709280173, 6374.681265936777, 5446.591505637172, 6023.096535519532, 6066.0, 6217.844553886189, 6252.874942501216, 6086.934786412448, 6110.175126357884, 6133.089845874549, 6164.260288765377, 6220.066989951543, 5925.555583331354, 5936.13925980739, 6009.429104235091, 6109.11417844419, 6141.785037523665, 6038.607490513103, 6041.214642096676, 6047.697615119138, 6119.694015299128, 6136.570440069152, 5034.773435195499, 5925.377835327228, 5948.524118070457, 5965.224520812267, 5975.492083172805, 5892.7642894283745, 5973.223480947449, 5992.700364981821, 6084.60450070744, 6135.14108024868, 5811.360750317546, 5857.912131318119, 5875.853103672443, 5881.794137205177, 5884.235049443545], "value": 5635.471609666988, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.14534129249927, 488.7018918459741, 503.5920904067704, 509.6738087623919, 524.1744252801838, 36148.0, 39626.20747585047, 39674.40488392673, 39744.60255397445, 41757.79121104398, 40565.79717101418, 41241.90685698577, 41315.58684413155, 41514.37728434076, 41519.0, 9045.728628141167, 9354.204892584105, 9365.76585585359, 9366.297527685418, 9377.718668439958, 9219.769505762342, 9312.534373281344, 9344.345895787297, 9364.204042656382, 9375.062493750638, 9166.495842728647, 9301.627934882597, 9347.018563050884, 9358.204552613037, 9395.0, 9230.93844207918, 9283.72148835536, 9310.999999999965, 9327.020662830373, 9354.06459354066, 9137.543122843897, 9197.494137822418, 9214.216791572657, 9223.446593204362, 9308.394954328014, 39115.804420978, 39500.60499395027, 39565.823879686606, 39668.801655991825, 39708.0, 3319.767616266862, 3326.9833650831833, 3355.261842394669, 3510.0347404463723, 3685.815709214555, 28490.287742806388, 29770.66032028564, 30104.24739381533, 30258.84870575655, 30334.241643958856, 3168.2237851726245, 3206.3266713990092, 3237.546743455918, 3243.1567792373917, 3304.1574398528223, 482540.0, 492996.0, 497468.0, 499266.503667483, 500296.0, 49592.75203624031, 59579.10631340533, 59804.70097649484, 60037.39962600318, 60086.39913600896, 54932.450675493536, 60823.0, 62697.37302626915, 63132.0, 64155.67922160359, 15750.291236894367, 15949.760753588705, 16006.759898601531, 16008.91995540038, 16035.278412471467, 12258.509659613694, 12302.876971230353, 12363.196392234477, 12379.195352302251, 12402.25586464815, 15972.20138993069, 17577.560560986076, 17612.29550817953, 17618.735718964224, 17658.381956631456, 6218.849512840139, 6391.808245752635, 6501.374656335916, 6853.554518956252, 6875.243723190445, 822.5928165558087, 931.3503083726787, 1010.4493051287067, 1027.4349107990574, 1098.5715570927346, 26346.473070538483, 29100.417991640472, 29697.703022969494, 30060.24849378783, 30279.09162725122, 870.6604424274411, 913.7806926337764, 1010.9191264698807, 1054.2356791326226, 1093.9945300273448, 13288.73422531544, 14680.412783488755, 15098.0, 15217.847821521864, 15500.9149359546, 629278.0, 634460.0, 637223.0, 637391.0, 639169.0, 56260.0, 56617.71691141518, 56959.71520142456, 57003.714981424826, 57153.0, 1302.47900839663, 1319.3073636340998, 1342.8187194728587, 1343.328335832084, 1346.2864681718704, 27517.86241068782, 28554.0, 28618.141455756362, 29492.977029816077, 30139.849300753354, 46809.06381872411, 50950.74524627353, 51024.74487627538, 51081.23378149256, 51203.48796511987, 581250.0, 582522.0, 583578.0, 585145.0, 585145.0, 39508.0, 40231.798841005606, 40429.989250267805, 40492.59507405006, 40495.59504405036, 45389.54610453853, 46177.307340390595, 46327.76836115798, 46737.2989405166, 46822.0, 32778.52496637609, 35764.0, 37229.81385093057, 37391.25217495689, 38691.0, 37131.443028355134, 37399.25201496009, 38327.0, 38551.0, 39545.80227098846, 36363.81818090893, 36780.89657310285, 36837.44743828791, 37850.0, 37933.620663794114, 33026.66973330236, 33557.167695905344, 33580.664193357756, 34029.31941361109, 34265.0, 20585.79414205839, 22151.778482214973, 24343.78281085974, 24888.253352399457, 25258.74741252564], "value": 19660.502215384986, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9136077510803, 365.786015181119, 367.68379193893253, 380.38567713143283, 395.8224282758793, 15857.524274271776, 17845.929244245337, 19440.708389374155, 19463.0, 19657.31199408021, 16763.161841907917, 18178.4546463606, 19019.714704279446, 19216.71174932377, 19453.70819437706, 5409.675419474825, 5657.801976930808, 5673.063944449161, 5687.687177205252, 5690.231818704463, 4874.561289483946, 5382.9730851345685, 5611.80358687446, 5687.17535957286, 5688.715564221793, 5565.2486914266665, 5609.52319052881, 5623.26897503326, 5640.153976903477, 5657.179708942201, 5393.59548033897, 5568.66588004719, 5640.153976903456, 5640.379558248589, 5642.351129620064, 4889.144399730033, 4912.6561140719805, 4914.754262286907, 4921.040397122562, 4922.458529561745, 22801.0, 22971.655425168636, 23241.76758232413, 23521.76478235213, 23838.761612383827, 2357.504923965952, 2472.8763561822016, 2488.1789009626777, 2594.8313359631566, 2847.957280640792, 9397.295202859812, 10075.445850478221, 14491.130532168103, 14626.488072917504, 15143.242837858174, 1654.2225154177518, 1657.3536320834885, 1666.758320043599, 1826.8173182681758, 1841.6224673941945, 117471.4126429371, 117532.12339382997, 119739.40130299379, 121111.0, 121451.0, 8943.288535257925, 9370.437773733598, 9657.46883921377, 9719.222462203006, 9827.459489728133, 12098.576549820715, 12171.939140304241, 12173.756524869454, 12187.39063046844, 12211.328376939176, 7481.214472480311, 7691.30778229962, 7699.153093159748, 7831.960840195762, 7836.098848632366, 7969.123396426388, 8008.15914328987, 8098.0, 8100.675972961133, 8141.552214628134, 3664.4136938089778, 3765.9058523537133, 3823.1397935464297, 3891.20230352777, 3893.377059670439, 1589.4834178891906, 1700.7363858601936, 1834.6422447622851, 1887.0941947865101, 1888.0654076232354, 852.882723632044, 860.1355637584188, 927.6892241099173, 941.9293552983416, 976.2287792643874, 19726.802731972784, 19852.60294794096, 19966.90016549937, 20000.59998800016, 20124.396268111686, 894.4052205283414, 918.4626993208914, 968.3608818179968, 984.4979060678962, 1001.484235618657, 8304.792380190424, 10482.842757358645, 10518.579256829642, 10562.788744225227, 10571.788564228673, 512145.0, 519628.0, 525777.0, 534983.0, 538962.0, 77490.0, 89723.0, 118389.4080529609, 120783.0, 122700.38649806693, 872.4111224923151, 882.8322618702426, 983.5918093991056, 1011.8667092856049, 1013.4932533733133, 17147.399841005506, 17494.96640950485, 17511.949283043057, 17566.38517651876, 17755.822441775417, 53820.0, 54024.45975540194, 54991.725041374535, 55123.17315240194, 55506.72246638741, 60585.0, 76569.85145222937, 76614.61692691501, 77187.0, 78326.60836695781, 5405.189221616789, 6346.841328966812, 6721.798346049626, 6915.066466027022, 7013.158420989514, 11480.425978701198, 11943.343116128697, 12170.75658486843, 12279.263244205376, 12457.1902826316, 7560.697572097055, 9955.303128781206, 10271.79492290685, 10601.628942986958, 10796.696082172024, 7263.418926485974, 9832.803343932937, 9891.604335826774, 10627.787444251226, 10809.945950270197, 8739.694110706096, 9234.492102934135, 10282.691519254435, 10730.61985474573, 10813.053650343014, 6634.0, 6926.757563485254, 7024.508284420246, 7732.149463559116, 7993.6003199838615, 10503.84457709636, 10812.45937703096, 10899.56401743953, 11048.116150708085, 11143.387113708824], "value": 8938.023202539001, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.818522684664, 1500.6848561802021, 1672.1472049254876, 1779.652967671303, 1814.7822261328643, 7556.735514256989, 7791.298783109519, 8045.275925166734, 8508.446950948184, 8541.957290213548, 8004.0, 8302.501849889011, 8305.792355191123, 8394.62224199911, 8421.410501264914, 2073.6682130859067, 2176.0751680535786, 2287.0965968442497, 2340.5552944940405, 2352.5059737455153, 2328.1967721136266, 2342.6603142544323, 2356.222446592629, 2362.244081893795, 2382.9285121446383, 2311.895964681585, 2323.4772176260376, 2338.240071976606, 2351.4826738117586, 2352.4706940938236, 2288.8512246704076, 2302.0331460786424, 2306.7693230676964, 2322.1640209524644, 2329.173143534055, 2326.2439707095264, 2348.0490401387465, 2354.376090336062, 2356.092904231883, 2356.104680221505, 8286.70996515125, 8445.957770211171, 8484.32125429964, 8491.278241349493, 8545.529995850226, 5085.23721441783, 5242.79028838846, 5257.605679574047, 5278.996990571817, 5279.63042587019, 3269.100997225758, 3408.8466019028956, 3512.297540491912, 3610.548681414823, 3616.258666973264, 985.9309848310621, 1025.2361990317188, 1072.7425417899726, 1080.481368942904, 1085.4952447112053, 10828.350298982086, 10985.395803230818, 11031.779364412747, 11036.951489608555, 11126.165537584713, 3770.472133901256, 4571.497135315112, 4677.812887484531, 4695.671303008757, 4716.622670186377, 9048.32605967898, 9350.953245233866, 9446.763830904283, 9473.810523789485, 9490.762730931781, 5187.792488300426, 5197.1684530475695, 5212.139996900545, 5217.295665085241, 5231.712255825966, 4246.7027308088755, 4565.292379681155, 4716.339712440261, 4757.26262429324, 4799.66402351832, 3599.6220396858475, 3745.85016599333, 3817.4655548223272, 3875.2249550090096, 3900.531936167678, 973.8295798235281, 1069.3102948598112, 1142.5886680794906, 1202.1705023533734, 1270.8284381608548, 777.7316825695126, 821.0926925746963, 867.5835598912557, 877.7322916510398, 910.3536489092818, 4474.88127968008, 5144.845654630367, 5356.384015838151, 5665.745041473144, 5732.627379220337, 354.96704589644014, 419.77542015021817, 420.3190830854036, 423.1030215942161, 425.78497858581835, 3532.3465158945164, 3679.7240206984584, 3706.870259540903, 3799.145192331703, 3812.599677033927, 9920.751981200528, 10137.493125343703, 10223.386596804216, 10317.071463568165, 10342.948285258675, 9497.14525692691, 9929.2056635469, 9985.500724963867, 10232.02795734404, 10234.385936843813, 7619.276168763959, 7821.491603045783, 7838.529688218725, 7853.960730196427, 7871.842563148705, 5488.341399032141, 5501.642393244426, 5504.366997795305, 5547.528460080777, 5855.443732845373, 9294.674686385944, 9462.952685236805, 9481.952590237004, 9499.43003419797, 9503.429794212369, 9047.411918225294, 9385.343025988388, 9386.342955993288, 9527.09492598202, 9543.665971690958, 6617.371349721769, 6819.283975182534, 7010.544314619533, 7126.786196414116, 7313.268673132644, 7876.330511906552, 8016.478928869605, 8091.87862182056, 8106.148854370442, 8110.797230069295, 6938.687759050956, 7066.929330706833, 7193.4604904631315, 7211.764353050571, 7310.451716121419, 6873.562185926601, 7003.01957725929, 7021.262767409553, 7133.82165445868, 7140.571565706075, 6259.436650701458, 6623.966880165761, 6636.867262654817, 6728.764493242713, 6743.325667433218, 4778.331033655221, 4792.496787837366, 4832.855014349575, 4860.295257187618, 4901.534354236342, 4106.809025382606, 4706.694064885772, 4748.691335063209, 4927.187014142626, 5015.595633222703], "value": 4300.888303368626, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.74197431581257, 291.07438346059536, 301.690766963862, 309.5264245704072, 314.9622045354558, 4807.302941073546, 5257.475332153671, 5429.945700542993, 5454.754536045877, 5543.473370029851, 4339.806553197872, 4929.876753081175, 5144.459831717662, 5238.947610523894, 5379.435159308275, 3505.8605953065166, 4077.2660921034203, 4116.835326586933, 4122.484689413824, 4140.585941405865, 3870.3033453978273, 3977.196076765873, 4033.5159780826343, 4095.7747323897174, 4139.7930103494855, 3742.719296052793, 4019.336809426441, 4040.515138183422, 4051.493563304587, 4052.53395859477, 3665.798381089039, 3772.1889793694486, 4054.189162167578, 4104.384342348641, 4115.506139263278, 2673.9064132755457, 2942.9411411771644, 3101.5347697845277, 3553.431450967833, 3559.982200088983, 4874.805007799684, 5183.45573714762, 5187.8184263550975, 5199.376074871002, 5258.684478931238, 3791.1802334879135, 3916.8041597920273, 4638.79125439356, 4640.373549570832, 4652.674312798105, 5293.3647962244395, 5656.91514627281, 5667.603267771257, 5700.743466544015, 5711.515490761532, 3308.97421799243, 3567.2865426914464, 3600.4959305697225, 3726.5900750917376, 3943.8225279862477, 5481.095619222823, 5572.749226284827, 5924.637333413339, 5927.9703601481715, 6077.662914158922, 3345.6821601947777, 3946.145311703495, 4008.8596899108975, 4011.5587285398583, 4023.4970628671417, 3774.9433758493647, 3952.150287688161, 4018.7340987588814, 4030.5969403059457, 4066.4916885389325, 3877.728559000899, 4437.312216606431, 4656.348111264426, 4686.266081549848, 4713.841726043559, 4536.387587675687, 5656.8868622627315, 5698.2592263005545, 5716.884752641466, 5742.7415766290615, 1022.412113034993, 1241.2366394667215, 1261.7099963566097, 1274.6494713953737, 1284.229462322599, 829.8009376450971, 843.4770442325674, 868.8834847221291, 891.4339394484549, 902.5306840443064, 4366.868993930188, 4481.551844815557, 4664.440267167962, 5120.134298604604, 5146.886133889018, 5090.9236361454605, 5279.386703581752, 5316.3354580677415, 5575.884823035409, 5636.802711905064, 4604.677672562955, 4640.814367425265, 5043.36957880265, 5481.12302031681, 5504.504639553912, 2620.2663254288827, 2742.4103817679293, 2754.944901101967, 2967.6438827340858, 3010.9397812043635, 5089.796408143707, 5536.446355364431, 5538.113901775697, 5739.569532285095, 5873.471387575139, 5157.767900444489, 5167.974160129175, 5526.970456884216, 5534.063077922695, 5796.478316951495, 5114.769835357343, 5464.699441530674, 5828.854278642983, 5854.970725146432, 5897.675627840425, 5407.458494507575, 5510.531604813636, 5545.861353466034, 5556.388797232382, 5607.719614019201, 4521.728696278234, 5293.735313234246, 5542.842867849113, 5564.721763911707, 5596.425644203549, 4683.929741053955, 5470.409333399937, 5704.229928959538, 5787.8553036174435, 5893.351731309467, 3371.8819841305435, 3400.8607116616213, 3411.5223868657913, 3594.3350480161253, 3693.8153092344737, 4544.614631661491, 4730.668853180245, 5051.570616497639, 5561.3882472927135, 5651.378348381757, 4362.411074504901, 4845.0, 5014.0, 5252.238425428368, 5272.470983414767, 4161.938705630045, 5096.955124199456, 5437.564994800486, 5634.549236061023, 5750.482456578928, 5039.773210205476, 5232.529072383504, 5370.758315875873, 5479.643823151482, 5636.379998200113, 4914.7542622867995, 4915.311856340187, 5714.885702285847, 5747.367789543064, 5885.941140588539, 4494.50560438345, 5044.830722789236, 5173.094708425935, 5441.374241962067, 5619.297587801525], "value": 3994.8680176246157, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.6815058395507, 633.7940169444931, 643.2538255623475, 655.567325565127, 796.9838455968641, 12399.814002789966, 13870.0, 14156.362963666634, 14400.639984000405, 14503.0, 13103.968884822843, 13341.066125371228, 13392.66518337042, 13444.126131801451, 13503.324833758323, 812.6708682983386, 835.6699103853974, 837.2130197614254, 837.7863644770575, 839.7480755773274, 652.3443938841482, 669.4778073102963, 804.5856383962264, 822.8765685147216, 834.3367023216557, 834.7996480844583, 862.1163307609687, 863.7149740585623, 865.1564724393729, 885.707716453572, 870.3907264914521, 887.6138879587368, 888.9644414223424, 888.9954351582735, 890.3589415620743, 632.3265722006049, 660.6960798032862, 662.0830150241901, 662.4303099334617, 665.0722242471774, 13798.310084495735, 13980.091294065853, 14059.086159399607, 14167.0, 14519.854801451957, 13675.863241367559, 13705.45178192871, 13759.312034398341, 14035.01754877159, 14055.437782488689, 11270.436478176058, 11357.14821388391, 11384.904702914888, 11399.316041037564, 11681.065514758884, 3663.670269675742, 3683.5027271318295, 3691.1141326081547, 3799.202167544819, 3809.047738065484, 70947.0, 89623.0, 89836.65245021264, 90586.09413905842, 95035.52482237613, 10681.82612211083, 10681.946590267155, 10809.945950270197, 10888.78222435547, 10968.012878840942, 21481.140754369964, 21487.355379338645, 21608.56782864334, 21793.237236696958, 21841.0, 7325.816854578678, 7417.295356941082, 7459.552426854298, 7588.089429268523, 7830.0, 3480.4083305838067, 4043.251998380309, 4076.062505623723, 4153.7300075494995, 4203.138356636882, 3032.0, 3115.704008119225, 3123.1567476781597, 3132.7180553750272, 3136.81179129253, 517.7806266242943, 544.6187668631974, 616.5653214483779, 628.7642134199675, 650.5283669339691, 571.5256337240081, 587.3363099697315, 591.2432086928738, 606.2452246952572, 619.6282230661567, 8427.662893484192, 8548.615312310962, 8596.957015214884, 8645.26515246211, 8797.47215167092, 469.9741514216751, 476.2403965674743, 527.8487430042716, 669.6138992285921, 679.6058286193995, 3294.341131773655, 4068.2677118119022, 4327.740335579875, 4448.932256258475, 4503.054358584668, 72204.0, 75966.10084747816, 86159.13840861636, 91477.54261228652, 104740.95259047311, 80282.5985870067, 82205.53383398507, 90271.0, 91894.0, 94530.52734736282, 82509.0, 82773.58613206896, 83684.0, 84953.99999999876, 86101.0, 9478.289128315266, 9688.128068473588, 9701.272404569827, 9727.85408218863, 9732.367396119229, 38769.80615096907, 40503.0, 55976.720116399156, 57092.714536427055, 57200.42799571951, 73306.26693733208, 76524.4695106106, 78840.21159788329, 80269.19730802617, 80432.0, 11700.800190144084, 12280.0, 12459.813102803646, 12502.624921252376, 12545.0, 13539.141344716709, 13665.726685466036, 14288.0, 14373.425062997781, 14599.489017884747, 12808.807867882175, 13386.933065334611, 13660.180389176681, 13700.24648644334, 13846.584602461942, 12351.444185011516, 12482.375881206084, 12588.307643079352, 12775.552855650008, 12939.676508087374, 12886.935565322114, 12887.548935787203, 13010.80483792725, 13038.804417933929, 13231.213786139133, 10883.0, 11076.833847492133, 11211.383373914514, 11361.772764544497, 11408.201425900439, 7586.937828704096, 10187.694369168938, 10280.897191027994, 11447.0, 11522.423878806194], "value": 7109.87683661184, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 118.0, 116.0, 120.0, 119.0, 122.0, 119.0, 124.0, 174.0], "value": 123.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 107.0, 137.0, 188.0, 145.0, 117.0, 118.0, 120.0, 144.0, 117.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 210.0, 264.0, 202.0, 196.0, 194.0, 195.0, 202.0, 196.0, 199.0], "value": 206.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 85.0, 50.0, 74.0, 53.0, 56.0, 86.0, 84.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 87.0, 71.0, 70.0, 71.0, 88.0, 71.0, 71.0, 86.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 86.0, 88.0, 102.0, 126.0, 133.0, 105.0, 127.0, 88.0, 120.0], "value": 109.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 105.0, 117.0, 173.0, 104.0, 98.0, 99.0, 98.0, 117.0, 96.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 79.0, 84.0, 76.0, 145.0, 149.0, 151.0, 94.0, 93.0], "value": 107.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 58.0, 62.0, 58.0, 83.0, 89.0, 88.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 73.0, 90.0, 78.0, 76.0, 70.0, 72.0, 71.0, 76.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 93.0, 91.0, 90.0, 90.0, 105.0, 88.0, 78.0, 79.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 148.0, 151.0, 163.0, 168.0, 163.0, 166.0, 212.0, 172.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 179.0, 178.0, 172.0, 177.0, 176.0, 175.0, 180.0, 177.0, 245.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 85.0, 89.0, 93.0, 73.0, 68.0, 70.0, 71.0, 83.0, 92.0], "value": 79.5, "unit": "score"}]}]} 12:49:48 INFO - Return code: 0 12:49:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:49:48 ERROR - # TBPL SUCCESS # 12:49:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:49:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:49:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:49:48 INFO - 2016-11-18 12:49:48.649 screenresolution[2302:21131] starting screenresolution argv=screenresolution get 12:49:48 INFO - 2016-11-18 12:49:48.652 screenresolution[2302:21131] Display 0: 1600x1200x32@60 12:49:48 INFO - 2016-11-18 12:49:48.658 screenresolution[2303:21134] starting screenresolution argv=screenresolution list 12:49:48 INFO - Available Modes on Display 0 12:49:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:49:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:49:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:49:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:49:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:49:48 INFO - Intel Iris: 12:49:48 INFO - Chipset Model: Intel Iris 12:49:48 INFO - Type: GPU 12:49:48 INFO - Bus: Built-In 12:49:48 INFO - VRAM (Dynamic, Max): 1536 MB 12:49:48 INFO - Vendor: Intel (0x8086) 12:49:48 INFO - Device ID: 0x0a2e 12:49:48 INFO - Revision ID: 0x0009 12:49:48 INFO - Displays: 12:49:48 INFO - Display: 12:49:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:49:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:49:48 INFO - Display Serial Number: 1600x1200 60 12:49:48 INFO - Main Display: Yes 12:49:48 INFO - Mirror: Off 12:49:48 INFO - Online: Yes 12:49:48 INFO - Rotation: Supported 12:49:48 INFO - Return code: 0 12:49:48 INFO - Running post-action listener: _resource_record_post_action 12:49:48 INFO - [mozharness: 2016-11-18 20:49:48.903435Z] Finished run-tests step (success) 12:49:48 INFO - Running post-run listener: _resource_record_post_run 12:49:48 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 996864; Write bytes: 697184256; Read time: 7; Write time: 1974 12:49:48 INFO - TinderboxPrint: CPU usage
54.6% 12:49:48 INFO - TinderboxPrint: I/O read bytes / time
996,864 / 7 12:49:48 INFO - TinderboxPrint: I/O write bytes / time
697,184,256 / 1,974 12:49:48 INFO - TinderboxPrint: CPU idle
2,204.4 (45.4%) 12:49:48 INFO - TinderboxPrint: CPU system
987.2 (20.3%) 12:49:48 INFO - TinderboxPrint: CPU user
1,666.0 (34.3%) 12:49:48 INFO - TinderboxPrint: Swap in / out
383,905,792 / 0 12:49:48 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 367600128; Write bytes: 367773184; Read time: 27538; Write time: 760 12:49:48 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 968192; Write bytes: 310680064; Read time: 7; Write time: 1167 12:49:49 INFO - Running post-run listener: _upload_blobber_files 12:49:49 INFO - Blob upload gear active. 12:49:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:49:49 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 12:49:49 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:49:49 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:49:49 INFO - (blobuploader) - INFO - Open directory for files ... 12:49:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:49:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:49:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:50 INFO - (blobuploader) - INFO - Done attempting. 12:49:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:49:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:49:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:51 INFO - (blobuploader) - INFO - Done attempting. 12:49:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:49:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:49:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:54 INFO - (blobuploader) - INFO - Done attempting. 12:49:54 INFO - (blobuploader) - INFO - Iteration through files over. 12:49:54 INFO - Return code: 0 12:49:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:49:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:49:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43db9e8cce790c1749e888d510aa93fc86f89ab14496098a5dd9c8aab945bb1ba57a46ec2dd45bc64c545aaf8ac5b21ccf339c8a8e446c728595cb625e5625cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eab76a36a3bc25830d6a955ea04ae5d77d153176ee778a3387d4e6bab795176318017198d5a63f584d39c8b6f7bc7ef024059b4ebc309c807a030ef3e4ecc14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b295e8378c60d44e22378854d99f0e2f7e73995b85ee13fbb4077c4a3e0a95b32d536a1b729371e8c948e3adf03e39a7a5ad3360c63ee2e0eff25444250d8873"} 12:49:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:49:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:49:54 INFO - Contents: 12:49:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43db9e8cce790c1749e888d510aa93fc86f89ab14496098a5dd9c8aab945bb1ba57a46ec2dd45bc64c545aaf8ac5b21ccf339c8a8e446c728595cb625e5625cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eab76a36a3bc25830d6a955ea04ae5d77d153176ee778a3387d4e6bab795176318017198d5a63f584d39c8b6f7bc7ef024059b4ebc309c807a030ef3e4ecc14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b295e8378c60d44e22378854d99f0e2f7e73995b85ee13fbb4077c4a3e0a95b32d536a1b729371e8c948e3adf03e39a7a5ad3360c63ee2e0eff25444250d8873"} 12:49:54 INFO - Running post-run listener: copy_logs_to_upload_dir 12:49:54 INFO - Copying logs to upload dir... 12:49:54 INFO - mkdir: /builds/slave/test/build/upload/logs 12:49:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.787256 ========= master_lag: 2.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-18 12:49:56.916707) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-11-18 12:49:56.925475) ========= 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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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/Firefox/sha512/43db9e8cce790c1749e888d510aa93fc86f89ab14496098a5dd9c8aab945bb1ba57a46ec2dd45bc64c545aaf8ac5b21ccf339c8a8e446c728595cb625e5625cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eab76a36a3bc25830d6a955ea04ae5d77d153176ee778a3387d4e6bab795176318017198d5a63f584d39c8b6f7bc7ef024059b4ebc309c807a030ef3e4ecc14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b295e8378c60d44e22378854d99f0e2f7e73995b85ee13fbb4077c4a3e0a95b32d536a1b729371e8c948e3adf03e39a7a5ad3360c63ee2e0eff25444250d8873"} build_url:https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/HactYvgcTA2YLBPAt_LRyA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43db9e8cce790c1749e888d510aa93fc86f89ab14496098a5dd9c8aab945bb1ba57a46ec2dd45bc64c545aaf8ac5b21ccf339c8a8e446c728595cb625e5625cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eab76a36a3bc25830d6a955ea04ae5d77d153176ee778a3387d4e6bab795176318017198d5a63f584d39c8b6f7bc7ef024059b4ebc309c807a030ef3e4ecc14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b295e8378c60d44e22378854d99f0e2f7e73995b85ee13fbb4077c4a3e0a95b32d536a1b729371e8c948e3adf03e39a7a5ad3360c63ee2e0eff25444250d8873"}' ========= master_lag: 8.45 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-11-18 12:50:05.385953) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-11-18 12:50:05.386503) ========= 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.Fn6be3jF8A/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.hmN3mwY6bi/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.005231 ========= master_lag: 7.33 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-11-18 12:50:12.719798) ========= ========= Started reboot skipped (results: 3, elapsed: 5 secs) (at 2016-11-18 12:50:12.720106) ========= ========= Finished reboot skipped (results: 3, elapsed: 5 secs) (at 2016-11-18 12:50:17.900372) ========= ========= Total master_lag: 18.49 =========