builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0092 starttime: 1478359549.04 results: success (0) buildid: 20161105031321 builduid: 90d6a77f3a2e4c7fbcf38adbf7d74146 revision: 73b00825762607f642563256fdae2ce66e883d0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.038168) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.038659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.038954) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.065161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.065548) ========= 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.zAm1iceJI9/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.owAVWOJjJ4/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-05 08:25:49-- 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% 9.03M=0.001s 2016-11-05 08:25:49 (9.03 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113447 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.201903) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.202223) ========= 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.zAm1iceJI9/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.owAVWOJjJ4/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.027639 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.250955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.251354) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 73b00825762607f642563256fdae2ce66e883d0e --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 73b00825762607f642563256fdae2ce66e883d0e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zAm1iceJI9/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.owAVWOJjJ4/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-05 08:25:49,328 truncating revision to first 12 chars 2016-11-05 08:25:49,328 Setting DEBUG logging. 2016-11-05 08:25:49,329 attempt 1/10 2016-11-05 08:25:49,329 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/73b008257626?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 08:25:49,675 unpacking tar archive at: mozilla-central-73b008257626/testing/mozharness/ program finished with exit code 0 elapsedTime=0.593220 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.868452) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.868933) ========= script_repo_revision: 73b00825762607f642563256fdae2ce66e883d0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.869516) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.869953) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 08:25:49.890865) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-05 08:25:49.891231) ========= /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.zAm1iceJI9/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.owAVWOJjJ4/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:25:50 INFO - MultiFileLogger online at 20161105 08:25:50 in /builds/slave/test 08:25:50 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 08:25:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:25:50 INFO - {'append_to_log': False, 08:25:50 INFO - 'base_work_dir': '/builds/slave/test', 08:25:50 INFO - 'blob_upload_branch': 'Firefox', 08:25:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:25:50 INFO - 'branch': 'Firefox', 08:25:50 INFO - 'buildbot_json_path': 'buildprops.json', 08:25:50 INFO - 'config_files': ('talos/mac_config.py',), 08:25:50 INFO - 'default_actions': ('clobber', 08:25:50 INFO - 'read-buildbot-config', 08:25:50 INFO - 'download-and-extract', 08:25:50 INFO - 'populate-webroot', 08:25:50 INFO - 'create-virtualenv', 08:25:50 INFO - 'install', 08:25:50 INFO - 'run-tests'), 08:25:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:25:50 INFO - 'download_minidump_stackwalk': True, 08:25:50 INFO - 'download_symbols': 'ondemand', 08:25:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:25:50 INFO - 'tooltool.py': '/tools/tooltool.py', 08:25:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:25:50 INFO - '/tools/misc-python/virtualenv.py')}, 08:25:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:25:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:25:50 INFO - 'installer_path': 'installer.exe', 08:25:50 INFO - 'log_level': 'info', 08:25:50 INFO - 'log_name': 'talos', 08:25:50 INFO - 'log_to_console': True, 08:25:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:25:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:25:50 INFO - 'opt_config_files': (), 08:25:50 INFO - 'pip_index': False, 08:25:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:25:50 INFO - 'cmd': ('bash', 08:25:50 INFO - '-c', 08:25:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:25:50 INFO - 'enabled': True, 08:25:50 INFO - 'halt_on_failure': False, 08:25:50 INFO - 'name': 'check_screen_resolution'},), 08:25:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:25:50 INFO - 'cmd': ('bash', 08:25:50 INFO - '-c', 08:25:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:25:50 INFO - 'enabled': True, 08:25:50 INFO - 'halt_on_failure': False, 08:25:50 INFO - 'name': 'check_screen_resolution'},), 08:25:50 INFO - 'run_cmd_checks_enabled': True, 08:25:50 INFO - 'sps_profile': False, 08:25:50 INFO - 'sps_profile_interval': 0, 08:25:50 INFO - 'suite': 'dromaeojs-e10s', 08:25:50 INFO - 'system_bits': '32', 08:25:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:25:50 INFO - 'title': 't-yosemite-r7-0092', 08:25:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:25:50 INFO - 'use_talos_json': True, 08:25:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:25:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:25:50 INFO - 'work_dir': 'build'} 08:25:50 INFO - [mozharness: 2016-11-05 15:25:50.097381Z] Running clobber step. 08:25:50 INFO - Running pre-action listener: _resource_record_pre_action 08:25:50 INFO - Running main action method: clobber 08:25:50 INFO - rmtree: /builds/slave/test/build 08:25:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:25:52 INFO - Running post-action listener: _resource_record_post_action 08:25:52 INFO - [mozharness: 2016-11-05 15:25:52.573255Z] Finished clobber step (success) 08:25:52 INFO - [mozharness: 2016-11-05 15:25:52.573419Z] Running read-buildbot-config step. 08:25:52 INFO - Running pre-action listener: _resource_record_pre_action 08:25:52 INFO - Running main action method: read_buildbot_config 08:25:52 INFO - Using buildbot properties: 08:25:52 INFO - { 08:25:52 INFO - "project": "", 08:25:52 INFO - "product": "firefox", 08:25:52 INFO - "script_repo_revision": "production", 08:25:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:25:52 INFO - "repository": "", 08:25:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:25:52 INFO - "buildid": "20161105031321", 08:25:52 INFO - "pgo_build": "False", 08:25:52 INFO - "basedir": "/builds/slave/test", 08:25:52 INFO - "buildnumber": 6, 08:25:52 INFO - "slavename": "t-yosemite-r7-0092", 08:25:52 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:25:52 INFO - "platform": "yosemite_r7", 08:25:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:25:52 INFO - "branch": "mozilla-central", 08:25:52 INFO - "repo_path": "mozilla-central", 08:25:52 INFO - "revision": "73b00825762607f642563256fdae2ce66e883d0e", 08:25:52 INFO - "stage_platform": "macosx64", 08:25:52 INFO - "builduid": "90d6a77f3a2e4c7fbcf38adbf7d74146", 08:25:52 INFO - "slavebuilddir": "test" 08:25:52 INFO - } 08:25:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:25:52 INFO - Running post-action listener: _resource_record_post_action 08:25:52 INFO - [mozharness: 2016-11-05 15:25:52.576996Z] Finished read-buildbot-config step (success) 08:25:52 INFO - [mozharness: 2016-11-05 15:25:52.577108Z] Running download-and-extract step. 08:25:52 INFO - Running pre-action listener: _resource_record_pre_action 08:25:52 INFO - Running main action method: download_and_extract 08:25:52 INFO - mkdir: /builds/slave/test/build/tests 08:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:52 INFO - trying https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:25:52 INFO - Downloading https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:25:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:25:53 INFO - Downloaded 1199 bytes. 08:25:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:25:53 INFO - Using the following test package requirements: 08:25:53 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:25:53 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:25:53 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:25:53 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'jsshell-mac.zip'], 08:25:53 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:25:53 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:25:53 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:25:53 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:25:53 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:25:53 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:53 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:25:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:25:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:25:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:25:53 INFO - Fetch https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:25:54 INFO - Http code: 200 08:25:54 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 08:25:54 INFO - x-amz-cf-id: qDkVurfWcAEKvIqKKX7THDTTgLcQNwG3EWWxsKuui2-FhEgH3mMQrg== 08:25:54 INFO - x-amz-version-id: 74KDl_CD5d4ajn5j5Q7a5.Qpz5OTNyRJ 08:25:54 INFO - Expected file size: 16295883 08:25:54 INFO - Obtained file size: 16295883 08:26:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:26:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:26:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:26:01 INFO - Fetch https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:26:01 INFO - Http code: 200 08:26:01 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 08:26:01 INFO - x-amz-cf-id: Ti4GdGAeMDAlbwMbdhSe4WJ0VQ4sLcag5z3THCRrmwAheUU8Zkm3yQ== 08:26:01 INFO - x-amz-version-id: 74KDl_CD5d4ajn5j5Q7a5.Qpz5OTNyRJ 08:26:02 INFO - Expected file size: 16295883 08:26:02 INFO - Obtained file size: 16295883 08:26:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:26:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:26:08 INFO - Fetch https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:26:09 INFO - Http code: 200 08:26:09 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 08:26:09 INFO - x-amz-cf-id: dbitZMl1eBH6h9dYKODnvsSqHCJe0uJRX5SMVOtxfaF1l0UrH5gWSw== 08:26:09 INFO - x-amz-version-id: 0oX.NHzH3C2tTtNm_K6u4Z_qRl4DRYvs 08:26:09 INFO - Expected file size: 14409038 08:26:09 INFO - Obtained file size: 14409038 08:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:09 INFO - trying https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:26:09 INFO - Downloading https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:26:12 INFO - Downloaded 111213562 bytes. 08:26:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:26:12 INFO - mkdir: /builds/slave/test/properties 08:26:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:26:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:26:12 INFO - Contents: 08:26:12 INFO - build_url:https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:26:13 INFO - Running post-action listener: _resource_record_post_action 08:26:13 INFO - Running post-action listener: set_extra_try_arguments 08:26:13 INFO - [mozharness: 2016-11-05 15:26:13.514700Z] Finished download-and-extract step (success) 08:26:13 INFO - [mozharness: 2016-11-05 15:26:13.514796Z] Running populate-webroot step. 08:26:13 INFO - Running pre-action listener: _resource_record_pre_action 08:26:13 INFO - Running main action method: populate_webroot 08:26:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:26:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:26:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:26:13 INFO - '--tppagecycles', 08:26:13 INFO - '7'], 08:26:13 INFO - 'tests': ['tsvgm']}}, 08:26:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:26:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:26:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:26:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:26:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['damp', 'tps']}, 08:26:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'tests': ['damp', 'tps']}, 08:26:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['dromaeo_dom']}, 08:26:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:26:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['basic_compositor_video']}, 08:26:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:26:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['a11yr', 08:26:13 INFO - 'ts_paint', 08:26:13 INFO - 'tpaint', 08:26:13 INFO - 'sessionrestore', 08:26:13 INFO - 'sessionrestore_no_auto_restore', 08:26:13 INFO - 'tabpaint']}, 08:26:13 INFO - 'other-e10s': {'tests': ['a11yr', 08:26:13 INFO - 'ts_paint', 08:26:13 INFO - 'tpaint', 08:26:13 INFO - 'sessionrestore', 08:26:13 INFO - 'sessionrestore_no_auto_restore', 08:26:13 INFO - 'tabpaint']}, 08:26:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:26:13 INFO - 'ts_paint', 08:26:13 INFO - 'tpaint', 08:26:13 INFO - 'sessionrestore', 08:26:13 INFO - 'sessionrestore_no_auto_restore', 08:26:13 INFO - 'tabpaint']}, 08:26:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:26:13 INFO - 'ts_paint', 08:26:13 INFO - 'tpaint', 08:26:13 INFO - 'sessionrestore', 08:26:13 INFO - 'sessionrestore_no_auto_restore', 08:26:13 INFO - 'tabpaint']}, 08:26:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['a11yr', 08:26:13 INFO - 'ts_paint', 08:26:13 INFO - 'tpaint', 08:26:13 INFO - 'sessionrestore', 08:26:13 INFO - 'sessionrestore_no_auto_restore', 08:26:13 INFO - 'tabpaint']}, 08:26:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['a11yr', 08:26:13 INFO - 'ts_paint', 08:26:13 INFO - 'tpaint', 08:26:13 INFO - 'sessionrestore', 08:26:13 INFO - 'sessionrestore_no_auto_restore', 08:26:13 INFO - 'tabpaint']}, 08:26:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['tsvgx', 08:26:13 INFO - 'tsvgr_opacity', 08:26:13 INFO - 'tart', 08:26:13 INFO - 'tscrollx', 08:26:13 INFO - 'cart']}, 08:26:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:26:13 INFO - 'tsvgr_opacity', 08:26:13 INFO - 'tart', 08:26:13 INFO - 'tscrollx', 08:26:13 INFO - 'cart']}, 08:26:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'talos_options': ['--disable-e10s'], 08:26:13 INFO - 'tests': ['tp5o']}, 08:26:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'tests': ['tp5o']}, 08:26:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'talos_options': ['--disable-e10s', 08:26:13 INFO - '--xperf_path', 08:26:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:13 INFO - 'tests': ['tp5n']}, 08:26:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:13 INFO - 'talos_options': ['--xperf_path', 08:26:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:13 INFO - 'tests': ['tp5n']}}, 08:26:13 INFO - 'talos.zip': {'path': '', 08:26:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:26:13 INFO - Running post-action listener: _resource_record_post_action 08:26:13 INFO - [mozharness: 2016-11-05 15:26:13.534078Z] Finished populate-webroot step (success) 08:26:13 INFO - [mozharness: 2016-11-05 15:26:13.534208Z] Running create-virtualenv step. 08:26:13 INFO - Running pre-action listener: _resource_record_pre_action 08:26:13 INFO - Running main action method: create_virtualenv 08:26:13 INFO - Creating virtualenv /builds/slave/test/build/venv 08:26:13 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 08:26:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:26:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:26:13 INFO - Using real prefix '/tools/python27' 08:26:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:26:14 INFO - Installing distribute.............................................................................................................................................................................................done. 08:26:17 INFO - Installing pip.................done. 08:26:17 INFO - Return code: 0 08:26:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:26:17 INFO - Reading from file tmpfile_stdout 08:26:17 INFO - Output received: 08:26:17 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) 08:26:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:17 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 08:26:17 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 08:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:17 INFO - 'HOME': '/Users/cltbld', 08:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:17 INFO - 'LOGNAME': 'cltbld', 08:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:17 INFO - 'MOZ_NO_REMOTE': '1', 08:26:17 INFO - 'NO_EM_RESTART': '1', 08:26:17 INFO - 'PAGER': '/bin/cat', 08:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:17 INFO - 'PWD': '/builds/slave/test', 08:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:17 INFO - 'SHELL': '/bin/bash', 08:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:17 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:17 INFO - 'USER': 'cltbld', 08:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:17 INFO - 'XPC_FLAGS': '0x0', 08:26:17 INFO - 'XPC_SERVICE_NAME': '0', 08:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:17 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 08:26:17 INFO - Cleaning up... 08:26:17 INFO - Return code: 0 08:26:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:17 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 08:26:17 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 08:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:17 INFO - 'HOME': '/Users/cltbld', 08:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:17 INFO - 'LOGNAME': 'cltbld', 08:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:17 INFO - 'MOZ_NO_REMOTE': '1', 08:26:17 INFO - 'NO_EM_RESTART': '1', 08:26:17 INFO - 'PAGER': '/bin/cat', 08:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:17 INFO - 'PWD': '/builds/slave/test', 08:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:17 INFO - 'SHELL': '/bin/bash', 08:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:17 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:17 INFO - 'USER': 'cltbld', 08:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:17 INFO - 'XPC_FLAGS': '0x0', 08:26:17 INFO - 'XPC_SERVICE_NAME': '0', 08:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:18 INFO - Downloading/unpacking psutil>=3.1.1 08:26:18 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 08:26:18 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 08:26:18 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 08:26:18 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 08:26:18 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 08:26:18 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 08:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:26:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:21 INFO - Installing collected packages: psutil 08:26:21 INFO - Running setup.py install for psutil 08:26:21 INFO - building 'psutil._psutil_osx' extension 08:26:21 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 08:26:22 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 08:26:22 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 08:26:22 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 08:26:22 INFO - building 'psutil._psutil_posix' extension 08:26:22 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 08:26:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:26:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:26:22 INFO - ^ 08:26:22 INFO - 1 warning generated. 08:26:22 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 08:26:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:22 INFO - Successfully installed psutil 08:26:22 INFO - Cleaning up... 08:26:22 INFO - Return code: 0 08:26:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:26:22 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')]} 08:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:22 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')]} 08:26:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:22 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:22 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 08:26:22 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 08:26:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:22 INFO - 'HOME': '/Users/cltbld', 08:26:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:22 INFO - 'LOGNAME': 'cltbld', 08:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:22 INFO - 'MOZ_NO_REMOTE': '1', 08:26:22 INFO - 'NO_EM_RESTART': '1', 08:26:22 INFO - 'PAGER': '/bin/cat', 08:26:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:22 INFO - 'PWD': '/builds/slave/test', 08:26:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:22 INFO - 'SHELL': '/bin/bash', 08:26:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:22 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:22 INFO - 'USER': 'cltbld', 08:26:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:22 INFO - 'XPC_FLAGS': '0x0', 08:26:22 INFO - 'XPC_SERVICE_NAME': '0', 08:26:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:26:25 INFO - Installing collected packages: mozsystemmonitor 08:26:25 INFO - Running setup.py install for mozsystemmonitor 08:26:25 INFO - Successfully installed mozsystemmonitor 08:26:25 INFO - Cleaning up... 08:26:25 INFO - Return code: 0 08:26:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:25 INFO - 'HOME': '/Users/cltbld', 08:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:25 INFO - 'LOGNAME': 'cltbld', 08:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:25 INFO - 'MOZ_NO_REMOTE': '1', 08:26:25 INFO - 'NO_EM_RESTART': '1', 08:26:25 INFO - 'PAGER': '/bin/cat', 08:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:25 INFO - 'PWD': '/builds/slave/test', 08:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:25 INFO - 'SHELL': '/bin/bash', 08:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:25 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:25 INFO - 'USER': 'cltbld', 08:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:25 INFO - 'XPC_FLAGS': '0x0', 08:26:25 INFO - 'XPC_SERVICE_NAME': '0', 08:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:26 INFO - Downloading/unpacking jsonschema==2.5.1 08:26:26 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 08:26:26 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 08:26:26 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 08:26:26 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 08:26:26 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 08:26:26 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 08:26:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:26:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:26:28 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 08:26:28 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 08:26:28 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 08:26:28 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 08:26:28 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 08:26:28 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 08:26:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:26:29 INFO - warning: no files found matching '*.txt' 08:26:29 INFO - no previously-included directories found matching 'build' 08:26:29 INFO - no previously-included directories found matching 'dist' 08:26:29 INFO - no previously-included directories found matching '.git*' 08:26:29 INFO - Installing collected packages: jsonschema, functools32 08:26:29 INFO - Running setup.py install for functools32 08:26:29 INFO - warning: no files found matching '*.txt' 08:26:29 INFO - no previously-included directories found matching 'build' 08:26:29 INFO - no previously-included directories found matching 'dist' 08:26:29 INFO - no previously-included directories found matching '.git*' 08:26:29 INFO - Successfully installed jsonschema functools32 08:26:29 INFO - Cleaning up... 08:26:29 INFO - Return code: 0 08:26:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:26:29 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')]} 08:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:29 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')]} 08:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:29 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:29 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 08:26:29 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 08:26:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:29 INFO - 'HOME': '/Users/cltbld', 08:26:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:29 INFO - 'LOGNAME': 'cltbld', 08:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:29 INFO - 'MOZ_NO_REMOTE': '1', 08:26:29 INFO - 'NO_EM_RESTART': '1', 08:26:29 INFO - 'PAGER': '/bin/cat', 08:26:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:29 INFO - 'PWD': '/builds/slave/test', 08:26:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:29 INFO - 'SHELL': '/bin/bash', 08:26:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:29 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:29 INFO - 'USER': 'cltbld', 08:26:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:29 INFO - 'XPC_FLAGS': '0x0', 08:26:29 INFO - 'XPC_SERVICE_NAME': '0', 08:26:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:26:29 INFO - Cleaning up... 08:26:29 INFO - Return code: 0 08:26:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:26:29 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')]} 08:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:29 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')]} 08:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:29 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:29 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 08:26:29 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 08:26:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:29 INFO - 'HOME': '/Users/cltbld', 08:26:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:29 INFO - 'LOGNAME': 'cltbld', 08:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:29 INFO - 'MOZ_NO_REMOTE': '1', 08:26:29 INFO - 'NO_EM_RESTART': '1', 08:26:29 INFO - 'PAGER': '/bin/cat', 08:26:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:29 INFO - 'PWD': '/builds/slave/test', 08:26:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:29 INFO - 'SHELL': '/bin/bash', 08:26:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:29 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:29 INFO - 'USER': 'cltbld', 08:26:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:29 INFO - 'XPC_FLAGS': '0x0', 08:26:29 INFO - 'XPC_SERVICE_NAME': '0', 08:26:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:26:29 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 08:26:29 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 08:26:29 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 08:26:29 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 08:26:29 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 08:26:29 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 08:26:32 INFO - Downloading blobuploader-1.2.4.tar.gz 08:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:26:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:26:32 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 08:26:32 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 08:26:32 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 08:26:32 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 08:26:32 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 08:26:32 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 08:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:26:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:26:33 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 08:26:33 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 08:26:33 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 08:26:33 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 08:26:33 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 08:26:33 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 08:26:33 INFO - Downloading docopt-0.6.1.tar.gz 08:26:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:26:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:26:33 INFO - Running setup.py install for blobuploader 08:26:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:26:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:26:33 INFO - Running setup.py install for requests 08:26:34 INFO - Running setup.py install for docopt 08:26:34 INFO - Successfully installed blobuploader requests docopt 08:26:34 INFO - Cleaning up... 08:26:34 INFO - Return code: 0 08:26:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26: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')]} 08:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26: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')]} 08:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:34 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:34 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 08:26:34 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 08:26:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:34 INFO - 'HOME': '/Users/cltbld', 08:26:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:34 INFO - 'LOGNAME': 'cltbld', 08:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:34 INFO - 'MOZ_NO_REMOTE': '1', 08:26:34 INFO - 'NO_EM_RESTART': '1', 08:26:34 INFO - 'PAGER': '/bin/cat', 08:26:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:34 INFO - 'PWD': '/builds/slave/test', 08:26:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:34 INFO - 'SHELL': '/bin/bash', 08:26:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:34 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:34 INFO - 'USER': 'cltbld', 08:26:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:34 INFO - 'XPC_FLAGS': '0x0', 08:26:34 INFO - 'XPC_SERVICE_NAME': '0', 08:26:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:34 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-XiVass-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:34 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-lw1WKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:34 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-RJzWKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-TH920Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-e5Upk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-YqJ4Sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-GPwFG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-P7rdE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-2gtBKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-UdJwEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-FVhDNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-sGF1sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-HOPd5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-LDCMSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-7WAIk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-htUhc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-ipSIKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:26:36 INFO - Running setup.py install for manifestparser 08:26:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:26:36 INFO - Running setup.py install for mozcrash 08:26:36 INFO - Running setup.py install for mozdebug 08:26:36 INFO - Running setup.py install for mozdevice 08:26:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Running setup.py install for mozfile 08:26:37 INFO - Running setup.py install for mozhttpd 08:26:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Running setup.py install for mozinfo 08:26:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Running setup.py install for mozInstall 08:26:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:26:37 INFO - Running setup.py install for mozleak 08:26:37 INFO - Running setup.py install for mozlog 08:26:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:26:38 INFO - Running setup.py install for moznetwork 08:26:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:26:38 INFO - Running setup.py install for mozprocess 08:26:38 INFO - Running setup.py install for mozprofile 08:26:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:26:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:26:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:26:38 INFO - Running setup.py install for mozrunner 08:26:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:26:38 INFO - Running setup.py install for mozscreenshot 08:26:38 INFO - Running setup.py install for moztest 08:26:38 INFO - Running setup.py install for mozversion 08:26:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:26:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:26:39 INFO - Cleaning up... 08:26:39 INFO - Return code: 0 08:26:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:39 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')]} 08:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:39 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')]} 08:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:39 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:39 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 08:26:39 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 08:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:39 INFO - 'HOME': '/Users/cltbld', 08:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:39 INFO - 'LOGNAME': 'cltbld', 08:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:39 INFO - 'MOZ_NO_REMOTE': '1', 08:26:39 INFO - 'NO_EM_RESTART': '1', 08:26:39 INFO - 'PAGER': '/bin/cat', 08:26:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:39 INFO - 'PWD': '/builds/slave/test', 08:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:39 INFO - 'SHELL': '/bin/bash', 08:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:39 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:39 INFO - 'USER': 'cltbld', 08:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:39 INFO - 'XPC_FLAGS': '0x0', 08:26:39 INFO - 'XPC_SERVICE_NAME': '0', 08:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:39 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-diDFVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:39 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)) 08:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:39 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-0YjzsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 08:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:39 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-bN0rlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:39 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)) 08:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:39 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-5fc1vW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:39 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-hZJ3_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:39 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)) 08:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:39 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-iUFhvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-nIsnVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-DUAVWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-4aXH_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-JxIZvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-DHg7gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-aA1tDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-z5psf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-PbFgmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-eZbeWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:40 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)) 08:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-NR5Tgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:41 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)) 08:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-iEHLWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:41 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)) 08:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:26:41 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)) 08:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:26:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:43 INFO - Downloading blessings-1.6.tar.gz 08:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:26:43 INFO - Installing collected packages: blessings 08:26:43 INFO - Running setup.py install for blessings 08:26:43 INFO - Successfully installed blessings 08:26:43 INFO - Cleaning up... 08:26:44 INFO - Return code: 0 08:26:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:26:44 INFO - Reading from file tmpfile_stdout 08:26:44 INFO - Current package versions: 08:26:44 INFO - blessings == 1.6 08:26:44 INFO - blobuploader == 1.2.4 08:26:44 INFO - docopt == 0.6.1 08:26:44 INFO - functools32 == 3.2.3-2 08:26:44 INFO - jsonschema == 2.5.1 08:26:44 INFO - manifestparser == 1.1 08:26:44 INFO - mozInstall == 1.12 08:26:44 INFO - mozcrash == 0.17 08:26:44 INFO - mozdebug == 0.1 08:26:44 INFO - mozdevice == 0.48 08:26:44 INFO - mozfile == 1.2 08:26:44 INFO - mozhttpd == 0.7 08:26:44 INFO - mozinfo == 0.9 08:26:44 INFO - mozleak == 0.1 08:26:44 INFO - mozlog == 3.4 08:26:44 INFO - moznetwork == 0.27 08:26:44 INFO - mozprocess == 0.23 08:26:44 INFO - mozprofile == 0.28 08:26:44 INFO - mozrunner == 6.12 08:26:44 INFO - mozscreenshot == 0.1 08:26:44 INFO - mozsystemmonitor == 0.3 08:26:44 INFO - moztest == 0.7 08:26:44 INFO - mozversion == 1.4 08:26:44 INFO - psutil == 3.1.1 08:26:44 INFO - requests == 1.2.3 08:26:44 INFO - wsgiref == 0.1.2 08:26:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:44 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')]} 08:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:44 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')]} 08:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:44 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:44 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 08:26:44 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 08:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:44 INFO - 'HOME': '/Users/cltbld', 08:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:44 INFO - 'LOGNAME': 'cltbld', 08:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:44 INFO - 'MOZ_NO_REMOTE': '1', 08:26:44 INFO - 'NO_EM_RESTART': '1', 08:26:44 INFO - 'PAGER': '/bin/cat', 08:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:44 INFO - 'PWD': '/builds/slave/test', 08:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:44 INFO - 'SHELL': '/bin/bash', 08:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:44 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:44 INFO - 'USER': 'cltbld', 08:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:44 INFO - 'XPC_FLAGS': '0x0', 08:26:44 INFO - 'XPC_SERVICE_NAME': '0', 08:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 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)) 08:26:44 INFO - Cleaning up... 08:26:44 INFO - Return code: 0 08:26:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:26:44 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')]} 08:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:44 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')]} 08:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:44 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 0x108089490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6ca59f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, '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.zAm1iceJI9/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.owAVWOJjJ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:26:44 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 08:26:44 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 08:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:44 INFO - 'HOME': '/Users/cltbld', 08:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:44 INFO - 'LOGNAME': 'cltbld', 08:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:44 INFO - 'MOZ_NO_REMOTE': '1', 08:26:44 INFO - 'NO_EM_RESTART': '1', 08:26:44 INFO - 'PAGER': '/bin/cat', 08:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:44 INFO - 'PWD': '/builds/slave/test', 08:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:44 INFO - 'SHELL': '/bin/bash', 08:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:26:44 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:44 INFO - 'USER': 'cltbld', 08:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:44 INFO - 'XPC_FLAGS': '0x0', 08:26:44 INFO - 'XPC_SERVICE_NAME': '0', 08:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:26:44 INFO - Cleaning up... 08:26:44 INFO - Return code: 0 08:26:44 INFO - Running post-action listener: _resource_record_post_action 08:26:44 INFO - Running post-action listener: _start_resource_monitoring 08:26:44 INFO - Starting resource monitoring. 08:26:44 INFO - [mozharness: 2016-11-05 15:26:44.951035Z] Finished create-virtualenv step (success) 08:26:44 INFO - [mozharness: 2016-11-05 15:26:44.951994Z] Running install step. 08:26:44 INFO - Running pre-action listener: _resource_record_pre_action 08:26:44 INFO - Running main action method: install 08:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:26:45 INFO - Reading from file tmpfile_stdout 08:26:45 INFO - Detecting whether we're running mozinstall >=1.0... 08:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:26:45 INFO - Reading from file tmpfile_stdout 08:26:45 INFO - Output received: 08:26:45 INFO - Usage: mozinstall [options] installer 08:26:45 INFO - Options: 08:26:45 INFO - -h, --help show this help message and exit 08:26:45 INFO - -d DEST, --destination=DEST 08:26:45 INFO - Directory to install application into. [default: 08:26:45 INFO - "/builds/slave/test"] 08:26:45 INFO - --app=APP Application being installed. [default: firefox] 08:26:45 INFO - mkdir: /builds/slave/test/build/application 08:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:27:19 INFO - Reading from file tmpfile_stdout 08:27:19 INFO - Output received: 08:27:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:27:19 INFO - Running post-action listener: _resource_record_post_action 08:27:19 INFO - [mozharness: 2016-11-05 15:27:19.500376Z] Finished install step (success) 08:27:19 INFO - [mozharness: 2016-11-05 15:27:19.500553Z] Running run-tests step. 08:27:19 INFO - Running pre-action listener: _resource_record_pre_action 08:27:19 INFO - Running main action method: run_tests 08:27:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:19 INFO - 2016-11-05 08:27:19.514 screenresolution[1640:7462] starting screenresolution argv=screenresolution get 08:27:19 INFO - 2016-11-05 08:27:19.517 screenresolution[1640:7462] Display 0: 1600x1200x32@60 08:27:19 INFO - 2016-11-05 08:27:19.523 screenresolution[1641:7464] starting screenresolution argv=screenresolution list 08:27:19 INFO - Available Modes on Display 0 08:27:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:19 INFO - Intel Iris: 08:27:19 INFO - Chipset Model: Intel Iris 08:27:19 INFO - Type: GPU 08:27:19 INFO - Bus: Built-In 08:27:19 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:19 INFO - Vendor: Intel (0x8086) 08:27:19 INFO - Device ID: 0x0a2e 08:27:19 INFO - Revision ID: 0x0009 08:27:19 INFO - Displays: 08:27:19 INFO - Display: 08:27:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:19 INFO - Display Serial Number: 1600x1200 60 08:27:19 INFO - Main Display: Yes 08:27:19 INFO - Mirror: Off 08:27:19 INFO - Online: Yes 08:27:19 INFO - Rotation: Supported 08:27:19 INFO - Return code: 0 08:27:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:27:19 INFO - Python 2.7.3 08:27:19 INFO - Return code: 0 08:27:19 INFO - grabbing minidump binary from tooltool 08:27: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')]} 08:27:19 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 0x107e9dd30>, '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 0x107cc3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e95b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:27:19 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 08:27:19 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 08:27:19 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 08:27:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:27:19 INFO - Return code: 0 08:27:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:27:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:27:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:27:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:27:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:27:19 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/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 08:27:19 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/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0092 --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 08:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zAm1iceJI9/Render', 08:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:19 INFO - 'HOME': '/Users/cltbld', 08:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:19 INFO - 'LOGNAME': 'cltbld', 08:27:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:27:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:19 INFO - 'MOZ_NO_REMOTE': '1', 08:27:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:27:19 INFO - 'NO_EM_RESTART': '1', 08:27:19 INFO - 'PAGER': '/bin/cat', 08:27:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:19 INFO - 'PWD': '/builds/slave/test', 08:27:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:19 INFO - 'SHELL': '/bin/bash', 08:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.owAVWOJjJ4/Listeners', 08:27:19 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:19 INFO - 'USER': 'cltbld', 08:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:19 INFO - 'XPC_FLAGS': '0x0', 08:27:19 INFO - 'XPC_SERVICE_NAME': '0', 08:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:19 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/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 08:27:20 INFO - mozversion application_buildid: 20161105031321 08:27:20 INFO - mozversion application_changeset: 73b00825762607f642563256fdae2ce66e883d0e 08:27:20 INFO - mozversion application_display_name: Nightly 08:27:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:27:20 INFO - mozversion application_name: Firefox 08:27:20 INFO - mozversion application_remotingname: firefox 08:27:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:27:20 INFO - mozversion application_vendor: Mozilla 08:27:20 INFO - mozversion application_version: 52.0a1 08:27:20 INFO - mozversion platform_buildid: 20161105031321 08:27:20 INFO - mozversion platform_changeset: 73b00825762607f642563256fdae2ce66e883d0e 08:27:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:27:20 INFO - mozversion platform_version: 52.0a1 08:27:20 INFO - using testdate: 1478359640 08:27:20 INFO - actual date: 1478359640 08:27:20 INFO - starting webserver on 'localhost:49274' 08:27:20 INFO - SUITE-START | Running 2 tests 08:27:20 INFO - TEST-START | dromaeo_css 08:27:20 INFO - operating with platform_type : mac_ 08:27:20 INFO - Initialising browser for dromaeo_css test... 08:27:20 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpeyOGp9/profile http://localhost:49274/getInfo.html) 08:27:22 INFO - PROCESS | 1651 | 2016-11-05 08:27:22.862 plugin-container[1653:7640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 08:27:22 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:22 INFO - PROCESS | 1651 | 2016-11-05 08:27:22.865 plugin-container[1653:7640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 08:27:22 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:22 INFO - PROCESS | 1651 | 2016-11-05 08:27:22.865 plugin-container[1653:7640] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:27:22 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:27:22 INFO - PROCESS | 1651 | colorDepth:24 08:27:22 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:27:22 INFO - PROCESS | 1651 | __metrics 08:27:23 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 08:27:23 INFO - TEST-INFO | 1651: exit 0 08:27:23 INFO - Browser initialized. 08:27:23 INFO - Running cycle 1/1 for dromaeo_css test... 08:27:23 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpeyOGp9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:26 INFO - PROCESS | 1654 | 2016-11-05 08:27:26.289 plugin-container[1655:7867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 08:27:26 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:26 INFO - PROCESS | 1654 | 2016-11-05 08:27:26.292 plugin-container[1655:7867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c27, name = 'com.apple.CFPasteboardClient' 08:27:26 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:26 INFO - PROCESS | 1654 | 2016-11-05 08:27:26.292 plugin-container[1655:7867] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:27:35 INFO - PROCESS | 1654 | RSS: Main: 238768128 08:27:35 INFO - PROCESS | 1654 | 08:28:25 INFO - PROCESS | 1654 | 1478359705644 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] 08:30:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 08:30:40 INFO - PROCESS | 1654 | RSS: Main: 251695104 08:30:40 INFO - PROCESS | 1654 | 08:33:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 08:33:43 INFO - PROCESS | 1654 | RSS: Main: 263593984 08:33:43 INFO - PROCESS | 1654 | 08:36:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 08:36:46 INFO - PROCESS | 1654 | RSS: Main: 266833920 08:36:46 INFO - PROCESS | 1654 | 08:39:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 08:39:48 INFO - PROCESS | 1654 | RSS: Main: 265613312 08:39:48 INFO - PROCESS | 1654 | 08:42:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 08:42:52 INFO - PROCESS | 1654 | RSS: Main: 269914112 08:42:52 INFO - PROCESS | 1654 | 08:45:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 08:45:55 INFO - PROCESS | 1654 | RSS: Main: 272474112 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | __start_tp_report 08:45:55 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:45:55 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:45:55 INFO - PROCESS | 1654 | |i|pagename|runs| 08:45:55 INFO - PROCESS | 1654 | |0;dojo.html;3406.06333258354;3408.7613867029304;3422.5379573757536;3427.948580771287;3440.7935523868578;4357.86926392208;5328.573714102868;5780.450857168573;5793.942060579393;5839.591228613998;5623.4938855503;5738.684372359518;5757.942420575803;5790.420957904207;5790.623609465381;5261.39493958194;5286.709230992294;5305.442928492511;5315.176147697094;5418.7019713915915;5258.316418865542;5342.118550439173;5357.651752636086;5364.597655175858;5379.220013098098;5326.493983071602;5348.744244350446;5373.032854086263;5380.784768609251;5431.429699881515;5175.404828444739;5207.60942929278;5259.105951988133;5298.311219541473;5317.096093664087;4360.389545463638;4700.177468942921;5203.765830537635;5205.817796377109;5341.759620817073;4639.350490931272;5648.887022259573;5792.362840087587;5824.126381042877;5875.559333050039;6178.443940045373;6246.406591373812;6267.341929097424;6270.059491076329;6305.464035556983;6226.62640241587;6286;6297.87404251917;6301;6342.365763423667;5949.464548190684;5966.612170208922;5980.34216236218;5983.0128028875615;6041.184440100618;6225.844353891189;6232.158658581034;6262.906056409158;6273.65494897785;6335.968320158462;6243.469305109026;6246.281677607042;6248.687565621792;6260.029695397221;6281.246250449975;6276;6276.121343011982;6295.213098362705;6298.149749783812;6322.747090116344;5299.973500132474;6076.776043325925;6196.132541444202;6274.905876411857;6281.120643109968;6218.657973811393;6270.6864656766975;6276.560640755195;6278.811635650938;6313.242410910629;4995.900081998339;5012.072766538202;5017.67385119966;5025.170846810235;5029.698218106925;4430.933535996963;4444.333349997526;4460.487043989918;4521.61566266871;4531.071130418256;5155.9742201288755;5694.974904517227;5910.940890591039;5939.910901336483;5959.195508606369;6244.718987645567;6262.718177682015;6268.059791031382;6273.96863015682;6299.6535190564955;5771.134329850556;5814.505767009851;5882.3529411764675;5913.113033045078;5954.838806432791;5412.56699464042;5421.105517589633;5444.101723215705;5463.972680136574;5527.336719593594;6202.627842329384;6272.310045894857;6280.591761535486;6284.5600807943;6292;6159;6288.874222515616;6315.400036996478;6318.08387783778;6326.620402775849;5198.246254293031;6174.8147555573405;6216.7824126155365;6257.8122656318565;6320.051992201023;5192.766325515286;6266.592671476339;6292.685365731603;6299.527535434769;6316.589421687576;5530.585206109477;6031.5476339273855;6158.568900176944;6161.876762464815;6245.718942647501;6062.333143354315;6110.072772100023;6127.281156068077;6128.597795181288;6148.1699970503405;6071.483923866521;6095.8780824382375;6111.174991376108;6114.877702445837;6120.265568131853;4016.5180178377614;5045.142325804695;5360.678359298455;5846.327672317568;5943.286805583185;4742.5731684148595;5404.459554044564;6109.86017705286;6170.228721409824;6225.719842607185;4968.950310496849;6064.727087280995;6069.787557435468;6083.513318934386;6089.634621922699 08:45:55 INFO - PROCESS | 1654 | |1;ext.html;480.06387544288646;514.1952843799456;515.675124671457;523.9397469291032;526.8682829292677;38078.428823567636;39597;39654.405183922194;39694;39740.801295993515;40589.59410405895;40722.59277407225;40979.59020409795;41056.79471602645;41074.79462602683;9314.301427392938;9426.952865235664;9432.905670943272;9468.384555003902;9516.38143520669;9397.62409503619;9419.48192849393;9466.574004169828;9476.00501947296;9497.952510237474;9449.480278584675;9477.905220947805;9487.098725621056;9487.383320084173;9501.714948551555;9362;9427.292953028516;9469.952650236773;9476.810463790685;9486.9525652372;9298.442093474345;9333.626654933794;9341.392809467363;9421.90578094224;9440.2919781016;38451.42322865159;39022.60977390218;39206.51108844764;39259;39263.41104883429;2623.4097328101216;3215.2122729931084;3265.755068369858;3358.1436733633013;3588.856445742167;29651;29666.406671866658;30305.848470757504;30350.937717179793;30401.847990759903;3198.072558957923;3251.1872031992;3283.294091770257;3324.80051196929;3341.04780137662;446220;449575.7521212406;451031;452716.73641631583;454067.7296613529;55753.72123139358;56015.55821080628;56059;56206;56310.15534766982;58362;58794;58881.82236355334;58964;59063.818723625285;13244.668883277995;15468.84531154674;15573.221338933008;15644.452444164399;15680.05919644825;11948.581799636971;12091.57679481214;12168.57409990646;12176.756464870654;12202.572909948109;13931.860681393258;15375.461858834888;15383.692326153414;15462;15536;6011.248593925759;6019.488343490765;6042.214512113397;6104.175936248638;6124.601900876429;820.8440396324679;976.199516396548;1031.7626945802508;1032.7779527401644;1046.1212581431482;28251.57622635662;28509.572356414672;29249.122526324245;29711.702882970894;30132.54801177984;1019.2966852871494;1042.2287507244585;1053.1679972821385;1057.0750593230923;1067.0769784136667;15077.924610376878;15140.772888406682;15211.771823422658;15275.847241527665;15327.846721532642;629388;637079;638163;638305.8084709547;639097;55362;55515.72242138763;55541.16688249679;55938.160927585304;56149.43850561524;1282.9743405132033;1304.2370213425252;1325.105553751214;1328.521732176435;1343.2746316988716;22411.887940560846;23421.648675270226;23584;29027;30256.941007064648;46767.298490521986;47449.76275118603;47637.28544071772;47822.28266576074;47853.76073119612;578211;578296;580943;581756;584179;25444.982200712504;25770.871145644902;25821.741782581932;25853.870730646227;25866.965321386935;44147.337789932535;45741.31388029249;45893.623191304316;47148.764256178496;47688.5231147698;36673;37478.81260593679;37602.811985939894;38353.46586136635;38488.61511384962;37073;37134.071648209014;37169.07077323089;38040.619593803705;38442.0389490265;36523.26953460969;37228;37442.43836342402;37472.437913430775;37490;28689.995850145144;30815.921442749397;31097.68902310948;31309.904153354524;31354.52968205433;23826.16608418697;24801;25221.495570088864;25413.61879571771;25529.106481273808 08:45:55 INFO - PROCESS | 1654 | |2;jquery.html;329.4843569813242;373.39882788710173;377.7846627422369;384.69993405144;401.6746435387337;15847.683046339082;18485.44543663689;19348.90325548374;19847;20088.296909608165;18997.240110395567;19213.903930480326;19494;19731.40805775829;19875.503112422197;5625.46558076982;5687.488126068653;5805.10020946754;5817.912731309034;5925.222433270023;5763.423657634224;5917.40825917407;5929.229200203989;5930.614510056854;5936.703164841762;5575.749091290882;5715.799947001856;5757.251557297545;5869.060950247961;5904.852378690524;5684.602077854552;5754.079347304432;5769.913451298234;5788.565857560658;5804.82585522435;5018.222175562793;5028.924566131511;5061;5064.848054558369;5084.500072478628;24500.264992050266;24908.37729056788;24973.62539561908;25087.498250035078;25301.74698253031;1977.2585280519804;2016.5361966747585;2218.4377044513612;2383.5590415773136;2943.499605067144;9442.10300021497;9973.152282056033;10175.725378013927;10192.796144077078;10312.175025997902;1755.4645833020923;1850.7964123946356;1867.8412334951545;1869.7382366468705;1870.8503319734525;125173;128947;129965;130437.99999999905;132922;9236.538173091318;10891.237613367146;11184.161187910939;11279.492422840993;11367.602133925273;14090.084144530572;14102.01285909997;14119.082259653089;14129.576112716635;14143.85856141446;6446.387593178734;7972.60136993148;8303.626336814857;8344.791380215422;8490.193431623986;8077.766148620638;8112.351011919032;8221.095679475253;8244.54654993969;8268.9663792026;3850.4416859555768;4308.728924967142;4403.603675669206;4417.757023363746;4513.337131092671;1867.421099459172;1901.7147427885927;1912.6939689649867;1918.1368384227153;1919.7984211657574;854.5300084953251;859.9054104048549;891.572045418203;911.9316051296179;960.7982323711959;13935.09421887574;16037.759433608506;17496.47510574685;19310.71033934492;20204.494887627927;822.8783930134526;926.7173512078746;939.6147579492512;970.0154343341515;1009.2783659683362;9067.863982040275;9421.858672119924;9830.262730295257;10298;10424.009719076676;563229;585735;593978;595891;601591;113230.43384783024;123449.38275308746;131112;136300;139148;870.8693695945659;955.5508910812047;1003.4079892863392;1036.9896301036893;1037.113268155731;18363.265469381076;18523.90738046301;18595.535111622317;18612.627747444705;18656.16047277903;47415.05169896652;54307;54536.18195726988;54606;55565;72144.63927680538;77810;78468.60765696364;78639.82040269286;78822;6695.899561506679;7078.0444639975;7126.322999315057;7831.295183433518;7837.843243135219;12115.789315831136;12235.877641223475;12473.5010599575;12503.374021533653;12569.183003104768;9657.855132173163;10330.378324385067;10369.118624916966;10722.839157412489;11053.557857685924;8490.448120872123;10566.841497377392;10924.453777310944;10996.615118470816;11133.554657813596;10849.891501084889;11182.217244792786;11198.496067676811;11205.43972801373;11418.942905285421;6690;7087.972244024691;7168.068151140318;7234.5659260444545;7412.703491860265;10030.749231269278;10355.844662330222;10480.528376223241;10553.788924221626;10603.628872989408 08:45:55 INFO - PROCESS | 1654 | |3;mootools.html;1430.1276221504884;1467.1197281631019;1650.273879493023;1774.4055741326656;1787.5441762350606;7399.297066778661;7678.15540290569;7942.324902383297;8443.915560844389;8446.57767111645;7822.882656760153;8148.4703494272835;8186.79533011675;8305.294050005741;8354.665813367457;2307.8153747700185;2317.9768202318014;2320.280712978982;2321.3384185507134;2324.69778928739;2295.104909085458;2329.9417514562188;2339.1812865497027;2344.76552344765;2349.882505874708;2281.258590957945;2282.8402011859175;2302.182725132579;2307.1348244408346;2307.3078076577044;2268.8411811173223;2274.044901141508;2277.840551161419;2302.3553405046446;2303.4126297794037;2266.875321857297;2267.115824828318;2271.4548508357875;2284.463151159476;2294;8229.46508476947;8333.66665333386;8349.54077525736;8414.915850841475;8415.70545030921;5023.4474207837475;5195.532402083831;5202.479752024805;5206.505381988705;5227.686338819683;3296.5055241713694;3457.861685532576;3553.4492153716214;3631.2555926035097;3637.381645120336;837.3552364679224;1044.9766268273513;1079.0773888325448;1100.834874768783;1100.9669709908715;10886.23796334257;10900.1279897608;10967.780644387147;11031.669049928514;11048.569314750743;3655.433407821739;4405.9866230766465;4569.13186494565;4658.114958158007;4691.765411729468;8254.298384637254;9349.95325023384;9436.811263774685;9446.102620251066;9485.810283794286;5208.739563021833;5210.322658054429;5212.739363031833;5214.504622060974;5214.895702085937;4253.425787518708;4415.205263052617;4604.884877878013;4768.61851051915;4783.689060211144;3378.679025492574;3562.2697347043795;3690.33573956685;3774.6036666150208;3832.405977073558;1261.6467389131058;1274.8661390554043;1276.4766445757195;1284.4862055178003;1285.3701686173708;848.7368915636049;862.6463150108425;874.6763697431991;894.4007514964989;900.8648702694649;5708.514776244066;5708.600397972102;5757.827265181967;5773.769049238067;5918.260217472816;840.1388576708922;917.9036201198911;941.4304345870787;948.9193418559499;987.629638885418;3697.7411581189594;3708.6291370862696;3715.8142092895246;3811.4282857571584;3832.885013449601;9878.4566848824;10285.845712314322;10291.22815788804;10293.588256469657;10294.639687610897;10123.443210623487;10228.437435941094;10236.795264094677;10255.58977640886;10260.948695256477;6365.777197798147;7769.572673503011;7864.016997875266;7870.921290787019;7885.408594355446;5339.946600533945;5426.593005524681;5494.835154945358;5514.228008078792;5577.330720313588;9368.765780855532;9456.385334953206;9537.380070295409;9558.426494410358;9580.856287155559;9526.142647161789;9536.713898583053;9608.615655373707;9644.083812037845;9698.806023879342;6225.252969643671;6434.163558737334;6537.901931470937;6689.732410703517;6845.041694162921;7755.7673269802;8000.359971202406;8025.2777250047775;8033.0360356757565;8036.557989310644;6967.268436814062;7098.574085554883;7124.3944264738075;7163.391111755351;7200.423966082596;7000.894986575104;7008.684609192496;7020.262872398529;7026.543274687129;7159.140903091662;6574.159235414092;6612.173478315211;6705.362990516089;6717.2946840581035;6721.227058888095;4107.424960505591;4764.928526072181;4830.154722923404;4843.055604157155;4912.484189160086;4724.126036683225;4750.738709371018;4842.443119041355;4920.630952678492;5030 08:45:55 INFO - PROCESS | 1654 | |4;prototype.html;251.194920280501;261.59452848085465;270.7576718836641;288.1873117807782;307.69077077537077;5316.3354580677415;5494.340679118506;5503.229547863298;5526.41972592877;5545.750441230144;5246.720799500313;5376.247325374451;5398.4601539846;5489.24344209853;5493.285872836525;3795.3168429682646;3811.4282857571447;4182.163567286547;4201.159768046387;4206.053637931472;3757.0043938356357;3787.3750831112834;3897.018780632818;4128.277551428503;4217.620414162725;3528.8412021459094;4089.8957281533967;4092.85675001375;4116.34138537834;4134.793260336971;4073.674106071507;4093;4154.231467178582;4161.583841615845;4170.311898536738;2945.587617733519;3576.7675101118343;3587.318409502186;3596.9640303597153;3599.4780756790383;4384.9561504384865;4751.83368582098;4777.59390451812;5162.638615296931;5285.345625751029;4574.719078657981;4697.436307643071;4718.419329524613;4726.645501587394;4733.100710864925;5217.843204861042;5411.945880541183;5676.240365486712;5766.990776614076;5782.7108644568025;3825.980870095632;3860.478835357219;3879.1465877507185;3917.4711413959035;3945.861894833667;5952.553558483088;6013.51891848651;6064.878702425971;6078.96960515199;6115.492648014293;3222.7905186162825;3383.8308084595674;3610.675039246428;3968.5514787103075;3979.383195604686;3815.988762977813;4026.7382620129597;4036.495438070241;4047.210793895221;4073.124278280184;4690.624750019991;4709.398804406518;4711.222648263067;4719.787609557578;4733.5503127202865;5138.691678499302;5799.739011744482;5841.561882858718;5853.209816674779;5888.5289176865745;1176.87020460359;1223.9449224784905;1229.3853073463267;1238.4798384678456;1272.3256673962815;802.330054404567;828.8052307707684;830.2154464031553;851.3997631669782;855.6363545493141;4270.743755374688;4353.586409291112;4900.088965303535;5144.279800827887;5163.535281824654;4774.618030557547;4987.750612469362;5365.409804921455;5588.183840251934;5627.240322556484;4827.300041494034;5016.498350164954;5049.48515445365;5050.671706339076;5093.465186155475;2441.6459613355964;2886.5958765772807;2904.1868276882506;2922.7056760443375;2927.663318718332;4869.878253043702;5058.949410505921;5264.394594621667;5555.055730494295;5755.971220143872;4769.252764480546;4852.840485983774;5706.403586368193;5744.856378590569;5804.390538993345;5748.655080695172;5764.913526297109;5774.249347584787;5786.797462088806;5797.478226959678;4607.884802879955;5239.633225674166;5659.802211750348;5667.3482549507335;5697.572682048946;5479.589030822784;5590.8043218487155;5875.588708790344;5920.585559010828;5925.851853703692;6151.554384719498;6151.9384806151365;6158.722857471335;6160.691965401622;6175.5677102602385;3866.8646597368956;3988.900277493086;4003.899902502461;4012.317905956053;4016.578259282733;5196.350456192976;5654.717264136859;5922.052471604523;5941.643501389931;6066.484348830399;5558.777069044803;5675.0274807634;5774.451427114417;5831.79588714393;5853.678047707417;5598.776048957996;5788;5845.3277873045145;5894.410558944071;5931.436513531207;4858.757062146808;5438.483344082306;5442.42169770762;5624.734434752311;5672.829815105389;5773.509251713652;5792.160136780228;5859.882802343844;5863.530917526673;5864.67744274052;5046.435604962473;5311.521963023347;5582.302212223472;5688.77244910199;5716.485516303553 08:45:55 INFO - PROCESS | 1654 | |5;yui.html;676.874778166039;684.2644157530656;689.5655736885761;698.5354739098497;789.7907054630522;12315.322657253848;13891.791623125648;14125.717485650306;14780.704385912275;14918;10362.585496580128;13176.407061682223;13404.994625403086;13418.73162536751;13422.194668319908;783.2402569507583;800.7998000499875;800.9479383840061;801.4590151647615;802.7070119406433;638.7540796793245;653.3662347522895;779.7504798464494;792.0772300270207;798.704479342644;852.6205838401913;856.8329175810719;857.4598003257938;857.9142085791433;859.5057841740993;853.9402241843073;858.7938894665298;860.3504354212561;860.6772460327377;862.094800459515;634.8952422850224;634.9587276826992;636.147562266565;637.0762394527953;638.0556775971588;13617.795733064011;14229.217393043378;14250.714985700331;14260.429582816674;14277.857221427757;13442.126261792951;13845.653858653513;13940.790888136686;14027.78958315626;14110.3926604771;11082.140143331822;11252.54989800407;11371.431428428545;11372;11455.541778328858;3689.631036896316;3705.9073523162137;3727.5340582427198;3802.4676545283683;3832.6933845292588;92070;93070.534647327;93205.53397232971;93875;93988.53005734996;9769.31614786958;10167;10374.839626894232;10507.422091785025;10605.510247747077;19973.800261997483;20928.267510637055;21366.679499807517;21532.246371376925;21561.353159405244;7756.806079848049;7792.220777922163;7859.331956783737;7863.803404914808;8074.111847696749;4178.415021896937;4264.232438161161;4266.0188156724125;4285.785710714452;4353.976815448367;3453.102193429676;3524.9118772030906;3526.806025668613;3554.733394995334;3558.0393293810525;655.1810237203496;656.8095252376758;664.1200409457488;678.7522554267657;706.8197609609516;574.994250057494;607.7477846693661;649.5518092516148;660.4947215380238;672.0758956434902;8888.377813552988;9059.411138276122;9060;9080.18278354938;9264.44413335202;567.537456972566;617.527591392585;652.1456891472187;669.8961660942562;670.3208462800653;3275.4595491743594;3517.982410087933;3592.3713350163616;3689.790881997352;4347.174036932973;106772.93227067785;107995.92004080016;118754.40622796831;122304;124083.13875291878;76166.61916690491;84666.57666711627;85025;88561.67157492643;96158;28187.71812281851;38775.80612096922;83345.58327208325;84067.5796621013;84508;8580.798688183784;9187.310951678784;9536.237101031762;9597;9602.855957160788;47068.529314706415;48209.035819284116;48519.757401212766;54608.45391546033;56201;56461;68247.31752682608;69346.306536936;71378.64310678621;74022.62988685022;10107.49462526856;12354.073444491878;12557.372131393577;12571.93714031424;12774;13730.794038089236;14043.08719933201;14112;14273.21497317616;14291;13119.27843968591;13149.60551183466;13178.275194864374;13180.934095329461;13230.404631791786;12957.546485872948;13181.538646147339;13192.274424906722;13248.337583120998;13329.400176991865;12724.982001439677;12862.807057894326;12943.417546210254;13103;13158.934205329295;9833.803323933338;10513.84466184415;10656.040956313658;10811.56753729842;11237.49431275611;9389.953050234704;9428.245740340893;10796.514156863117;11167.832482512931;11207.383593902088 08:45:55 INFO - PROCESS | 1654 | __end_tp_report 08:45:55 INFO - PROCESS | 1654 | __start_cc_report 08:45:55 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,361 08:45:55 INFO - PROCESS | 1654 | __end_cc_report 08:45:55 INFO - PROCESS | 1654 | __startTimestamp1478360755091__endTimestamp 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:45:55 INFO - PROCESS | 1654 | Number of tests: 6 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5714.15 Median:5959.20 stddev:674.23 (11.3%) stddev-sans-first:651.53 08:45:55 INFO - PROCESS | 1654 | Values: 3406.1 3408.8 3422.5 3427.9 3440.8 4357.9 5328.6 5780.5 5793.9 5839.6 5623.5 5738.7 5757.9 5790.4 5790.6 5261.4 5286.7 5305.4 5315.2 5418.7 5258.3 5342.1 5357.7 5364.6 5379.2 5326.5 5348.7 5373.0 5380.8 5431.4 5175.4 5207.6 5259.1 5298.3 5317.1 4360.4 4700.2 5203.8 5205.8 5341.8 4639.4 5648.9 5792.4 5824.1 5875.6 6178.4 6246.4 6267.3 6270.1 6305.5 6226.6 6286.0 6297.9 6301.0 6342.4 5949.5 5966.6 5980.3 5983.0 6041.2 6225.8 6232.2 6262.9 6273.7 6336.0 6243.5 6246.3 6248.7 6260.0 6281.2 6276.0 6276.1 6295.2 6298.1 6322.7 5300.0 6076.8 6196.1 6274.9 6281.1 6218.7 6270.7 6276.6 6278.8 6313.2 4995.9 5012.1 5017.7 5025.2 5029.7 4430.9 4444.3 4460.5 4521.6 4531.1 5156.0 5695.0 5910.9 5939.9 5959.2 6244.7 6262.7 6268.1 6274.0 6299.7 5771.1 5814.5 5882.4 5913.1 5954.8 5412.6 5421.1 5444.1 5464.0 5527.3 6202.6 6272.3 6280.6 6284.6 6292.0 6159.0 6288.9 6315.4 6318.1 6326.6 5198.2 6174.8 6216.8 6257.8 6320.1 5192.8 6266.6 6292.7 6299.5 6316.6 5530.6 6031.5 6158.6 6161.9 6245.7 6062.3 6110.1 6127.3 6128.6 6148.2 6071.5 6095.9 6111.2 6114.9 6120.3 4016.5 5045.1 5360.7 5846.3 5943.3 4742.6 5404.5 6109.9 6170.2 6225.7 4969.0 6064.7 6069.8 6083.5 6089.6 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:68092.73 Median:39114.56 stddev:150187.47 (384.0%) stddev-sans-first:150523.00 08:45:55 INFO - PROCESS | 1654 | Values: 480.1 514.2 515.7 523.9 526.9 38078.4 39597.0 39654.4 39694.0 39740.8 40589.6 40722.6 40979.6 41056.8 41074.8 9314.3 9427.0 9432.9 9468.4 9516.4 9397.6 9419.5 9466.6 9476.0 9498.0 9449.5 9477.9 9487.1 9487.4 9501.7 9362.0 9427.3 9470.0 9476.8 9487.0 9298.4 9333.6 9341.4 9421.9 9440.3 38451.4 39022.6 39206.5 39259.0 39263.4 2623.4 3215.2 3265.8 3358.1 3588.9 29651.0 29666.4 30305.8 30350.9 30401.8 3198.1 3251.2 3283.3 3324.8 3341.0 446220.0 449575.8 451031.0 452716.7 454067.7 55753.7 56015.6 56059.0 56206.0 56310.2 58362.0 58794.0 58881.8 58964.0 59063.8 13244.7 15468.8 15573.2 15644.5 15680.1 11948.6 12091.6 12168.6 12176.8 12202.6 13931.9 15375.5 15383.7 15462.0 15536.0 6011.2 6019.5 6042.2 6104.2 6124.6 820.8 976.2 1031.8 1032.8 1046.1 28251.6 28509.6 29249.1 29711.7 30132.5 1019.3 1042.2 1053.2 1057.1 1067.1 15077.9 15140.8 15211.8 15275.8 15327.8 629388.0 637079.0 638163.0 638305.8 639097.0 55362.0 55515.7 55541.2 55938.2 56149.4 1283.0 1304.2 1325.1 1328.5 1343.3 22411.9 23421.6 23584.0 29027.0 30256.9 46767.3 47449.8 47637.3 47822.3 47853.8 578211.0 578296.0 580943.0 581756.0 584179.0 25445.0 25770.9 25821.7 25853.9 25867.0 44147.3 45741.3 45893.6 47148.8 47688.5 36673.0 37478.8 37602.8 38353.5 38488.6 37073.0 37134.1 37169.1 38040.6 38442.0 36523.3 37228.0 37442.4 37472.4 37490.0 28690.0 30815.9 31097.7 31309.9 31354.5 23826.2 24801.0 25221.5 25413.6 25529.1 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34656.20 Median:1636.49 stddev:98673.07 (6029.5%) stddev-sans-first:98916.21 08:45:55 INFO - PROCESS | 1654 | Values: 329.5 373.4 377.8 384.7 401.7 15847.7 18485.4 19348.9 19847.0 20088.3 18997.2 19213.9 19494.0 19731.4 19875.5 5625.5 5687.5 5805.1 5817.9 5925.2 5763.4 5917.4 5929.2 5930.6 5936.7 5575.7 5715.8 5757.3 5869.1 5904.9 5684.6 5754.1 5769.9 5788.6 5804.8 5018.2 5028.9 5061.0 5064.8 5084.5 24500.3 24908.4 24973.6 25087.5 25301.7 1977.3 2016.5 2218.4 2383.6 2943.5 9442.1 9973.2 10175.7 10192.8 10312.2 1755.5 1850.8 1867.8 1869.7 1870.9 125173.0 128947.0 129965.0 130438.0 132922.0 9236.5 10891.2 11184.2 11279.5 11367.6 14090.1 14102.0 14119.1 14129.6 14143.9 6446.4 7972.6 8303.6 8344.8 8490.2 8077.8 8112.4 8221.1 8244.5 8269.0 3850.4 4308.7 4403.6 4417.8 4513.3 1867.4 1901.7 1912.7 1918.1 1919.8 854.5 859.9 891.6 911.9 960.8 13935.1 16037.8 17496.5 19310.7 20204.5 822.9 926.7 939.6 970.0 1009.3 9067.9 9421.9 9830.3 10298.0 10424.0 563229.0 585735.0 593978.0 595891.0 601591.0 113230.4 123449.4 131112.0 136300.0 139148.0 870.9 955.6 1003.4 1037.0 1037.1 18363.3 18523.9 18595.5 18612.6 18656.2 47415.1 54307.0 54536.2 54606.0 55565.0 72144.6 77810.0 78468.6 78639.8 78822.0 6695.9 7078.0 7126.3 7831.3 7837.8 12115.8 12235.9 12473.5 12503.4 12569.2 9657.9 10330.4 10369.1 10722.8 11053.6 8490.4 10566.8 10924.5 10996.6 11133.6 10849.9 11182.2 11198.5 11205.4 11418.9 6690.0 7088.0 7168.1 7234.6 7412.7 10030.7 10355.8 10480.5 10553.8 10603.6 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5426.93 Median:5204.49 stddev:2995.19 (57.6%) stddev-sans-first:2988.53 08:45:55 INFO - PROCESS | 1654 | Values: 1430.1 1467.1 1650.3 1774.4 1787.5 7399.3 7678.2 7942.3 8443.9 8446.6 7822.9 8148.5 8186.8 8305.3 8354.7 2307.8 2318.0 2320.3 2321.3 2324.7 2295.1 2329.9 2339.2 2344.8 2349.9 2281.3 2282.8 2302.2 2307.1 2307.3 2268.8 2274.0 2277.8 2302.4 2303.4 2266.9 2267.1 2271.5 2284.5 2294.0 8229.5 8333.7 8349.5 8414.9 8415.7 5023.4 5195.5 5202.5 5206.5 5227.7 3296.5 3457.9 3553.4 3631.3 3637.4 837.4 1045.0 1079.1 1100.8 1101.0 10886.2 10900.1 10967.8 11031.7 11048.6 3655.4 4406.0 4569.1 4658.1 4691.8 8254.3 9350.0 9436.8 9446.1 9485.8 5208.7 5210.3 5212.7 5214.5 5214.9 4253.4 4415.2 4604.9 4768.6 4783.7 3378.7 3562.3 3690.3 3774.6 3832.4 1261.6 1274.9 1276.5 1284.5 1285.4 848.7 862.6 874.7 894.4 900.9 5708.5 5708.6 5757.8 5773.8 5918.3 840.1 917.9 941.4 948.9 987.6 3697.7 3708.6 3715.8 3811.4 3832.9 9878.5 10285.8 10291.2 10293.6 10294.6 10123.4 10228.4 10236.8 10255.6 10260.9 6365.8 7769.6 7864.0 7870.9 7885.4 5339.9 5426.6 5494.8 5514.2 5577.3 9368.8 9456.4 9537.4 9558.4 9580.9 9526.1 9536.7 9608.6 9644.1 9698.8 6225.3 6434.2 6537.9 6689.7 6845.0 7755.8 8000.4 8025.3 8033.0 8036.6 6967.3 7098.6 7124.4 7163.4 7200.4 7000.9 7008.7 7020.3 7026.5 7159.1 6574.2 6612.2 6705.4 6717.3 6721.2 4107.4 4764.9 4830.2 4843.1 4912.5 4724.1 4750.7 4842.4 4920.6 5030.0 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4597.38 Median:5141.49 stddev:1439.34 (28.0%) stddev-sans-first:1405.93 08:45:55 INFO - PROCESS | 1654 | Values: 251.2 261.6 270.8 288.2 307.7 5316.3 5494.3 5503.2 5526.4 5545.8 5246.7 5376.2 5398.5 5489.2 5493.3 3795.3 3811.4 4182.2 4201.2 4206.1 3757.0 3787.4 3897.0 4128.3 4217.6 3528.8 4089.9 4092.9 4116.3 4134.8 4073.7 4093.0 4154.2 4161.6 4170.3 2945.6 3576.8 3587.3 3597.0 3599.5 4385.0 4751.8 4777.6 5162.6 5285.3 4574.7 4697.4 4718.4 4726.6 4733.1 5217.8 5411.9 5676.2 5767.0 5782.7 3826.0 3860.5 3879.1 3917.5 3945.9 5952.6 6013.5 6064.9 6079.0 6115.5 3222.8 3383.8 3610.7 3968.6 3979.4 3816.0 4026.7 4036.5 4047.2 4073.1 4690.6 4709.4 4711.2 4719.8 4733.6 5138.7 5799.7 5841.6 5853.2 5888.5 1176.9 1223.9 1229.4 1238.5 1272.3 802.3 828.8 830.2 851.4 855.6 4270.7 4353.6 4900.1 5144.3 5163.5 4774.6 4987.8 5365.4 5588.2 5627.2 4827.3 5016.5 5049.5 5050.7 5093.5 2441.6 2886.6 2904.2 2922.7 2927.7 4869.9 5058.9 5264.4 5555.1 5756.0 4769.3 4852.8 5706.4 5744.9 5804.4 5748.7 5764.9 5774.2 5786.8 5797.5 4607.9 5239.6 5659.8 5667.3 5697.6 5479.6 5590.8 5875.6 5920.6 5925.9 6151.6 6151.9 6158.7 6160.7 6175.6 3866.9 3988.9 4003.9 4012.3 4016.6 5196.4 5654.7 5922.1 5941.6 6066.5 5558.8 5675.0 5774.5 5831.8 5853.7 5598.8 5788.0 5845.3 5894.4 5931.4 4858.8 5438.5 5442.4 5624.7 5672.8 5773.5 5792.2 5859.9 5863.5 5864.7 5046.4 5311.5 5582.3 5688.8 5716.5 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:19717.83 Median:4316.48 stddev:29145.66 (675.2%) stddev-sans-first:29192.36 08:45:55 INFO - PROCESS | 1654 | Values: 676.9 684.3 689.6 698.5 789.8 12315.3 13891.8 14125.7 14780.7 14918.0 10362.6 13176.4 13405.0 13418.7 13422.2 783.2 800.8 800.9 801.5 802.7 638.8 653.4 779.8 792.1 798.7 852.6 856.8 857.5 857.9 859.5 853.9 858.8 860.4 860.7 862.1 634.9 635.0 636.1 637.1 638.1 13617.8 14229.2 14250.7 14260.4 14277.9 13442.1 13845.7 13940.8 14027.8 14110.4 11082.1 11252.5 11371.4 11372.0 11455.5 3689.6 3705.9 3727.5 3802.5 3832.7 92070.0 93070.5 93205.5 93875.0 93988.5 9769.3 10167.0 10374.8 10507.4 10605.5 19973.8 20928.3 21366.7 21532.2 21561.4 7756.8 7792.2 7859.3 7863.8 8074.1 4178.4 4264.2 4266.0 4285.8 4354.0 3453.1 3524.9 3526.8 3554.7 3558.0 655.2 656.8 664.1 678.8 706.8 575.0 607.7 649.6 660.5 672.1 8888.4 9059.4 9060.0 9080.2 9264.4 567.5 617.5 652.1 669.9 670.3 3275.5 3518.0 3592.4 3689.8 4347.2 106772.9 107995.9 118754.4 122304.0 124083.1 76166.6 84666.6 85025.0 88561.7 96158.0 28187.7 38775.8 83345.6 84067.6 84508.0 8580.8 9187.3 9536.2 9597.0 9602.9 47068.5 48209.0 48519.8 54608.5 56201.0 56461.0 68247.3 69346.3 71378.6 74022.6 10107.5 12354.1 12557.4 12571.9 12774.0 13730.8 14043.1 14112.0 14273.2 14291.0 13119.3 13149.6 13178.3 13180.9 13230.4 12957.5 13181.5 13192.3 13248.3 13329.4 12725.0 12862.8 12943.4 13103.0 13158.9 9833.8 10513.8 10656.0 10811.6 11237.5 9390.0 9428.2 10796.5 11167.8 11207.4 08:45:55 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:45:55 INFO - PROCESS | 1654 | 08:45:55 INFO - TEST-INFO | 1654: exit 0 08:45:55 INFO - TEST-OK | dromaeo_css | took 1115626ms 08:45:55 INFO - TEST-START | kraken 08:45:55 INFO - operating with platform_type : mac_ 08:45:55 INFO - Initialising browser for kraken test... 08:45:55 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpBJ56dr/profile http://localhost:49274/getInfo.html) 08:45:58 INFO - PROCESS | 1667 | 2016-11-05 08:45:58.288 plugin-container[1668:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 08:45:58 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:58 INFO - PROCESS | 1667 | 2016-11-05 08:45:58.290 plugin-container[1668:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:45:58 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:58 INFO - PROCESS | 1667 | 2016-11-05 08:45:58.290 plugin-container[1668:10293] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:58 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 08:45:58 INFO - PROCESS | 1667 | colorDepth:24 08:45:58 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 08:45:58 INFO - PROCESS | 1667 | __metrics 08:45:58 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 08:45:59 INFO - TEST-INFO | 1667: exit 0 08:45:59 INFO - Browser initialized. 08:45:59 INFO - Running cycle 1/1 for kraken test... 08:45:59 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpBJ56dr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:46:01 INFO - PROCESS | 1669 | 2016-11-05 08:46:01.398 plugin-container[1670:10409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 08:46:01 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:46:01 INFO - PROCESS | 1669 | 2016-11-05 08:46:01.399 plugin-container[1670:10409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:46:01 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:46:01 INFO - PROCESS | 1669 | 2016-11-05 08:46:01.399 plugin-container[1670:10409] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:46:11 INFO - PROCESS | 1669 | RSS: Main: 239034368 08:46:11 INFO - PROCESS | 1669 | 08:46:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 08:46:15 INFO - PROCESS | 1669 | RSS: Main: 251400192 08:46:15 INFO - PROCESS | 1669 | 08:46:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 08:46:18 INFO - PROCESS | 1669 | RSS: Main: 258912256 08:46:18 INFO - PROCESS | 1669 | 08:46:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 08:46:22 INFO - PROCESS | 1669 | RSS: Main: 261541888 08:46:22 INFO - PROCESS | 1669 | 08:46:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 08:46:24 INFO - PROCESS | 1669 | RSS: Main: 263802880 08:46:24 INFO - PROCESS | 1669 | 08:46:26 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:46:27 INFO - PROCESS | 1669 | RSS: Main: 264089600 08:46:27 INFO - PROCESS | 1669 | 08:46:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 08:46:30 INFO - PROCESS | 1669 | RSS: Main: 264187904 08:46:30 INFO - PROCESS | 1669 | 08:46:34 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 08:46:34 INFO - PROCESS | 1669 | RSS: Main: 264585216 08:46:34 INFO - PROCESS | 1669 | 08:46:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 08:46:38 INFO - PROCESS | 1669 | RSS: Main: 264839168 08:46:38 INFO - PROCESS | 1669 | 08:46:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:46:40 INFO - PROCESS | 1669 | RSS: Main: 264441856 08:46:40 INFO - PROCESS | 1669 | 08:46:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:46:43 INFO - PROCESS | 1669 | RSS: Main: 264609792 08:46:43 INFO - PROCESS | 1669 | 08:46:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:46:45 INFO - PROCESS | 1669 | RSS: Main: 264568832 08:46:45 INFO - PROCESS | 1669 | 08:46:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:46:49 INFO - PROCESS | 1669 | RSS: Main: 264876032 08:46:49 INFO - PROCESS | 1669 | 08:46:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:46:53 INFO - PROCESS | 1669 | RSS: Main: 264900608 08:46:53 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 08:46:55 INFO - PROCESS | 1669 | RSS: Main: 266555392 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | __start_tp_report 08:46:55 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 08:46:55 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 08:46:55 INFO - PROCESS | 1669 | |i|pagename|runs| 08:46:55 INFO - PROCESS | 1669 | |0;ai-astar;107;118;158;118;117;119;117;117;117;118 08:46:55 INFO - PROCESS | 1669 | |1;audio-beat-detection;147;118;115;150;116;113;113;113;146;118 08:46:55 INFO - PROCESS | 1669 | |2;audio-dft;185;203;204;258;197;280;287;197;195;201 08:46:55 INFO - PROCESS | 1669 | |3;audio-fft;84;61;59;85;63;60;59;61;80;61 08:46:55 INFO - PROCESS | 1669 | |4;audio-oscillator;83;72;87;71;73;70;88;104;71;88 08:46:55 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;91;88;90;100;138;89;91;89;133;88 08:46:55 INFO - PROCESS | 1669 | |6;imaging-darkroom;102;103;130;172;132;174;145;103;104;101 08:46:55 INFO - PROCESS | 1669 | |7;imaging-desaturate;87;100;93;142;92;89;91;90;89;101 08:46:55 INFO - PROCESS | 1669 | |8;json-parse-financial;56;56;56;57;58;59;57;58;57;57 08:46:55 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;71;75;74;63;116;112;74;73;75;74 08:46:55 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;86;88;84;92;86;90;90;91;91;87 08:46:55 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;151;152;167;145;167;167;170;153;196;153 08:46:55 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;250;188;172;177;174;174;174;173;175;179 08:46:55 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;70;67;71;69;68;69;69;71;69;67 08:46:55 INFO - PROCESS | 1669 | __end_tp_report 08:46:55 INFO - PROCESS | 1669 | __start_cc_report 08:46:55 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,967 08:46:55 INFO - PROCESS | 1669 | __end_cc_report 08:46:55 INFO - PROCESS | 1669 | __startTimestamp1478360815283__endTimestamp 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | ------- Summary: start ------- 08:46:55 INFO - PROCESS | 1669 | Number of tests: 14 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:120.60 Median:118.00 stddev:13.57 (11.5%) stddev-sans-first:13.48 08:46:55 INFO - PROCESS | 1669 | Values: 107.0 118.0 158.0 118.0 117.0 119.0 117.0 117.0 117.0 118.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:118.00 stddev:15.85 (13.4%) stddev-sans-first:14.66 08:46:55 INFO - PROCESS | 1669 | Values: 147.0 118.0 115.0 150.0 116.0 113.0 113.0 113.0 146.0 118.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:220.70 Median:203.50 stddev:38.50 (18.9%) stddev-sans-first:38.61 08:46:55 INFO - PROCESS | 1669 | Values: 185.0 203.0 204.0 258.0 197.0 280.0 287.0 197.0 195.0 201.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:67.30 Median:62.00 stddev:10.97 (17.7%) stddev-sans-first:9.82 08:46:55 INFO - PROCESS | 1669 | Values: 84.0 61.0 59.0 85.0 63.0 60.0 59.0 61.0 80.0 61.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:78.00 stddev:11.22 (14.4%) stddev-sans-first:11.86 08:46:55 INFO - PROCESS | 1669 | Values: 83.0 72.0 87.0 71.0 73.0 70.0 88.0 104.0 71.0 88.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:99.70 Median:89.00 stddev:19.22 (21.6%) stddev-sans-first:20.12 08:46:55 INFO - PROCESS | 1669 | Values: 91.0 88.0 90.0 100.0 138.0 89.0 91.0 89.0 133.0 88.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:126.60 Median:131.00 stddev:29.01 (22.1%) stddev-sans-first:29.37 08:46:55 INFO - PROCESS | 1669 | Values: 102.0 103.0 130.0 172.0 132.0 174.0 145.0 103.0 104.0 101.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:89.50 stddev:16.34 (18.3%) stddev-sans-first:16.89 08:46:55 INFO - PROCESS | 1669 | Values: 87.0 100.0 93.0 142.0 92.0 89.0 91.0 90.0 89.0 101.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.00 stddev:0.99 (1.7%) stddev-sans-first:0.97 08:46:55 INFO - PROCESS | 1669 | Values: 56.0 56.0 56.0 57.0 58.0 59.0 57.0 58.0 57.0 57.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:80.70 Median:74.00 stddev:17.93 (24.2%) stddev-sans-first:18.67 08:46:55 INFO - PROCESS | 1669 | Values: 71.0 75.0 74.0 63.0 116.0 112.0 74.0 73.0 75.0 74.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:90.00 stddev:2.68 (3.0%) stddev-sans-first:2.68 08:46:55 INFO - PROCESS | 1669 | Values: 86.0 88.0 84.0 92.0 86.0 90.0 90.0 91.0 91.0 87.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:162.10 Median:167.00 stddev:14.78 (8.9%) stddev-sans-first:15.12 08:46:55 INFO - PROCESS | 1669 | Values: 151.0 152.0 167.0 145.0 167.0 167.0 170.0 153.0 196.0 153.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:176.00 stddev:23.78 (13.5%) stddev-sans-first:4.89 08:46:55 INFO - PROCESS | 1669 | Values: 250.0 188.0 172.0 177.0 174.0 174.0 174.0 173.0 175.0 179.0 08:46:55 INFO - PROCESS | 1669 | 08:46:55 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:1.41 (2.0%) stddev-sans-first:1.45 08:46:55 INFO - PROCESS | 1669 | Values: 70.0 67.0 71.0 69.0 68.0 69.0 69.0 71.0 69.0 67.0 08:46:55 INFO - PROCESS | 1669 | -------- Summary: end -------- 08:46:55 INFO - PROCESS | 1669 | 08:46:56 INFO - TEST-INFO | 1669: exit 0 08:46:56 INFO - TEST-OK | kraken | took 60560ms 08:46:56 INFO - SUITE-END | took 1176s 08:46:56 INFO - Completed test suite (00:19:36) 08:46:56 INFO - javascript benchmark 08:46:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7113.970444752193, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3406.06333258354, 3408.7613867029304, 3422.5379573757536, 3427.948580771287, 3440.7935523868578, 4357.86926392208, 5328.573714102868, 5780.450857168573, 5793.942060579393, 5839.591228613998, 5623.4938855503, 5738.684372359518, 5757.942420575803, 5790.420957904207, 5790.623609465381, 5261.39493958194, 5286.709230992294, 5305.442928492511, 5315.176147697094, 5418.7019713915915, 5258.316418865542, 5342.118550439173, 5357.651752636086, 5364.597655175858, 5379.220013098098, 5326.493983071602, 5348.744244350446, 5373.032854086263, 5380.784768609251, 5431.429699881515, 5175.404828444739, 5207.60942929278, 5259.105951988133, 5298.311219541473, 5317.096093664087, 4360.389545463638, 4700.177468942921, 5203.765830537635, 5205.817796377109, 5341.759620817073, 4639.350490931272, 5648.887022259573, 5792.362840087587, 5824.126381042877, 5875.559333050039, 6178.443940045373, 6246.406591373812, 6267.341929097424, 6270.059491076329, 6305.464035556983, 6226.62640241587, 6286.0, 6297.87404251917, 6301.0, 6342.365763423667, 5949.464548190684, 5966.612170208922, 5980.34216236218, 5983.0128028875615, 6041.184440100618, 6225.844353891189, 6232.158658581034, 6262.906056409158, 6273.65494897785, 6335.968320158462, 6243.469305109026, 6246.281677607042, 6248.687565621792, 6260.029695397221, 6281.246250449975, 6276.0, 6276.121343011982, 6295.213098362705, 6298.149749783812, 6322.747090116344, 5299.973500132474, 6076.776043325925, 6196.132541444202, 6274.905876411857, 6281.120643109968, 6218.657973811393, 6270.6864656766975, 6276.560640755195, 6278.811635650938, 6313.242410910629, 4995.900081998339, 5012.072766538202, 5017.67385119966, 5025.170846810235, 5029.698218106925, 4430.933535996963, 4444.333349997526, 4460.487043989918, 4521.61566266871, 4531.071130418256, 5155.9742201288755, 5694.974904517227, 5910.940890591039, 5939.910901336483, 5959.195508606369, 6244.718987645567, 6262.718177682015, 6268.059791031382, 6273.96863015682, 6299.6535190564955, 5771.134329850556, 5814.505767009851, 5882.3529411764675, 5913.113033045078, 5954.838806432791, 5412.56699464042, 5421.105517589633, 5444.101723215705, 5463.972680136574, 5527.336719593594, 6202.627842329384, 6272.310045894857, 6280.591761535486, 6284.5600807943, 6292.0, 6159.0, 6288.874222515616, 6315.400036996478, 6318.08387783778, 6326.620402775849, 5198.246254293031, 6174.8147555573405, 6216.7824126155365, 6257.8122656318565, 6320.051992201023, 5192.766325515286, 6266.592671476339, 6292.685365731603, 6299.527535434769, 6316.589421687576, 5530.585206109477, 6031.5476339273855, 6158.568900176944, 6161.876762464815, 6245.718942647501, 6062.333143354315, 6110.072772100023, 6127.281156068077, 6128.597795181288, 6148.1699970503405, 6071.483923866521, 6095.8780824382375, 6111.174991376108, 6114.877702445837, 6120.265568131853, 4016.5180178377614, 5045.142325804695, 5360.678359298455, 5846.327672317568, 5943.286805583185, 4742.5731684148595, 5404.459554044564, 6109.86017705286, 6170.228721409824, 6225.719842607185, 4968.950310496849, 6064.727087280995, 6069.787557435468, 6083.513318934386, 6089.634621922699], "value": 5675.120327829581, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.06387544288646, 514.1952843799456, 515.675124671457, 523.9397469291032, 526.8682829292677, 38078.428823567636, 39597.0, 39654.405183922194, 39694.0, 39740.801295993515, 40589.59410405895, 40722.59277407225, 40979.59020409795, 41056.79471602645, 41074.79462602683, 9314.301427392938, 9426.952865235664, 9432.905670943272, 9468.384555003902, 9516.38143520669, 9397.62409503619, 9419.48192849393, 9466.574004169828, 9476.00501947296, 9497.952510237474, 9449.480278584675, 9477.905220947805, 9487.098725621056, 9487.383320084173, 9501.714948551555, 9362.0, 9427.292953028516, 9469.952650236773, 9476.810463790685, 9486.9525652372, 9298.442093474345, 9333.626654933794, 9341.392809467363, 9421.90578094224, 9440.2919781016, 38451.42322865159, 39022.60977390218, 39206.51108844764, 39259.0, 39263.41104883429, 2623.4097328101216, 3215.2122729931084, 3265.755068369858, 3358.1436733633013, 3588.856445742167, 29651.0, 29666.406671866658, 30305.848470757504, 30350.937717179793, 30401.847990759903, 3198.072558957923, 3251.1872031992, 3283.294091770257, 3324.80051196929, 3341.04780137662, 446220.0, 449575.7521212406, 451031.0, 452716.73641631583, 454067.7296613529, 55753.72123139358, 56015.55821080628, 56059.0, 56206.0, 56310.15534766982, 58362.0, 58794.0, 58881.82236355334, 58964.0, 59063.818723625285, 13244.668883277995, 15468.84531154674, 15573.221338933008, 15644.452444164399, 15680.05919644825, 11948.581799636971, 12091.57679481214, 12168.57409990646, 12176.756464870654, 12202.572909948109, 13931.860681393258, 15375.461858834888, 15383.692326153414, 15462.0, 15536.0, 6011.248593925759, 6019.488343490765, 6042.214512113397, 6104.175936248638, 6124.601900876429, 820.8440396324679, 976.199516396548, 1031.7626945802508, 1032.7779527401644, 1046.1212581431482, 28251.57622635662, 28509.572356414672, 29249.122526324245, 29711.702882970894, 30132.54801177984, 1019.2966852871494, 1042.2287507244585, 1053.1679972821385, 1057.0750593230923, 1067.0769784136667, 15077.924610376878, 15140.772888406682, 15211.771823422658, 15275.847241527665, 15327.846721532642, 629388.0, 637079.0, 638163.0, 638305.8084709547, 639097.0, 55362.0, 55515.72242138763, 55541.16688249679, 55938.160927585304, 56149.43850561524, 1282.9743405132033, 1304.2370213425252, 1325.105553751214, 1328.521732176435, 1343.2746316988716, 22411.887940560846, 23421.648675270226, 23584.0, 29027.0, 30256.941007064648, 46767.298490521986, 47449.76275118603, 47637.28544071772, 47822.28266576074, 47853.76073119612, 578211.0, 578296.0, 580943.0, 581756.0, 584179.0, 25444.982200712504, 25770.871145644902, 25821.741782581932, 25853.870730646227, 25866.965321386935, 44147.337789932535, 45741.31388029249, 45893.623191304316, 47148.764256178496, 47688.5231147698, 36673.0, 37478.81260593679, 37602.811985939894, 38353.46586136635, 38488.61511384962, 37073.0, 37134.071648209014, 37169.07077323089, 38040.619593803705, 38442.0389490265, 36523.26953460969, 37228.0, 37442.43836342402, 37472.437913430775, 37490.0, 28689.995850145144, 30815.921442749397, 31097.68902310948, 31309.904153354524, 31354.52968205433, 23826.16608418697, 24801.0, 25221.495570088864, 25413.61879571771, 25529.106481273808], "value": 19130.22195125017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.4843569813242, 373.39882788710173, 377.7846627422369, 384.69993405144, 401.6746435387337, 15847.683046339082, 18485.44543663689, 19348.90325548374, 19847.0, 20088.296909608165, 18997.240110395567, 19213.903930480326, 19494.0, 19731.40805775829, 19875.503112422197, 5625.46558076982, 5687.488126068653, 5805.10020946754, 5817.912731309034, 5925.222433270023, 5763.423657634224, 5917.40825917407, 5929.229200203989, 5930.614510056854, 5936.703164841762, 5575.749091290882, 5715.799947001856, 5757.251557297545, 5869.060950247961, 5904.852378690524, 5684.602077854552, 5754.079347304432, 5769.913451298234, 5788.565857560658, 5804.82585522435, 5018.222175562793, 5028.924566131511, 5061.0, 5064.848054558369, 5084.500072478628, 24500.264992050266, 24908.37729056788, 24973.62539561908, 25087.498250035078, 25301.74698253031, 1977.2585280519804, 2016.5361966747585, 2218.4377044513612, 2383.5590415773136, 2943.499605067144, 9442.10300021497, 9973.152282056033, 10175.725378013927, 10192.796144077078, 10312.175025997902, 1755.4645833020923, 1850.7964123946356, 1867.8412334951545, 1869.7382366468705, 1870.8503319734525, 125173.0, 128947.0, 129965.0, 130437.99999999905, 132922.0, 9236.538173091318, 10891.237613367146, 11184.161187910939, 11279.492422840993, 11367.602133925273, 14090.084144530572, 14102.01285909997, 14119.082259653089, 14129.576112716635, 14143.85856141446, 6446.387593178734, 7972.60136993148, 8303.626336814857, 8344.791380215422, 8490.193431623986, 8077.766148620638, 8112.351011919032, 8221.095679475253, 8244.54654993969, 8268.9663792026, 3850.4416859555768, 4308.728924967142, 4403.603675669206, 4417.757023363746, 4513.337131092671, 1867.421099459172, 1901.7147427885927, 1912.6939689649867, 1918.1368384227153, 1919.7984211657574, 854.5300084953251, 859.9054104048549, 891.572045418203, 911.9316051296179, 960.7982323711959, 13935.09421887574, 16037.759433608506, 17496.47510574685, 19310.71033934492, 20204.494887627927, 822.8783930134526, 926.7173512078746, 939.6147579492512, 970.0154343341515, 1009.2783659683362, 9067.863982040275, 9421.858672119924, 9830.262730295257, 10298.0, 10424.009719076676, 563229.0, 585735.0, 593978.0, 595891.0, 601591.0, 113230.43384783024, 123449.38275308746, 131112.0, 136300.0, 139148.0, 870.8693695945659, 955.5508910812047, 1003.4079892863392, 1036.9896301036893, 1037.113268155731, 18363.265469381076, 18523.90738046301, 18595.535111622317, 18612.627747444705, 18656.16047277903, 47415.05169896652, 54307.0, 54536.18195726988, 54606.0, 55565.0, 72144.63927680538, 77810.0, 78468.60765696364, 78639.82040269286, 78822.0, 6695.899561506679, 7078.0444639975, 7126.322999315057, 7831.295183433518, 7837.843243135219, 12115.789315831136, 12235.877641223475, 12473.5010599575, 12503.374021533653, 12569.183003104768, 9657.855132173163, 10330.378324385067, 10369.118624916966, 10722.839157412489, 11053.557857685924, 8490.448120872123, 10566.841497377392, 10924.453777310944, 10996.615118470816, 11133.554657813596, 10849.891501084889, 11182.217244792786, 11198.496067676811, 11205.43972801373, 11418.942905285421, 6690.0, 7087.972244024691, 7168.068151140318, 7234.5659260444545, 7412.703491860265, 10030.749231269278, 10355.844662330222, 10480.528376223241, 10553.788924221626, 10603.628872989408], "value": 9235.783921455939, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.1276221504884, 1467.1197281631019, 1650.273879493023, 1774.4055741326656, 1787.5441762350606, 7399.297066778661, 7678.15540290569, 7942.324902383297, 8443.915560844389, 8446.57767111645, 7822.882656760153, 8148.4703494272835, 8186.79533011675, 8305.294050005741, 8354.665813367457, 2307.8153747700185, 2317.9768202318014, 2320.280712978982, 2321.3384185507134, 2324.69778928739, 2295.104909085458, 2329.9417514562188, 2339.1812865497027, 2344.76552344765, 2349.882505874708, 2281.258590957945, 2282.8402011859175, 2302.182725132579, 2307.1348244408346, 2307.3078076577044, 2268.8411811173223, 2274.044901141508, 2277.840551161419, 2302.3553405046446, 2303.4126297794037, 2266.875321857297, 2267.115824828318, 2271.4548508357875, 2284.463151159476, 2294.0, 8229.46508476947, 8333.66665333386, 8349.54077525736, 8414.915850841475, 8415.70545030921, 5023.4474207837475, 5195.532402083831, 5202.479752024805, 5206.505381988705, 5227.686338819683, 3296.5055241713694, 3457.861685532576, 3553.4492153716214, 3631.2555926035097, 3637.381645120336, 837.3552364679224, 1044.9766268273513, 1079.0773888325448, 1100.834874768783, 1100.9669709908715, 10886.23796334257, 10900.1279897608, 10967.780644387147, 11031.669049928514, 11048.569314750743, 3655.433407821739, 4405.9866230766465, 4569.13186494565, 4658.114958158007, 4691.765411729468, 8254.298384637254, 9349.95325023384, 9436.811263774685, 9446.102620251066, 9485.810283794286, 5208.739563021833, 5210.322658054429, 5212.739363031833, 5214.504622060974, 5214.895702085937, 4253.425787518708, 4415.205263052617, 4604.884877878013, 4768.61851051915, 4783.689060211144, 3378.679025492574, 3562.2697347043795, 3690.33573956685, 3774.6036666150208, 3832.405977073558, 1261.6467389131058, 1274.8661390554043, 1276.4766445757195, 1284.4862055178003, 1285.3701686173708, 848.7368915636049, 862.6463150108425, 874.6763697431991, 894.4007514964989, 900.8648702694649, 5708.514776244066, 5708.600397972102, 5757.827265181967, 5773.769049238067, 5918.260217472816, 840.1388576708922, 917.9036201198911, 941.4304345870787, 948.9193418559499, 987.629638885418, 3697.7411581189594, 3708.6291370862696, 3715.8142092895246, 3811.4282857571584, 3832.885013449601, 9878.4566848824, 10285.845712314322, 10291.22815788804, 10293.588256469657, 10294.639687610897, 10123.443210623487, 10228.437435941094, 10236.795264094677, 10255.58977640886, 10260.948695256477, 6365.777197798147, 7769.572673503011, 7864.016997875266, 7870.921290787019, 7885.408594355446, 5339.946600533945, 5426.593005524681, 5494.835154945358, 5514.228008078792, 5577.330720313588, 9368.765780855532, 9456.385334953206, 9537.380070295409, 9558.426494410358, 9580.856287155559, 9526.142647161789, 9536.713898583053, 9608.615655373707, 9644.083812037845, 9698.806023879342, 6225.252969643671, 6434.163558737334, 6537.901931470937, 6689.732410703517, 6845.041694162921, 7755.7673269802, 8000.359971202406, 8025.2777250047775, 8033.0360356757565, 8036.557989310644, 6967.268436814062, 7098.574085554883, 7124.3944264738075, 7163.391111755351, 7200.423966082596, 7000.894986575104, 7008.684609192496, 7020.262872398529, 7026.543274687129, 7159.140903091662, 6574.159235414092, 6612.173478315211, 6705.362990516089, 6717.2946840581035, 6721.227058888095, 4107.424960505591, 4764.928526072181, 4830.154722923404, 4843.055604157155, 4912.484189160086, 4724.126036683225, 4750.738709371018, 4842.443119041355, 4920.630952678492, 5030.0], "value": 4398.915817319661, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.194920280501, 261.59452848085465, 270.7576718836641, 288.1873117807782, 307.69077077537077, 5316.3354580677415, 5494.340679118506, 5503.229547863298, 5526.41972592877, 5545.750441230144, 5246.720799500313, 5376.247325374451, 5398.4601539846, 5489.24344209853, 5493.285872836525, 3795.3168429682646, 3811.4282857571447, 4182.163567286547, 4201.159768046387, 4206.053637931472, 3757.0043938356357, 3787.3750831112834, 3897.018780632818, 4128.277551428503, 4217.620414162725, 3528.8412021459094, 4089.8957281533967, 4092.85675001375, 4116.34138537834, 4134.793260336971, 4073.674106071507, 4093.0, 4154.231467178582, 4161.583841615845, 4170.311898536738, 2945.587617733519, 3576.7675101118343, 3587.318409502186, 3596.9640303597153, 3599.4780756790383, 4384.9561504384865, 4751.83368582098, 4777.59390451812, 5162.638615296931, 5285.345625751029, 4574.719078657981, 4697.436307643071, 4718.419329524613, 4726.645501587394, 4733.100710864925, 5217.843204861042, 5411.945880541183, 5676.240365486712, 5766.990776614076, 5782.7108644568025, 3825.980870095632, 3860.478835357219, 3879.1465877507185, 3917.4711413959035, 3945.861894833667, 5952.553558483088, 6013.51891848651, 6064.878702425971, 6078.96960515199, 6115.492648014293, 3222.7905186162825, 3383.8308084595674, 3610.675039246428, 3968.5514787103075, 3979.383195604686, 3815.988762977813, 4026.7382620129597, 4036.495438070241, 4047.210793895221, 4073.124278280184, 4690.624750019991, 4709.398804406518, 4711.222648263067, 4719.787609557578, 4733.5503127202865, 5138.691678499302, 5799.739011744482, 5841.561882858718, 5853.209816674779, 5888.5289176865745, 1176.87020460359, 1223.9449224784905, 1229.3853073463267, 1238.4798384678456, 1272.3256673962815, 802.330054404567, 828.8052307707684, 830.2154464031553, 851.3997631669782, 855.6363545493141, 4270.743755374688, 4353.586409291112, 4900.088965303535, 5144.279800827887, 5163.535281824654, 4774.618030557547, 4987.750612469362, 5365.409804921455, 5588.183840251934, 5627.240322556484, 4827.300041494034, 5016.498350164954, 5049.48515445365, 5050.671706339076, 5093.465186155475, 2441.6459613355964, 2886.5958765772807, 2904.1868276882506, 2922.7056760443375, 2927.663318718332, 4869.878253043702, 5058.949410505921, 5264.394594621667, 5555.055730494295, 5755.971220143872, 4769.252764480546, 4852.840485983774, 5706.403586368193, 5744.856378590569, 5804.390538993345, 5748.655080695172, 5764.913526297109, 5774.249347584787, 5786.797462088806, 5797.478226959678, 4607.884802879955, 5239.633225674166, 5659.802211750348, 5667.3482549507335, 5697.572682048946, 5479.589030822784, 5590.8043218487155, 5875.588708790344, 5920.585559010828, 5925.851853703692, 6151.554384719498, 6151.9384806151365, 6158.722857471335, 6160.691965401622, 6175.5677102602385, 3866.8646597368956, 3988.900277493086, 4003.899902502461, 4012.317905956053, 4016.578259282733, 5196.350456192976, 5654.717264136859, 5922.052471604523, 5941.643501389931, 6066.484348830399, 5558.777069044803, 5675.0274807634, 5774.451427114417, 5831.79588714393, 5853.678047707417, 5598.776048957996, 5788.0, 5845.3277873045145, 5894.410558944071, 5931.436513531207, 4858.757062146808, 5438.483344082306, 5442.42169770762, 5624.734434752311, 5672.829815105389, 5773.509251713652, 5792.160136780228, 5859.882802343844, 5863.530917526673, 5864.67744274052, 5046.435604962473, 5311.521963023347, 5582.302212223472, 5688.77244910199, 5716.485516303553], "value": 4114.8290761927965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.874778166039, 684.2644157530656, 689.5655736885761, 698.5354739098497, 789.7907054630522, 12315.322657253848, 13891.791623125648, 14125.717485650306, 14780.704385912275, 14918.0, 10362.585496580128, 13176.407061682223, 13404.994625403086, 13418.73162536751, 13422.194668319908, 783.2402569507583, 800.7998000499875, 800.9479383840061, 801.4590151647615, 802.7070119406433, 638.7540796793245, 653.3662347522895, 779.7504798464494, 792.0772300270207, 798.704479342644, 852.6205838401913, 856.8329175810719, 857.4598003257938, 857.9142085791433, 859.5057841740993, 853.9402241843073, 858.7938894665298, 860.3504354212561, 860.6772460327377, 862.094800459515, 634.8952422850224, 634.9587276826992, 636.147562266565, 637.0762394527953, 638.0556775971588, 13617.795733064011, 14229.217393043378, 14250.714985700331, 14260.429582816674, 14277.857221427757, 13442.126261792951, 13845.653858653513, 13940.790888136686, 14027.78958315626, 14110.3926604771, 11082.140143331822, 11252.54989800407, 11371.431428428545, 11372.0, 11455.541778328858, 3689.631036896316, 3705.9073523162137, 3727.5340582427198, 3802.4676545283683, 3832.6933845292588, 92070.0, 93070.534647327, 93205.53397232971, 93875.0, 93988.53005734996, 9769.31614786958, 10167.0, 10374.839626894232, 10507.422091785025, 10605.510247747077, 19973.800261997483, 20928.267510637055, 21366.679499807517, 21532.246371376925, 21561.353159405244, 7756.806079848049, 7792.220777922163, 7859.331956783737, 7863.803404914808, 8074.111847696749, 4178.415021896937, 4264.232438161161, 4266.0188156724125, 4285.785710714452, 4353.976815448367, 3453.102193429676, 3524.9118772030906, 3526.806025668613, 3554.733394995334, 3558.0393293810525, 655.1810237203496, 656.8095252376758, 664.1200409457488, 678.7522554267657, 706.8197609609516, 574.994250057494, 607.7477846693661, 649.5518092516148, 660.4947215380238, 672.0758956434902, 8888.377813552988, 9059.411138276122, 9060.0, 9080.18278354938, 9264.44413335202, 567.537456972566, 617.527591392585, 652.1456891472187, 669.8961660942562, 670.3208462800653, 3275.4595491743594, 3517.982410087933, 3592.3713350163616, 3689.790881997352, 4347.174036932973, 106772.93227067785, 107995.92004080016, 118754.40622796831, 122304.0, 124083.13875291878, 76166.61916690491, 84666.57666711627, 85025.0, 88561.67157492643, 96158.0, 28187.71812281851, 38775.80612096922, 83345.58327208325, 84067.5796621013, 84508.0, 8580.798688183784, 9187.310951678784, 9536.237101031762, 9597.0, 9602.855957160788, 47068.529314706415, 48209.035819284116, 48519.757401212766, 54608.45391546033, 56201.0, 56461.0, 68247.31752682608, 69346.306536936, 71378.64310678621, 74022.62988685022, 10107.49462526856, 12354.073444491878, 12557.372131393577, 12571.93714031424, 12774.0, 13730.794038089236, 14043.08719933201, 14112.0, 14273.21497317616, 14291.0, 13119.27843968591, 13149.60551183466, 13178.275194864374, 13180.934095329461, 13230.404631791786, 12957.546485872948, 13181.538646147339, 13192.274424906722, 13248.337583120998, 13329.400176991865, 12724.982001439677, 12862.807057894326, 12943.417546210254, 13103.0, 13158.934205329295, 9833.803323933338, 10513.84466184415, 10656.040956313658, 10811.56753729842, 11237.49431275611, 9389.953050234704, 9428.245740340893, 10796.514156863117, 11167.832482512931, 11207.383593902088], "value": 7141.032403225731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 158.0, 118.0, 117.0, 119.0, 117.0, 117.0, 117.0, 118.0], "value": 120.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 115.0, 150.0, 116.0, 113.0, 113.0, 113.0, 146.0, 118.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 203.0, 204.0, 258.0, 197.0, 280.0, 287.0, 197.0, 195.0, 201.0], "value": 220.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 59.0, 85.0, 63.0, 60.0, 59.0, 61.0, 80.0, 61.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 72.0, 87.0, 71.0, 73.0, 70.0, 88.0, 104.0, 71.0, 88.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 90.0, 100.0, 138.0, 89.0, 91.0, 89.0, 133.0, 88.0], "value": 99.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 103.0, 130.0, 172.0, 132.0, 174.0, 145.0, 103.0, 104.0, 101.0], "value": 126.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 100.0, 93.0, 142.0, 92.0, 89.0, 91.0, 90.0, 89.0, 101.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 58.0, 59.0, 57.0, 58.0, 57.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 74.0, 63.0, 116.0, 112.0, 74.0, 73.0, 75.0, 74.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 84.0, 92.0, 86.0, 90.0, 90.0, 91.0, 91.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 167.0, 145.0, 167.0, 167.0, 170.0, 153.0, 196.0, 153.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [250.0, 188.0, 172.0, 177.0, 174.0, 174.0, 174.0, 173.0, 175.0, 179.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 71.0, 69.0, 68.0, 69.0, 69.0, 71.0, 69.0, 67.0], "value": 69.0, "unit": "score"}]}]} 08:46:56 INFO - Return code: 0 08:46:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:46:56 ERROR - # TBPL SUCCESS # 08:46:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:46:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:46:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:46:56 INFO - 2016-11-05 08:46:56.606 screenresolution[1674:10806] starting screenresolution argv=screenresolution get 08:46:56 INFO - 2016-11-05 08:46:56.609 screenresolution[1674:10806] Display 0: 1600x1200x32@60 08:46:56 INFO - 2016-11-05 08:46:56.615 screenresolution[1675:10809] starting screenresolution argv=screenresolution list 08:46:56 INFO - Available Modes on Display 0 08:46:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:46:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:46:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:46:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:46:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:46:56 INFO - Intel Iris: 08:46:56 INFO - Chipset Model: Intel Iris 08:46:56 INFO - Type: GPU 08:46:56 INFO - Bus: Built-In 08:46:56 INFO - VRAM (Dynamic, Max): 1536 MB 08:46:56 INFO - Vendor: Intel (0x8086) 08:46:56 INFO - Device ID: 0x0a2e 08:46:56 INFO - Revision ID: 0x0009 08:46:56 INFO - Displays: 08:46:56 INFO - Display: 08:46:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:46:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:46:56 INFO - Display Serial Number: 1600x1200 60 08:46:56 INFO - Main Display: Yes 08:46:56 INFO - Mirror: Off 08:46:56 INFO - Online: Yes 08:46:56 INFO - Rotation: Supported 08:46:56 INFO - Return code: 0 08:46:56 INFO - Running post-action listener: _resource_record_post_action 08:46:56 INFO - [mozharness: 2016-11-05 15:46:56.856767Z] Finished run-tests step (success) 08:46:56 INFO - Running post-run listener: _resource_record_post_run 08:46:56 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 39811584; Write bytes: 707742720; Read time: 391; Write time: 2123 08:46:56 INFO - TinderboxPrint: CPU usage
54.5% 08:46:56 INFO - TinderboxPrint: I/O read bytes / time
39,811,584 / 391 08:46:56 INFO - TinderboxPrint: I/O write bytes / time
707,742,720 / 2,123 08:46:56 INFO - TinderboxPrint: CPU idle
2,202.5 (45.5%) 08:46:56 INFO - TinderboxPrint: CPU system
977.2 (20.2%) 08:46:56 INFO - TinderboxPrint: CPU user
1,665.9 (34.4%) 08:46:56 INFO - TinderboxPrint: Swap in / out
441,671,680 / 0 08:46:56 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393394688; Write bytes: 388675584; Read time: 29562; Write time: 726 08:46:56 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 37812736; Write bytes: 300856320; Read time: 370; Write time: 1357 08:46:57 INFO - Running post-run listener: _upload_blobber_files 08:46:57 INFO - Blob upload gear active. 08:46:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:46:57 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 08:46:57 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'] 08:46:57 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 08:46:57 INFO - (blobuploader) - INFO - Open directory for files ... 08:46:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:46:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:46:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:46:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:46:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:46:59 INFO - (blobuploader) - INFO - Done attempting. 08:46:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:46:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:46:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:47:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:47:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:47:00 INFO - (blobuploader) - INFO - Done attempting. 08:47:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:47:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:47:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:47:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:47:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:47:02 INFO - (blobuploader) - INFO - Done attempting. 08:47:02 INFO - (blobuploader) - INFO - Iteration through files over. 08:47:02 INFO - Return code: 0 08:47:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:47:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:47:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffe59ef2a2f88857730da2d104e4184be5cf583fba1763852d0336191423e90bc1d902b78b46a6e1f7e4c23e1fac1e2968e59d78a89b07c8734aefb39988b78d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5857432240f67587df901c237e35b63ef60386ae5b64426fcef2313a99e1459822f4b12903a51307ea7f8686cc2ab1110a91ba119797e2c1b13fcd2c9c5f1c7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3077c48e7da1cd8020e52f5e0c6dad16293888509a9429b8a5bd195ad36b894803ad2cb526886bdb94431dfa4b0b95c1504a8e8291c5e66992011d55e603007"} 08:47:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:47:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:47:02 INFO - Contents: 08:47:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffe59ef2a2f88857730da2d104e4184be5cf583fba1763852d0336191423e90bc1d902b78b46a6e1f7e4c23e1fac1e2968e59d78a89b07c8734aefb39988b78d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5857432240f67587df901c237e35b63ef60386ae5b64426fcef2313a99e1459822f4b12903a51307ea7f8686cc2ab1110a91ba119797e2c1b13fcd2c9c5f1c7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3077c48e7da1cd8020e52f5e0c6dad16293888509a9429b8a5bd195ad36b894803ad2cb526886bdb94431dfa4b0b95c1504a8e8291c5e66992011d55e603007"} 08:47:02 INFO - Running post-run listener: copy_logs_to_upload_dir 08:47:02 INFO - Copying logs to upload dir... 08:47:02 INFO - mkdir: /builds/slave/test/build/upload/logs 08:47:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.418910 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-05 08:47:02.353914) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 08:47:02.361215) ========= 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.zAm1iceJI9/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.owAVWOJjJ4/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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/ffe59ef2a2f88857730da2d104e4184be5cf583fba1763852d0336191423e90bc1d902b78b46a6e1f7e4c23e1fac1e2968e59d78a89b07c8734aefb39988b78d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5857432240f67587df901c237e35b63ef60386ae5b64426fcef2313a99e1459822f4b12903a51307ea7f8686cc2ab1110a91ba119797e2c1b13fcd2c9c5f1c7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3077c48e7da1cd8020e52f5e0c6dad16293888509a9429b8a5bd195ad36b894803ad2cb526886bdb94431dfa4b0b95c1504a8e8291c5e66992011d55e603007"} build_url:https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010875 build_url: 'https://queue.taskcluster.net/v1/task/T77vpxJVREijNPq18scQhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffe59ef2a2f88857730da2d104e4184be5cf583fba1763852d0336191423e90bc1d902b78b46a6e1f7e4c23e1fac1e2968e59d78a89b07c8734aefb39988b78d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5857432240f67587df901c237e35b63ef60386ae5b64426fcef2313a99e1459822f4b12903a51307ea7f8686cc2ab1110a91ba119797e2c1b13fcd2c9c5f1c7b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3077c48e7da1cd8020e52f5e0c6dad16293888509a9429b8a5bd195ad36b894803ad2cb526886bdb94431dfa4b0b95c1504a8e8291c5e66992011d55e603007"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 08:47:02.392872) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:47:02.393173) ========= 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.zAm1iceJI9/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.owAVWOJjJ4/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.005216 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 08:47:02.442567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 08:47:02.442932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 08:47:02.443218) ========= ========= Total master_lag: 0.18 =========