builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0296 starttime: 1476853510.17 results: success (0) buildid: 20161018193723 builduid: 16ccca1654054941add662d30dc90f1c revision: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.167738) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.168185) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.168481) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.193570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.193991) ========= 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.iQi12uF0J4/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.xxb81xSE2W/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-10-18 22:05:10-- 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% 159M=0s 2016-10-18 22:05:10 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080895 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.297663) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.298009) ========= 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.iQi12uF0J4/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.xxb81xSE2W/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.024800 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:10.347430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 22:05:10.347868) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 90d8afaddf9150853b0b68b35b30c1e54a8683e7 --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 90d8afaddf9150853b0b68b35b30c1e54a8683e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iQi12uF0J4/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.xxb81xSE2W/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-10-18 22:05:10,432 truncating revision to first 12 chars 2016-10-18 22:05:10,432 Setting DEBUG logging. 2016-10-18 22:05:10,432 attempt 1/10 2016-10-18 22:05:10,432 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/90d8afaddf91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 22:05:11,528 unpacking tar archive at: mozilla-central-90d8afaddf91/testing/mozharness/ program finished with exit code 0 elapsedTime=1.357028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 22:05:11.726748) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:11.727179) ========= script_repo_revision: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:11.727568) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:11.727826) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 22:05:11.745961) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 22:05:11.746380) ========= /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.iQi12uF0J4/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.xxb81xSE2W/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05:11 INFO - MultiFileLogger online at 20161018 22:05:11 in /builds/slave/test 22:05:11 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 22:05:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:05:11 INFO - {'append_to_log': False, 22:05:11 INFO - 'base_work_dir': '/builds/slave/test', 22:05:11 INFO - 'blob_upload_branch': 'Firefox', 22:05:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:05:11 INFO - 'branch': 'Firefox', 22:05:11 INFO - 'buildbot_json_path': 'buildprops.json', 22:05:11 INFO - 'config_files': ('talos/mac_config.py',), 22:05:11 INFO - 'default_actions': ('clobber', 22:05:11 INFO - 'read-buildbot-config', 22:05:11 INFO - 'download-and-extract', 22:05:11 INFO - 'populate-webroot', 22:05:11 INFO - 'create-virtualenv', 22:05:11 INFO - 'install', 22:05:11 INFO - 'run-tests'), 22:05:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:05:11 INFO - 'download_minidump_stackwalk': True, 22:05:11 INFO - 'download_symbols': 'ondemand', 22:05:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:05:11 INFO - 'tooltool.py': '/tools/tooltool.py', 22:05:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:05:11 INFO - '/tools/misc-python/virtualenv.py')}, 22:05:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:05:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:05:11 INFO - 'installer_path': 'installer.exe', 22:05:11 INFO - 'log_level': 'info', 22:05:11 INFO - 'log_name': 'talos', 22:05:11 INFO - 'log_to_console': True, 22:05:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:05:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:05:11 INFO - 'opt_config_files': (), 22:05:11 INFO - 'pip_index': False, 22:05:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:05:11 INFO - 'cmd': ('bash', 22:05:11 INFO - '-c', 22:05:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:05:11 INFO - 'enabled': True, 22:05:11 INFO - 'halt_on_failure': False, 22:05:11 INFO - 'name': 'check_screen_resolution'},), 22:05:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:05:11 INFO - 'cmd': ('bash', 22:05:11 INFO - '-c', 22:05:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:05:11 INFO - 'enabled': True, 22:05:11 INFO - 'halt_on_failure': False, 22:05:11 INFO - 'name': 'check_screen_resolution'},), 22:05:11 INFO - 'run_cmd_checks_enabled': True, 22:05:11 INFO - 'sps_profile': False, 22:05:11 INFO - 'sps_profile_interval': 0, 22:05:11 INFO - 'suite': 'dromaeojs-e10s', 22:05:11 INFO - 'system_bits': '32', 22:05:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:05:11 INFO - 'title': 't-yosemite-r7-0296', 22:05:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:05:11 INFO - 'use_talos_json': True, 22:05:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:05:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:05:11 INFO - 'work_dir': 'build'} 22:05:11 INFO - [mozharness: 2016-10-19 05:05:11.954925Z] Running clobber step. 22:05:11 INFO - Running pre-action listener: _resource_record_pre_action 22:05:11 INFO - Running main action method: clobber 22:05:11 INFO - rmtree: /builds/slave/test/build 22:05:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:05:13 INFO - Running post-action listener: _resource_record_post_action 22:05:13 INFO - [mozharness: 2016-10-19 05:05:13.970303Z] Finished clobber step (success) 22:05:13 INFO - [mozharness: 2016-10-19 05:05:13.970405Z] Running read-buildbot-config step. 22:05:13 INFO - Running pre-action listener: _resource_record_pre_action 22:05:13 INFO - Running main action method: read_buildbot_config 22:05:13 INFO - Using buildbot properties: 22:05:13 INFO - { 22:05:13 INFO - "project": "", 22:05:13 INFO - "product": "firefox", 22:05:13 INFO - "script_repo_revision": "production", 22:05:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:05:13 INFO - "repository": "", 22:05:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:05:13 INFO - "buildid": "20161018193723", 22:05:13 INFO - "pgo_build": "False", 22:05:13 INFO - "basedir": "/builds/slave/test", 22:05:13 INFO - "buildnumber": 311, 22:05:13 INFO - "slavename": "t-yosemite-r7-0296", 22:05:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:05:13 INFO - "platform": "yosemite_r7", 22:05:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:05:13 INFO - "branch": "mozilla-central", 22:05:13 INFO - "repo_path": "mozilla-central", 22:05:13 INFO - "revision": "90d8afaddf9150853b0b68b35b30c1e54a8683e7", 22:05:13 INFO - "stage_platform": "macosx64", 22:05:13 INFO - "builduid": "16ccca1654054941add662d30dc90f1c", 22:05:13 INFO - "slavebuilddir": "test" 22:05:13 INFO - } 22:05:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:05:13 INFO - Running post-action listener: _resource_record_post_action 22:05:13 INFO - [mozharness: 2016-10-19 05:05:13.973924Z] Finished read-buildbot-config step (success) 22:05:13 INFO - [mozharness: 2016-10-19 05:05:13.974055Z] Running download-and-extract step. 22:05:13 INFO - Running pre-action listener: _resource_record_pre_action 22:05:13 INFO - Running main action method: download_and_extract 22:05:13 INFO - mkdir: /builds/slave/test/build/tests 22:05:13 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')]} 22:05:13 INFO - trying https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:05:13 INFO - Downloading https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/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 22:05:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/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 22:05:15 INFO - Downloaded 1199 bytes. 22:05:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:05:15 INFO - Using the following test package requirements: 22:05:15 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:05:15 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:05:15 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:05:15 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'jsshell-mac.zip'], 22:05:15 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:05:15 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:05:15 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:05:15 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:05:15 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:05:15 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:05:15 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:05:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:05:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:05:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:05:15 INFO - Fetch https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:05:15 INFO - Http code: 200 22:05:15 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 22:05:15 INFO - x-amz-cf-id: 3ICn9VpKnV-Bxq6ohr6_0xQfvS_yy3bI3Ncub3RXYPKFM2hUteivkg== 22:05:15 INFO - x-amz-version-id: nx_pkPpRWYDP7FWBl2MQhfO2ZBmc5qWR 22:05:16 INFO - Expected file size: 16499966 22:05:16 INFO - Obtained file size: 16499966 22:05:22 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 22:05:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:05:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:05:22 INFO - Fetch https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:05:23 INFO - Http code: 200 22:05:23 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 22:05:23 INFO - x-amz-cf-id: oHOq6_Z_7JwIGu-dI6RkTsanVgYF0gK5S8pOTv6bxzPBkqQbbu_8zA== 22:05:23 INFO - x-amz-version-id: nx_pkPpRWYDP7FWBl2MQhfO2ZBmc5qWR 22:05:23 INFO - Expected file size: 16499966 22:05:23 INFO - Obtained file size: 16499966 22:05:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:05:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:05:30 INFO - Fetch https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:05:30 INFO - Http code: 200 22:05:30 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 22:05:30 INFO - x-amz-cf-id: V_Swpw0eVb1CbphVLmNW1C-mByfvMnN7PH4goYhih3At8N_dovs8mQ== 22:05:30 INFO - x-amz-version-id: 3sJVuzWNi6SOctCh_I5mBz2ZQLX5.2.g 22:05:30 INFO - Expected file size: 14409028 22:05:30 INFO - Obtained file size: 14409028 22:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:31 INFO - trying https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:05:31 INFO - Downloading https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:05:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/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 22:05:34 INFO - Downloaded 111646645 bytes. 22:05:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:05:34 INFO - mkdir: /builds/slave/test/properties 22:05:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:05:34 INFO - Writing to file /builds/slave/test/properties/build_url 22:05:34 INFO - Contents: 22:05:34 INFO - build_url:https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:05:34 INFO - Running post-action listener: _resource_record_post_action 22:05:34 INFO - Running post-action listener: set_extra_try_arguments 22:05:34 INFO - [mozharness: 2016-10-19 05:05:34.810086Z] Finished download-and-extract step (success) 22:05:34 INFO - [mozharness: 2016-10-19 05:05:34.810250Z] Running populate-webroot step. 22:05:34 INFO - Running pre-action listener: _resource_record_pre_action 22:05:34 INFO - Running main action method: populate_webroot 22:05:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:05:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:05:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:05:34 INFO - '--tppagecycles', 22:05:34 INFO - '7'], 22:05:34 INFO - 'tests': ['tsvgm']}}, 22:05:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:05:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:05:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:05:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:05:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['damp', 'tps']}, 22:05:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'tests': ['damp', 'tps']}, 22:05:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['dromaeo_dom']}, 22:05:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:05:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['basic_compositor_video']}, 22:05:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:05:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['a11yr', 22:05:34 INFO - 'ts_paint', 22:05:34 INFO - 'tpaint', 22:05:34 INFO - 'sessionrestore', 22:05:34 INFO - 'sessionrestore_no_auto_restore', 22:05:34 INFO - 'tabpaint']}, 22:05:34 INFO - 'other-e10s': {'tests': ['a11yr', 22:05:34 INFO - 'ts_paint', 22:05:34 INFO - 'tpaint', 22:05:34 INFO - 'sessionrestore', 22:05:34 INFO - 'sessionrestore_no_auto_restore', 22:05:34 INFO - 'tabpaint']}, 22:05:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:05:34 INFO - 'ts_paint', 22:05:34 INFO - 'tpaint', 22:05:34 INFO - 'sessionrestore', 22:05:34 INFO - 'sessionrestore_no_auto_restore', 22:05:34 INFO - 'tabpaint']}, 22:05:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:05:34 INFO - 'ts_paint', 22:05:34 INFO - 'tpaint', 22:05:34 INFO - 'sessionrestore', 22:05:34 INFO - 'sessionrestore_no_auto_restore', 22:05:34 INFO - 'tabpaint']}, 22:05:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['a11yr', 22:05:34 INFO - 'ts_paint', 22:05:34 INFO - 'tpaint', 22:05:34 INFO - 'sessionrestore', 22:05:34 INFO - 'sessionrestore_no_auto_restore', 22:05:34 INFO - 'tabpaint']}, 22:05:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['a11yr', 22:05:34 INFO - 'ts_paint', 22:05:34 INFO - 'tpaint', 22:05:34 INFO - 'sessionrestore', 22:05:34 INFO - 'sessionrestore_no_auto_restore', 22:05:34 INFO - 'tabpaint']}, 22:05:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['tsvgx', 22:05:34 INFO - 'tsvgr_opacity', 22:05:34 INFO - 'tart', 22:05:34 INFO - 'tscrollx', 22:05:34 INFO - 'cart']}, 22:05:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:05:34 INFO - 'tsvgr_opacity', 22:05:34 INFO - 'tart', 22:05:34 INFO - 'tscrollx', 22:05:34 INFO - 'cart']}, 22:05:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'talos_options': ['--disable-e10s'], 22:05:34 INFO - 'tests': ['tp5o']}, 22:05:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'tests': ['tp5o']}, 22:05:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'talos_options': ['--disable-e10s', 22:05:34 INFO - '--xperf_path', 22:05:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:34 INFO - 'tests': ['tp5n']}, 22:05:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:34 INFO - 'talos_options': ['--xperf_path', 22:05:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:34 INFO - 'tests': ['tp5n']}}, 22:05:34 INFO - 'talos.zip': {'path': '', 22:05:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:05:34 INFO - Running post-action listener: _resource_record_post_action 22:05:34 INFO - [mozharness: 2016-10-19 05:05:34.829182Z] Finished populate-webroot step (success) 22:05:34 INFO - [mozharness: 2016-10-19 05:05:34.829288Z] Running create-virtualenv step. 22:05:34 INFO - Running pre-action listener: _resource_record_pre_action 22:05:34 INFO - Running main action method: create_virtualenv 22:05:34 INFO - Creating virtualenv /builds/slave/test/build/venv 22:05:34 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 22:05:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:05:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:05:34 INFO - Using real prefix '/tools/python27' 22:05:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:05:35 INFO - Installing distribute.............................................................................................................................................................................................done. 22:05:38 INFO - Installing pip.................done. 22:05:38 INFO - Return code: 0 22:05:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:05:38 INFO - Reading from file tmpfile_stdout 22:05:38 INFO - Output received: 22:05:38 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) 22:05:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:05:38 INFO - 'HOME': '/Users/cltbld', 22:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:05:38 INFO - 'LOGNAME': 'cltbld', 22:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:38 INFO - 'MOZ_NO_REMOTE': '1', 22:05:38 INFO - 'NO_EM_RESTART': '1', 22:05:38 INFO - 'PAGER': '/bin/cat', 22:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:38 INFO - 'PWD': '/builds/slave/test', 22:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:05:38 INFO - 'SHELL': '/bin/bash', 22:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:05:38 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:05:38 INFO - 'USER': 'cltbld', 22:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:38 INFO - 'XPC_FLAGS': '0x0', 22:05:38 INFO - 'XPC_SERVICE_NAME': '0', 22:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:39 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 22:05:39 INFO - Cleaning up... 22:05:39 INFO - Return code: 0 22:05:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:39 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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05:39 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 22:05:39 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 22:05:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:05:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:05:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:05:39 INFO - 'HOME': '/Users/cltbld', 22:05:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:05:39 INFO - 'LOGNAME': 'cltbld', 22:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:39 INFO - 'MOZ_NO_REMOTE': '1', 22:05:39 INFO - 'NO_EM_RESTART': '1', 22:05:39 INFO - 'PAGER': '/bin/cat', 22:05:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:39 INFO - 'PWD': '/builds/slave/test', 22:05:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:05:39 INFO - 'SHELL': '/bin/bash', 22:05:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:05:39 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:05:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:05:39 INFO - 'USER': 'cltbld', 22:05:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:05:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:39 INFO - 'XPC_FLAGS': '0x0', 22:05:39 INFO - 'XPC_SERVICE_NAME': '0', 22:05:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:39 INFO - Downloading/unpacking psutil>=3.1.1 22:05:39 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 22:05:39 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 22:05:39 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 22:05:39 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 22:05:39 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 22:05:39 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 22:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:05:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:42 INFO - Installing collected packages: psutil 22:05:42 INFO - Running setup.py install for psutil 22:05:42 INFO - building 'psutil._psutil_osx' extension 22:05:42 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 22:05:43 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 22:05:43 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 22:05:43 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 22:05:43 INFO - building 'psutil._psutil_posix' extension 22:05:43 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 22:05:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:05:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:05:43 INFO - ^ 22:05:43 INFO - 1 warning generated. 22:05:43 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 22:05:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:44 INFO - Successfully installed psutil 22:05:44 INFO - Cleaning up... 22:05:44 INFO - Return code: 0 22:05:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:05: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 mozsystemmonitor==0.3 22:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:05:44 INFO - 'HOME': '/Users/cltbld', 22:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:05:44 INFO - 'LOGNAME': 'cltbld', 22:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:44 INFO - 'MOZ_NO_REMOTE': '1', 22:05:44 INFO - 'NO_EM_RESTART': '1', 22:05:44 INFO - 'PAGER': '/bin/cat', 22:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:44 INFO - 'PWD': '/builds/slave/test', 22:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:05:44 INFO - 'SHELL': '/bin/bash', 22:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:05:44 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:05:44 INFO - 'USER': 'cltbld', 22:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:44 INFO - 'XPC_FLAGS': '0x0', 22:05:44 INFO - 'XPC_SERVICE_NAME': '0', 22:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:05:44 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 22:05:44 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 22:05:44 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 22:05:44 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 22:05:44 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 22:05:44 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 22:05:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:05:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:05:46 INFO - Installing collected packages: mozsystemmonitor 22:05:46 INFO - Running setup.py install for mozsystemmonitor 22:05:47 INFO - Successfully installed mozsystemmonitor 22:05:47 INFO - Cleaning up... 22:05:47 INFO - Return code: 0 22:05:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:05:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:05:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:05:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:05:47 INFO - 'HOME': '/Users/cltbld', 22:05:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:05:47 INFO - 'LOGNAME': 'cltbld', 22:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:47 INFO - 'MOZ_NO_REMOTE': '1', 22:05:47 INFO - 'NO_EM_RESTART': '1', 22:05:47 INFO - 'PAGER': '/bin/cat', 22:05:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:47 INFO - 'PWD': '/builds/slave/test', 22:05:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:05:47 INFO - 'SHELL': '/bin/bash', 22:05:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:05:47 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:05:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:05:47 INFO - 'USER': 'cltbld', 22:05:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:05:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:47 INFO - 'XPC_FLAGS': '0x0', 22:05:47 INFO - 'XPC_SERVICE_NAME': '0', 22:05:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:47 INFO - Downloading/unpacking jsonschema==2.5.1 22:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:05:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:05:49 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 22:05:49 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 22:05:49 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 22:05:49 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 22:05:49 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 22:05:49 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 22:05:50 INFO - Downloading functools32-3.2.3-2.tar.gz 22:05:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:05:50 INFO - warning: no files found matching '*.txt' 22:05:50 INFO - no previously-included directories found matching 'build' 22:05:50 INFO - no previously-included directories found matching 'dist' 22:05:50 INFO - no previously-included directories found matching '.git*' 22:05:50 INFO - Installing collected packages: jsonschema, functools32 22:05:50 INFO - Running setup.py install for functools32 22:05:50 INFO - warning: no files found matching '*.txt' 22:05:50 INFO - no previously-included directories found matching 'build' 22:05:50 INFO - no previously-included directories found matching 'dist' 22:05:50 INFO - no previously-included directories found matching '.git*' 22:05:50 INFO - Successfully installed jsonschema functools32 22:05:50 INFO - Cleaning up... 22:05:50 INFO - Return code: 0 22:05:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:05:50 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')]} 22:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:50 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')]} 22:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:50 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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05:50 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 22:05:50 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 22:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:05:50 INFO - 'HOME': '/Users/cltbld', 22:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:05:50 INFO - 'LOGNAME': 'cltbld', 22:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:50 INFO - 'MOZ_NO_REMOTE': '1', 22:05:50 INFO - 'NO_EM_RESTART': '1', 22:05:50 INFO - 'PAGER': '/bin/cat', 22:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:50 INFO - 'PWD': '/builds/slave/test', 22:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:05:50 INFO - 'SHELL': '/bin/bash', 22:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:05:50 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:05:50 INFO - 'USER': 'cltbld', 22:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:50 INFO - 'XPC_FLAGS': '0x0', 22:05:50 INFO - 'XPC_SERVICE_NAME': '0', 22:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:05:51 INFO - Cleaning up... 22:05:51 INFO - Return code: 0 22:05:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:05:51 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')]} 22:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:51 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')]} 22:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:51 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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05:51 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 22:05:51 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 22:05:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:05:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:05:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:05:51 INFO - 'HOME': '/Users/cltbld', 22:05:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:05:51 INFO - 'LOGNAME': 'cltbld', 22:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:51 INFO - 'MOZ_NO_REMOTE': '1', 22:05:51 INFO - 'NO_EM_RESTART': '1', 22:05:51 INFO - 'PAGER': '/bin/cat', 22:05:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:51 INFO - 'PWD': '/builds/slave/test', 22:05:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:05:51 INFO - 'SHELL': '/bin/bash', 22:05:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:05:51 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:05:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:05:51 INFO - 'USER': 'cltbld', 22:05:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:05:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:51 INFO - 'XPC_FLAGS': '0x0', 22:05:51 INFO - 'XPC_SERVICE_NAME': '0', 22:05:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:51 INFO - Downloading/unpacking blobuploader==1.2.4 22:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:53 INFO - Downloading blobuploader-1.2.4.tar.gz 22:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:05:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:05:53 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 22:05:53 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 22:05:53 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 22:05:53 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 22:05:53 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 22:05:53 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 22:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:05:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:05:54 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 22:05:54 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 22:05:54 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 22:05:54 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 22:05:54 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 22:05:54 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 22:05:55 INFO - Downloading docopt-0.6.1.tar.gz 22:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:05:55 INFO - Installing collected packages: blobuploader, requests, docopt 22:05:55 INFO - Running setup.py install for blobuploader 22:05:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:05:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:05:55 INFO - Running setup.py install for requests 22:05:55 INFO - Running setup.py install for docopt 22:05:55 INFO - Successfully installed blobuploader requests docopt 22:05:55 INFO - Cleaning up... 22:05:56 INFO - Return code: 0 22:05:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:56 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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:05:56 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 22:05:56 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 22:05:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:05:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:05:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:05:56 INFO - 'HOME': '/Users/cltbld', 22:05:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:05:56 INFO - 'LOGNAME': 'cltbld', 22:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:56 INFO - 'MOZ_NO_REMOTE': '1', 22:05:56 INFO - 'NO_EM_RESTART': '1', 22:05:56 INFO - 'PAGER': '/bin/cat', 22:05:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:56 INFO - 'PWD': '/builds/slave/test', 22:05:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:05:56 INFO - 'SHELL': '/bin/bash', 22:05:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:05:56 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:05:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:05:56 INFO - 'USER': 'cltbld', 22:05:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:05:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:56 INFO - 'XPC_FLAGS': '0x0', 22:05:56 INFO - 'XPC_SERVICE_NAME': '0', 22:05:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:05:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-teSjyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:05:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Qsi_Ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:05:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nB5px2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:05:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-6kXa7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:05:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wQdms_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:05:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BeRe9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:05:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BBOBiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-6bpBtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-yjq2wI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-NsiRCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XMHlUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-VWEz7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wLDctu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-YxLj7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-E6jWj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-9xAHQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:05:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-EJUmws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:05:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:05:58 INFO - Running setup.py install for manifestparser 22:05:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:05:58 INFO - Running setup.py install for mozcrash 22:05:58 INFO - Running setup.py install for mozdebug 22:05:58 INFO - Running setup.py install for mozdevice 22:05:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:05:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:05:58 INFO - Running setup.py install for mozfile 22:05:58 INFO - Running setup.py install for mozhttpd 22:05:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:05:58 INFO - Running setup.py install for mozinfo 22:05:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:05:59 INFO - Running setup.py install for mozInstall 22:05:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:05:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:05:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:05:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:05:59 INFO - Running setup.py install for mozleak 22:05:59 INFO - Running setup.py install for mozlog 22:05:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:05:59 INFO - Running setup.py install for moznetwork 22:05:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:05:59 INFO - Running setup.py install for mozprocess 22:05:59 INFO - Running setup.py install for mozprofile 22:06:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:06:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:06:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:06:00 INFO - Running setup.py install for mozrunner 22:06:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:06:00 INFO - Running setup.py install for mozscreenshot 22:06:00 INFO - Running setup.py install for moztest 22:06:00 INFO - Running setup.py install for mozversion 22:06:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:06:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:06:00 INFO - Cleaning up... 22:06:00 INFO - Return code: 0 22:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:00 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')]} 22:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:00 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')]} 22:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:00 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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:06:00 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 22:06:00 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 22:06:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:06:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:00 INFO - 'HOME': '/Users/cltbld', 22:06:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:00 INFO - 'LOGNAME': 'cltbld', 22:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:00 INFO - 'MOZ_NO_REMOTE': '1', 22:06:00 INFO - 'NO_EM_RESTART': '1', 22:06:00 INFO - 'PAGER': '/bin/cat', 22:06:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:00 INFO - 'PWD': '/builds/slave/test', 22:06:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:00 INFO - 'SHELL': '/bin/bash', 22:06:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:06:00 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:06:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:00 INFO - 'USER': 'cltbld', 22:06:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:00 INFO - 'XPC_FLAGS': '0x0', 22:06:00 INFO - 'XPC_SERVICE_NAME': '0', 22:06:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-I_ddD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-70MBEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-yMemad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BocR7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-WoBsk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-mBkF9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-_2bPdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Bw4TMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-VXzQnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:01 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)) 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-hJ5hNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:02 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)) 22:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-7q3LUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:02 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)) 22:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-RX3brQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:02 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)) 22:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-qbxYYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:02 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)) 22:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-19D9w3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:02 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)) 22:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-pODruZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:02 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)) 22:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-DuSrEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:02 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)) 22:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-MuG7rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:02 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)) 22:06:02 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)) 22:06:02 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)) 22:06:02 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)) 22:06:02 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)) 22:06:02 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)) 22:06:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:05 INFO - Downloading blessings-1.6.tar.gz 22:06:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:06:05 INFO - Installing collected packages: blessings 22:06:05 INFO - Running setup.py install for blessings 22:06:05 INFO - Successfully installed blessings 22:06:05 INFO - Cleaning up... 22:06:05 INFO - Return code: 0 22:06:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:06:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:05 INFO - Reading from file tmpfile_stdout 22:06:05 INFO - Current package versions: 22:06:05 INFO - blessings == 1.6 22:06:05 INFO - blobuploader == 1.2.4 22:06:05 INFO - docopt == 0.6.1 22:06:05 INFO - functools32 == 3.2.3-2 22:06:05 INFO - jsonschema == 2.5.1 22:06:05 INFO - manifestparser == 1.1 22:06:05 INFO - mozInstall == 1.12 22:06:05 INFO - mozcrash == 0.17 22:06:05 INFO - mozdebug == 0.1 22:06:05 INFO - mozdevice == 0.48 22:06:05 INFO - mozfile == 1.2 22:06:05 INFO - mozhttpd == 0.7 22:06:05 INFO - mozinfo == 0.9 22:06:05 INFO - mozleak == 0.1 22:06:05 INFO - mozlog == 3.4 22:06:05 INFO - moznetwork == 0.27 22:06:05 INFO - mozprocess == 0.23 22:06:05 INFO - mozprofile == 0.28 22:06:05 INFO - mozrunner == 6.12 22:06:05 INFO - mozscreenshot == 0.1 22:06:05 INFO - mozsystemmonitor == 0.3 22:06:05 INFO - moztest == 0.7 22:06:05 INFO - mozversion == 1.4 22:06:05 INFO - psutil == 3.1.1 22:06:05 INFO - requests == 1.2.3 22:06:05 INFO - wsgiref == 0.1.2 22:06:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:05 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')]} 22:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:05 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')]} 22:06:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:05 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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:06:05 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 22:06:05 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 22:06:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:06:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:05 INFO - 'HOME': '/Users/cltbld', 22:06:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:05 INFO - 'LOGNAME': 'cltbld', 22:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:05 INFO - 'MOZ_NO_REMOTE': '1', 22:06:05 INFO - 'NO_EM_RESTART': '1', 22:06:05 INFO - 'PAGER': '/bin/cat', 22:06:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:05 INFO - 'PWD': '/builds/slave/test', 22:06:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:05 INFO - 'SHELL': '/bin/bash', 22:06:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:06:05 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:06:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:05 INFO - 'USER': 'cltbld', 22:06:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:05 INFO - 'XPC_FLAGS': '0x0', 22:06:05 INFO - 'XPC_SERVICE_NAME': '0', 22:06:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 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)) 22:06:06 INFO - Cleaning up... 22:06:06 INFO - Return code: 0 22:06:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:06:06 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')]} 22:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:06 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')]} 22:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:06 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 0x1060df490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106055570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2c1d6df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, '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.iQi12uF0J4/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.xxb81xSE2W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 22:06:06 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 22:06:06 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 22:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:06 INFO - 'HOME': '/Users/cltbld', 22:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:06 INFO - 'LOGNAME': 'cltbld', 22:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:06 INFO - 'MOZ_NO_REMOTE': '1', 22:06:06 INFO - 'NO_EM_RESTART': '1', 22:06:06 INFO - 'PAGER': '/bin/cat', 22:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:06 INFO - 'PWD': '/builds/slave/test', 22:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:06 INFO - 'SHELL': '/bin/bash', 22:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:06:06 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:06 INFO - 'USER': 'cltbld', 22:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:06 INFO - 'XPC_FLAGS': '0x0', 22:06:06 INFO - 'XPC_SERVICE_NAME': '0', 22:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:06:06 INFO - Cleaning up... 22:06:06 INFO - Return code: 0 22:06:06 INFO - Running post-action listener: _resource_record_post_action 22:06:06 INFO - Running post-action listener: _start_resource_monitoring 22:06:06 INFO - Starting resource monitoring. 22:06:06 INFO - [mozharness: 2016-10-19 05:06:06.563697Z] Finished create-virtualenv step (success) 22:06:06 INFO - [mozharness: 2016-10-19 05:06:06.564735Z] Running install step. 22:06:06 INFO - Running pre-action listener: _resource_record_pre_action 22:06:06 INFO - Running main action method: install 22:06:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:06 INFO - Reading from file tmpfile_stdout 22:06:06 INFO - Detecting whether we're running mozinstall >=1.0... 22:06:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:06:06 INFO - Reading from file tmpfile_stdout 22:06:06 INFO - Output received: 22:06:06 INFO - Usage: mozinstall [options] installer 22:06:06 INFO - Options: 22:06:06 INFO - -h, --help show this help message and exit 22:06:06 INFO - -d DEST, --destination=DEST 22:06:06 INFO - Directory to install application into. [default: 22:06:06 INFO - "/builds/slave/test"] 22:06:06 INFO - --app=APP Application being installed. [default: firefox] 22:06:06 INFO - mkdir: /builds/slave/test/build/application 22:06:06 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'] 22:06:06 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 22:06:41 INFO - Reading from file tmpfile_stdout 22:06:41 INFO - Output received: 22:06:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:06:41 INFO - Running post-action listener: _resource_record_post_action 22:06:41 INFO - [mozharness: 2016-10-19 05:06:41.114945Z] Finished install step (success) 22:06:41 INFO - [mozharness: 2016-10-19 05:06:41.115110Z] Running run-tests step. 22:06:41 INFO - Running pre-action listener: _resource_record_pre_action 22:06:41 INFO - Running main action method: run_tests 22:06:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:06:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:06:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:06:41 INFO - 2016-10-18 22:06:41.129 screenresolution[1642:7382] starting screenresolution argv=screenresolution get 22:06:41 INFO - 2016-10-18 22:06:41.132 screenresolution[1642:7382] Display 0: 1600x1200x32@60 22:06:41 INFO - 2016-10-18 22:06:41.138 screenresolution[1643:7384] starting screenresolution argv=screenresolution list 22:06:41 INFO - Available Modes on Display 0 22:06:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:06:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:06:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:06:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:06:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:06:41 INFO - Intel Iris: 22:06:41 INFO - Chipset Model: Intel Iris 22:06:41 INFO - Type: GPU 22:06:41 INFO - Bus: Built-In 22:06:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:06:41 INFO - Vendor: Intel (0x8086) 22:06:41 INFO - Device ID: 0x0a2e 22:06:41 INFO - Revision ID: 0x0009 22:06:41 INFO - Displays: 22:06:41 INFO - Display: 22:06:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:06:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:06:41 INFO - Display Serial Number: 1600x1200 60 22:06:41 INFO - Main Display: Yes 22:06:41 INFO - Mirror: Off 22:06:41 INFO - Online: Yes 22:06:41 INFO - Rotation: Supported 22:06:41 INFO - Return code: 0 22:06:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:06:41 INFO - Python 2.7.3 22:06:41 INFO - Return code: 0 22:06:41 INFO - grabbing minidump binary from tooltool 22:06:41 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')]} 22:06:41 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 0x105ef3d30>, '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 0x105d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eebb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:06:41 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 22:06:41 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 22:06:41 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 22:06:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:06:41 INFO - Return code: 0 22:06:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:06:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:06:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:06:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:06:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:06:41 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/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 22:06:41 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/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 22:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQi12uF0J4/Render', 22:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:41 INFO - 'HOME': '/Users/cltbld', 22:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:41 INFO - 'LOGNAME': 'cltbld', 22:06:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:06:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:41 INFO - 'MOZ_NO_REMOTE': '1', 22:06:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:06:41 INFO - 'NO_EM_RESTART': '1', 22:06:41 INFO - 'PAGER': '/bin/cat', 22:06:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:41 INFO - 'PWD': '/builds/slave/test', 22:06:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:41 INFO - 'SHELL': '/bin/bash', 22:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xxb81xSE2W/Listeners', 22:06:41 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 22:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:41 INFO - 'USER': 'cltbld', 22:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:41 INFO - 'XPC_FLAGS': '0x0', 22:06:41 INFO - 'XPC_SERVICE_NAME': '0', 22:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:41 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/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 22:06:41 INFO - mozversion application_buildid: 20161018193723 22:06:41 INFO - mozversion application_changeset: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 22:06:41 INFO - mozversion application_display_name: Nightly 22:06:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:06:41 INFO - mozversion application_name: Firefox 22:06:41 INFO - mozversion application_remotingname: firefox 22:06:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:06:41 INFO - mozversion application_vendor: Mozilla 22:06:41 INFO - mozversion application_version: 52.0a1 22:06:41 INFO - mozversion platform_buildid: 20161018193723 22:06:41 INFO - mozversion platform_changeset: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 22:06:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:06:41 INFO - mozversion platform_version: 52.0a1 22:06:41 INFO - using testdate: 1476853601 22:06:41 INFO - actual date: 1476853601 22:06:41 INFO - starting webserver on 'localhost:49273' 22:06:41 INFO - SUITE-START | Running 2 tests 22:06:41 INFO - TEST-START | dromaeo_css 22:06:41 INFO - operating with platform_type : mac_ 22:06:41 INFO - Initialising browser for dromaeo_css test... 22:06:41 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpDR91w9/profile http://localhost:49273/getInfo.html) 22:06:44 INFO - PROCESS | 1653 | 2016-10-18 22:06:44.704 plugin-container[1655:7552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 22:06:44 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:06:44 INFO - PROCESS | 1653 | 2016-10-18 22:06:44.706 plugin-container[1655:7552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f1f, name = 'com.apple.CFPasteboardClient' 22:06:44 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:06:44 INFO - PROCESS | 1653 | 2016-10-18 22:06:44.707 plugin-container[1655:7552] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:06:44 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 22:06:44 INFO - PROCESS | 1653 | colorDepth:24 22:06:44 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 22:06:44 INFO - PROCESS | 1653 | __metrics 22:06:44 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 22:06:45 INFO - TEST-INFO | 1653: exit 0 22:06:45 INFO - Browser initialized. 22:06:45 INFO - Running cycle 1/1 for dromaeo_css test... 22:06:45 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpDR91w9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:48 INFO - PROCESS | 1656 | 2016-10-18 22:06:48.210 plugin-container[1657:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 22:06:48 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:06:48 INFO - PROCESS | 1656 | 2016-10-18 22:06:48.211 plugin-container[1657:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 22:06:48 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:06:48 INFO - PROCESS | 1656 | 2016-10-18 22:06:48.211 plugin-container[1657:7672] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:06:57 INFO - PROCESS | 1656 | RSS: Main: 237039616 22:06:57 INFO - PROCESS | 1656 | 22:07:47 INFO - PROCESS | 1656 | 1476853667406 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] 22:10:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 22:10:03 INFO - PROCESS | 1656 | RSS: Main: 249696256 22:10:03 INFO - PROCESS | 1656 | 22:13:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 22:13:06 INFO - PROCESS | 1656 | RSS: Main: 263774208 22:13:06 INFO - PROCESS | 1656 | 22:16:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 22:16:08 INFO - PROCESS | 1656 | RSS: Main: 268357632 22:16:08 INFO - PROCESS | 1656 | 22:19:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 22:19:11 INFO - PROCESS | 1656 | RSS: Main: 267853824 22:19:11 INFO - PROCESS | 1656 | 22:22:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 22:22:14 INFO - PROCESS | 1656 | RSS: Main: 273121280 22:22:14 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 22:25:17 INFO - PROCESS | 1656 | RSS: Main: 276045824 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | __start_tp_report 22:25:17 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 22:25:17 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 22:25:17 INFO - PROCESS | 1656 | |i|pagename|runs| 22:25:17 INFO - PROCESS | 1656 | |0;dojo.html;2983.5226363781794;3406.0973841931877;3415.9487607685874;3453.08493249289;3455.343484737898;4723.57487826096;5657.094864821629;5724.96950548901;5753.683547404891;5863.6806718488315;4803.135435621587;5673.8581535461635;5766.884662306762;5825;5890.970545147279;5189.143791274435;5227.764750586214;5233.764480598363;5353.518183363496;5372.7045012524295;4341.565843415665;5372.435894231108;5395.109806881879;5416.322959630046;5435.728213589325;5241.816536421207;5277.1820367842665;5313.0967735485065;5319.946800531984;5339.5461385782255;5290.629655924048;5326.387465441485;5329.173978033411;5336.252924590561;5357.142857142839;5216.478352164791;5217.791288348461;5256.579473642099;5290.338707661543;5314.5216930476445;4762.595179409755;5672;5676.233708449347;5700.030994730906;5851.356350801409;4651.930221046687;6137.631742095444;6171.87656246877;6216.87566248677;6234.158388617524;6300.59046161998;6303.086052522404;6309.116723658692;6342.460890824285;6381.074744162071;5952.375000624872;5956.195913551658;5961.4038596140035;5967.791127310524;5980.850478738066;6286.4342209201395;6294.402031806971;6302.338254483305;6346.524010699216;6372.171617689671;6223.688815559204;6232.657203853832;6241.18864547615;6241.937580624226;6248.5313601480075;6139.768135342631;6190.133381326618;6333.714982825784;6363.5863668861375;6364.904526432108;6146.7091910699255;6219.191505104308;6249.593776404519;6281.403266689657;6295.5907865988565;6243.781467648701;6290.685465726695;6293;6321.936780632227;6324.778632747832;4962.801487940442;4996.275540046673;5004.924926126111;5010.2735103409805;5011.1481048221885;4393.51671316155;4408.713433626804;4438.423005009393;4454.844080457168;4529.162105010583;5831.620944638585;5900.1149827526215;5910.202122713378;5913.526917846562;5958.85102872423;6297.055441683692;6300.21247344082;6315.873682526416;6327.968360158262;6329.2721337045905;4755.953690188153;4770.6660533763;5845.853853653608;5896.587238893322;5939.3763654816485;4797.688150270221;5476.507114359647;5483;5525.917111243334;5531.668099914018;5292.867678308073;6453.967730161319;6478.352164783578;6522.967385163044;6528.869422611426;6183.536234782499;6222.782202623068;6225.533085018732;6269.310375858743;6286.748530058747;4997.875053123701;6007.969960150172;6332.968335158479;6458.67706614658;6465.77369792055;6160.845978850564;6215.471684906834;6281.59169653971;6293.496520278275;6304.65324407162;5833.883322333614;6169.876602467836;6240.625562466085;6262.1546091278915;6339.651319177489;6119.015143218386;6146.385361463817;6188.9690551546955;6221.3156552780065;6254.6247225166635;6162.26052873658;6221.2845522765465;6224.875502490016;6260.843478913063;6262.530310226843;5464.371597266365;5488.780448782004;5885.646861188342;5953.523718102455;6036.939630603638;6180.783672571439;6206.286277078013;6211.534134939988;6215.81352559424;6241.157443745036;6048.8357307479255;6058.27300723916;6082.635041897501;6093.573449858644;6094.786682466092 22:25:17 INFO - PROCESS | 1656 | |1;ext.html;471.3071784476819;487.18396685551704;523.5968304405608;523.9764210610522;585.2128886647457;30014.84992575034;38033.42949855755;40540.391894121574;40552.98617534563;40576.39135412967;41538.96152596179;42436;42586.361204581954;42609.78695106528;43244.35133472992;9349.205317548;9623.56693948774;9627.614895404175;9665.275104367165;9679.516024198761;9528.23774098074;9620.807583848318;9634.47010414427;9649.662261820838;9679.128878400943;9635.373700709466;9644.614215431375;9675.080867317594;9698;9700.951495242514;9572.760680982961;9617.134457898752;9618.326717129803;9653.855192172123;9662.275329350327;9375.76560585984;9379.202767764747;9476.147146756824;9489.430634161972;9501;39529;39800;39918;39992;40051.198976020605;3365.9158521036925;3458.706009989154;3488.5639295088113;3584.211473475831;4197.832086716528;30086.946956856405;30849.537256941163;31201.37597248065;31266.21834454134;31305.37389252225;3135.263213144909;3254.3816674831705;3283.6387997320276;3320.3193345364384;3322.5016247562817;427999;428390;431466;432990;432992;61418.38581614216;61985.07022394668;62025.37974620196;62089.379106209264;62151;51997.840172786084;52070.73964630228;52793.94412111736;64525;65453.345466545674;15775.763363549557;15868.920655396649;16014.759778603331;16043;16126.354945802037;10758.892411075945;12483.12618116723;12494.375281235902;12579.685507862194;12613.243205407522;15081.396744130112;17554.29782808699;17800.732989005177;18244.908775456304;18250.452486425427;6409;6457.741690332428;6542.24764152119;6602.533669823381;6617.239017512951;846.3736834742247;896.3501461440403;973.4110862927968;1087.4508373271447;1116.6761639124563;28999.275018124295;30088.84955575208;30285.93999210017;30827.229319267197;30851.382972340427;953.4755884263766;987.7283746969497;1030.3611760708409;1087.722630729159;1129.8361737548173;15941.36234550605;15954.521364358856;16004.679906401807;16080.67838643244;16245.350185992427;538696.3065184649;656875.7156214188;662808;671839;671980;58950.705246473495;59540.70229648824;59558;59717.402825971185;59739.402605974254;1327.9004074694244;1367.4940272099386;1368.9589312320647;1388.9166650001032;1397.3991183790865;25921;29120.854395727885;30008.099757007323;30150.2462438432;30739.770409184275;51653;52248.47751522436;52272.738636306574;52432;52555.47444525659;560798;572974;574669;575717;577117;27111.322216944733;27205;27525.72474275312;27560.58659120155;27598.89604415801;46702.76648616871;46710;47680;48328.5167148324;49309.013819722684;33103.66896331006;40020;40027.79986100051;40423.98940026523;42372;35434;39676;40266.798666006485;40291.19417611573;40519;29121;33135;37208;38077;39671.801640992766;27039.86480067587;34508.65491345155;34958.65041349554;35358;35476.290474190886;21988.560228795654;22331;25957.351066223495;26007;26275.343116422242 22:25:17 INFO - PROCESS | 1656 | |2;jquery.html;361.27023412706336;366.4448360733488;367.15920541958917;376.8341929550996;397.8985358733523;11370.715732106692;11780.234284771484;11999.340036298001;12011.819822702644;18947.431577052692;17476.563085922826;20719.689204661903;21581.78418215821;21664.8916755416;22086.558268834615;6602.1747281589805;6883.518153729241;6919.515633905628;6939.0979172707475;6962.617056061915;6461.741530338781;6697.229818570853;6727.4618030557685;6783.6269005204695;6804.149481314836;6240.7830473057775;6374.649394283312;6557.180352455943;6791.049253104569;6801.081853949728;6677.632730199837;6777.2545020047755;6788.287229840845;6795.898061529081;6806.353396427331;6388.329225431309;6542.544976260113;6562.114114594517;6632.436242919358;6645.667716614198;22051.779482205133;26759.331016724544;28224.717752822413;28274.575881361798;28441.288967775763;2552.9361765955814;2638.604209368591;2763.101991852636;3374.4769560718128;3417.829108544563;13189.802152967713;13769.518066867611;14887.255637218204;15840.603984900354;16424.17879106052;1592.283472437408;1925.951851203717;2057.238821636004;2082.562661841015;2084.934377962963;93978;107503;122187.38906305411;122783;124662;16206;16945.661086778196;17017.553507951714;21634.675479867816;21817.67273490899;29999.850000749855;32753.50869736908;35042.64957350445;35085.29829403398;35143.64856351455;8442.493450392996;8476.618552165168;8641.654333826702;8985.101489850962;9665.613375464902;8418.536980466133;8674.78313042179;8754.737357879274;8805.471671699719;8838.86741698875;8552.572371381406;9821.950890245504;9860.605575777032;9972.451515166591;9978.401295922124;3444.844981975817;3830.138218900725;3906.3554513505524;3992.5208974923194;4041.6968727345566;853.1979938857454;884.0893879304327;943.8867335919733;961.3847137831793;984.1683777208248;17649.647007060044;19569.217231310588;20557.486062848548;20912.581748364948;21325.36023919285;889.1908363389284;914.9679761208325;918.6995495696484;937.4047479850344;1001.6193846338344;10536.841947370796;11018.283811552223;11035.944820276009;11039;11041.33751974884;575579;586737;593555;593777;600264;125050;127959.36020319839;128975.71024289825;129112;130903.69096309105;840.306746933782;934.3552948465673;951.2247518272724;985.5515740338129;987.1313244344931;19017.904910475914;19401.51496212606;19416.611667766283;19458.027098645296;19480.98044803001;38047.809760951015;52406.73796630993;52595.474045259056;55105;55658.44341556533;54061.64845878885;75429;82552.17447825686;83370;85257.72113418179;8011.919880801117;8140.796480088045;8143.6742530298125;8286.41995060364;8407.95796021016;12221.69445763863;12902.80645790295;13089.476420943056;13217.471301147847;13472.191668499921;8746.868796968178;9228.723138305862;9479.905200947902;11486.793886641783;11532.731399545875;9208.769780755267;9326;10641.414722190353;10968.23222374426;11481.425928703364;11243.60647377338;11380.715482113013;11605.825912611486;11613.825792612948;11710.238834475731;7798.454108212371;8066.7579972600915;8093.433459657767;8179.263866252066;8204.671813127408;9669.178119859891;9823.459709715748;9992.15066719337;11074.003339699466;11209.607663732057 22:25:17 INFO - PROCESS | 1656 | |3;mootools.html;1503.2483758120936;1555.152441919154;1754.184304298501;1826.6620675175102;1874.8500119990401;8098.797530061752;8378.245957863792;8528.445651032685;9141.497217653026;9197.678081267157;8546.615402306896;8836.602352894119;8859.95570022149;8923.687670931518;8980.640774369018;2346.8474549154334;2383.84505007174;2394.8563086214795;2396.532676128147;2397.568437681217;2359.917402890899;2397.29279862441;2398.1486572266767;2403.170906037414;2404.230646193219;2360.1503458754833;2362;2376.3227480168157;2400.667629465646;2404.2546810488802;2331.0908745589236;2343.2852979841227;2343.5781559319316;2354.1878052072007;2358.068562917655;2333.451638864882;2334.1480359668767;2344.3318654183645;2347.718273807137;2351.729551101611;8838.690645827364;8864;8910.153535414125;8967.327450441244;8967.372283940127;5233.659812112239;5390.326209223847;5394.217838413408;5399.946000539983;5421.349438067417;3560.7151427885706;3565.6969157621634;3620.312140693286;3632.7275454340765;3633.8909832705062;1053.4206186597416;1053.947302634865;1058.7935352606246;1063.7393838509538;1070.1973519860105;11797.115216358721;11848.229865058744;11929.463174157183;11943;12005.699857503545;4589.2198326284615;4614.792334344963;4806.182948898696;4812.783424745895;4860.708357498562;9961.750956226007;10040.196784257241;10073.194144468425;10074.445905475122;10101.141402980682;5308.309919710413;5325.068113080196;5329.094054010828;5350.117230656976;5360.544353729977;4437.800298986554;4604.171249175113;4767.189577771787;4785.186518291899;4897.240927656219;3271.362084393568;3370.1574606348413;3526.3652542542077;4022.778747168934;4179.435776170239;1262.6085913366885;1284.7623189709748;1309.4434865182259;1314.7764879970428;1320.2672516753103;641.1344684675651;741.2735519191223;832.7751507093172;909.7407238936871;941.2517048946104;5894.498967587803;5898.1152827076285;6078.878422431615;6108.725107370179;6371.426571608485;875.312879389676;886.7650072730731;925.1950802801606;975.9365641233297;1025.600015993763;3696.1129328960856;3772.4718539404507;3839.2897313997;3941.4876066111224;3984.6613037891525;10636.042756151983;10927.836082458769;11049.889501105046;11057.889421105687;11100.056495197998;10991.670249892515;10993.560257589766;11002.284851484628;11038.44807759625;11044.834327485094;8359.122292159236;8459.900212972398;8471.661133554711;8476.406651534333;8484.872726909101;4585.220512512814;4915.901681966412;5702;5756.107803290497;5876.206712093813;8897.555122243992;9582.993785652407;9970.800583988135;10254.846177307496;10284.691459256233;10042.347247428894;10260.640877569249;10350.171986241234;10429.634962776267;10482.52828622729;6572.671366431564;6805.3875151236625;7065.399441047569;7124.501284910007;7227.927720722936;8472.279856212044;8604.096569860074;8642.611082501428;8655.394122411371;8664.78338041554;6162.815115546541;7506.3244308012545;7631.54210747357;7632.313091821763;7767.417443691633;7450.664720087718;7457.515261507984;7492.587907665131;7502.399808015237;7681.116671582839;6937.479689023243;7067.5759454430845;7082.964585177041;7085.14978202619;7121.109861267341;4939.234418665113;4993.650444468853;5053.69677819332;5128.871778205575;5130.333056702605;4798.832040878552;5002.649814512949;5133.820316288912;5164.819231327036;5229.320188375486 22:25:17 INFO - PROCESS | 1656 | |4;prototype.html;276.2720232188184;280.8371144736053;296.8426733831068;310.1223537389189;311.33529913634385;4531.478879928805;4688.812447502104;5239.532930779388;5379.825902717396;5495.395506494282;4360.738355698661;5196.688198708081;5363.6245462817615;5389.703566303851;5418.775828382116;3419.111031131899;3592;3750.812459377034;4119.876403707894;4129.690273229505;3435.707964822993;4026.778527181004;4078.2047500737367;4147.221361687676;4276.5295817460055;4074.1851629674034;4099.446574712406;4111.5477297497255;4113.218488487178;4124.463819703449;3863.821534431997;4122.567130451304;4122.87671848998;4133.2973394522705;4166.375043743433;3370.4832825228746;3602.5316708827945;3608.618311914124;3613.9819300903328;3616.7287453440836;4373.775342903992;4984.651074424755;5022.673526220784;5279.656822306538;5314.0700377433795;4352.998810273655;4648.349231107648;4739.056986635497;4739.502352252998;4781.497942716034;5127.820526281562;5192.454792246797;5230.155835460933;5693.035902613592;5711.571632127566;2987.850607469618;3584.7849129052343;3802.8288727007352;3808.6762625176893;3876.5541962674088;5225.895482090375;5888.5877988540815;5976.671283079429;6022.825549017944;6027.698615069273;3882.475865758143;4011.7592944423427;4016.618421250035;4018.8593399230886;4059.4519739834745;3183.71346578809;3300;4062.370332598452;4101.876943691694;4111.759091295251;4695.5304469552775;4715.504871988392;4732.1718699227495;4749.45642666128;4757.26262429324;4950.480199578992;5297.099493086108;5374.570034397317;5755.884882302415;5833.308340581184;1213.5934461955344;1305.3668970549277;1310.4430616987743;1372.6225288045866;1398.7482253194319;885.9899714325674;900.1268769293774;929.7954450020984;952.2619969523536;954.9045095490395;5413.079776438015;5425.593080518976;5440.863978400572;5452.345718513804;5467.234587157801;5211.348581427322;5586.525145362609;5864.914990726644;5868.442497962772;5912.408759124053;5357.571394288526;5373.650712703662;5386.303314455934;5389.02997460453;5423.484768946943;2338.777816107467;2615.882285297166;2659.5372545100117;2681.6781986161395;2701.783857291412;4327.5239723631;4630.398048253705;5044.268581055726;5053.3071421074355;5149.922751158736;4748.406449193851;5073.492650734897;5081.339425874687;5119.744012799345;5165.767540460688;4039.444813746729;4577.5052596335745;4850.235365841507;4974.452810190876;5076.05085042081;4106.7946602670345;4774.30772537987;4913.7543122843;4931.50684931504;4944.145945270524;4012.678985681197;4193.454850869368;4200.516940551754;4305.763183024964;4766.951270720436;4496.505384407647;5068.0741318298815;5092.949070509248;5169.457206993212;5179.067767801832;3471.340445315382;3472.8610855565494;3592.4611308303965;3972.4041393790008;4016.8393264269102;5905.641702408556;5913.438223368773;5923.526117910643;5927.940720592739;5933.525317974638;5780.855478613068;5807.970960145172;5837.357890632112;5844.503217226414;5853.1220316952795;5429.40276569585;5622.578306626938;5713.800016999385;5727.365411910401;5731.168980497888;4818.927716084191;4906.822362632871;5044.419891712353;5289.090999949935;5712.800571779978;5077.619178561697;5251.632385732962;5846.210761547137;5956.344802071855;5958.523318134626;4973.6269779765935;5366.758495867617;5550.666959982414;5629.634073785191;5707.914381284201 22:25:17 INFO - PROCESS | 1656 | |5;yui.html;752.4018405367732;760.7983884270669;772.1236396689759;793.638894303092;854.7179430787838;15080.622984425394;16086.034837909734;16155.757663635055;16199.190040497971;16229.83770162298;14489.92755036226;14618.48835290765;14640.194789286585;14649.461806510297;14664.926675366634;863.3266052479077;873.8645509945936;902.8645703144515;912.4890061565511;914.1681070226095;901.9897714559702;903.0382642485764;905.483874191711;905.7961958559338;907.4918045894287;926.5645146781002;941.0965473145789;951.224751827262;952.9803110671584;953.8998405167462;943.1935694980796;946.1815529566944;947.3463310315894;947.4552132523827;947.8909925358535;679.8436359637311;700.5411455496658;703.5216053083901;703.7853454696316;704.6547191875944;15319.234038298151;15513.534593962198;15525.223738813127;15563;15697.843021569752;13947.302634868318;13990.790138147935;14217.146971181763;14225.928870355581;14283.78574321386;11478.655640330804;11748.118891083202;11795.882041179564;11989.700257493545;12082;3705.818393053098;3764.247150569897;3774.5281839770028;3781.149241420686;3899.298126337258;119070.4046479762;119120;119256;119938;120416;10782.975617316342;10867.728306792234;10885.346879187275;10913.618023369145;11036.613718519813;22007.708496842963;22024.44938876509;22029.66955495669;22053.669194962087;22071.558568828856;7598.278163574451;7811.296983271533;7871.48835325702;7913.762587122395;8086.948696669396;3803.9539126739814;4057.756534607933;4240.98216428055;4277.2087163874785;4576.244919588231;3611.656892595199;3671.6879065279218;3699.574548926854;3733.9066523337133;3741.607131251234;601.2544444888395;677.3463607618573;681.3493114076045;686.0326939016063;700.2472342232121;468.43319583304583;639.3223183425583;663.598522893652;671.3990978074694;673.959562426256;8804.119588041145;9573.808523829624;9646.9035309646;9668.806623867484;9868.496683677335;607.2834055814182;655.5968079630993;678.7827895073627;689.8551304226169;698.441247002396;4433.334999749999;4709.246520556694;4710.387649605529;4718.386609740781;4720.12677654635;100728.49635751774;146089;163697;169632;172816;134907.6509234895;143236;143708;144084;146137;117420;122554.3872280633;124967;126147;128859.35570322275;9033.232175265177;9106;9153.588088535898;9156.359054866361;9215.76960575991;65061;65259;65804.6709766448;67136.6643166781;68199.3180068193;56026.71986640041;82702.58648706718;88101.55949220213;91218;97628;13158;13159.53941612039;13255.867441325849;13304.334783260992;13681.973851960944;14272.038728045693;14577.85422145765;14578.198199099152;14835.629109271922;15013.849861501245;13642.658933526742;13694.52069177574;13741.24423156736;13843.307834608431;14219.431222751387;13314.209160677958;13601.931990339985;13671.111377760413;14282.42870285177;14289;13094.672633183865;13427.19436833754;13582.93208533951;13883.514077007256;13893.166410015432;10192.133668637953;11141.053010493875;11291.3789741565;11497.885021149681;11581.768364632491;9771.169450596495;10530.420826854597;10854.620088296871;11046.392448415414;11629.47667354988 22:25:17 INFO - PROCESS | 1656 | __end_tp_report 22:25:17 INFO - PROCESS | 1656 | __start_cc_report 22:25:17 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,367 22:25:17 INFO - PROCESS | 1656 | __end_cc_report 22:25:17 INFO - PROCESS | 1656 | __startTimestamp1476854717603__endTimestamp 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | ------- Summary: start ------- 22:25:17 INFO - PROCESS | 1656 | Number of tests: 6 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5751.60 Median:5980.85 stddev:689.18 (11.5%) stddev-sans-first:656.19 22:25:17 INFO - PROCESS | 1656 | Values: 2983.5 3406.1 3415.9 3453.1 3455.3 4723.6 5657.1 5725.0 5753.7 5863.7 4803.1 5673.9 5766.9 5825.0 5891.0 5189.1 5227.8 5233.8 5353.5 5372.7 4341.6 5372.4 5395.1 5416.3 5435.7 5241.8 5277.2 5313.1 5319.9 5339.5 5290.6 5326.4 5329.2 5336.3 5357.1 5216.5 5217.8 5256.6 5290.3 5314.5 4762.6 5672.0 5676.2 5700.0 5851.4 4651.9 6137.6 6171.9 6216.9 6234.2 6300.6 6303.1 6309.1 6342.5 6381.1 5952.4 5956.2 5961.4 5967.8 5980.9 6286.4 6294.4 6302.3 6346.5 6372.2 6223.7 6232.7 6241.2 6241.9 6248.5 6139.8 6190.1 6333.7 6363.6 6364.9 6146.7 6219.2 6249.6 6281.4 6295.6 6243.8 6290.7 6293.0 6321.9 6324.8 4962.8 4996.3 5004.9 5010.3 5011.1 4393.5 4408.7 4438.4 4454.8 4529.2 5831.6 5900.1 5910.2 5913.5 5958.9 6297.1 6300.2 6315.9 6328.0 6329.3 4756.0 4770.7 5845.9 5896.6 5939.4 4797.7 5476.5 5483.0 5525.9 5531.7 5292.9 6454.0 6478.4 6523.0 6528.9 6183.5 6222.8 6225.5 6269.3 6286.7 4997.9 6008.0 6333.0 6458.7 6465.8 6160.8 6215.5 6281.6 6293.5 6304.7 5833.9 6169.9 6240.6 6262.2 6339.7 6119.0 6146.4 6189.0 6221.3 6254.6 6162.3 6221.3 6224.9 6260.8 6262.5 5464.4 5488.8 5885.6 5953.5 6036.9 6180.8 6206.3 6211.5 6215.8 6241.2 6048.8 6058.3 6082.6 6093.6 6094.8 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:68304.78 Median:40546.69 stddev:148505.83 (366.3%) stddev-sans-first:148835.10 22:25:17 INFO - PROCESS | 1656 | Values: 471.3 487.2 523.6 524.0 585.2 30014.8 38033.4 40540.4 40553.0 40576.4 41539.0 42436.0 42586.4 42609.8 43244.4 9349.2 9623.6 9627.6 9665.3 9679.5 9528.2 9620.8 9634.5 9649.7 9679.1 9635.4 9644.6 9675.1 9698.0 9701.0 9572.8 9617.1 9618.3 9653.9 9662.3 9375.8 9379.2 9476.1 9489.4 9501.0 39529.0 39800.0 39918.0 39992.0 40051.2 3365.9 3458.7 3488.6 3584.2 4197.8 30086.9 30849.5 31201.4 31266.2 31305.4 3135.3 3254.4 3283.6 3320.3 3322.5 427999.0 428390.0 431466.0 432990.0 432992.0 61418.4 61985.1 62025.4 62089.4 62151.0 51997.8 52070.7 52793.9 64525.0 65453.3 15775.8 15868.9 16014.8 16043.0 16126.4 10758.9 12483.1 12494.4 12579.7 12613.2 15081.4 17554.3 17800.7 18244.9 18250.5 6409.0 6457.7 6542.2 6602.5 6617.2 846.4 896.4 973.4 1087.5 1116.7 28999.3 30088.8 30285.9 30827.2 30851.4 953.5 987.7 1030.4 1087.7 1129.8 15941.4 15954.5 16004.7 16080.7 16245.4 538696.3 656875.7 662808.0 671839.0 671980.0 58950.7 59540.7 59558.0 59717.4 59739.4 1327.9 1367.5 1369.0 1388.9 1397.4 25921.0 29120.9 30008.1 30150.2 30739.8 51653.0 52248.5 52272.7 52432.0 52555.5 560798.0 572974.0 574669.0 575717.0 577117.0 27111.3 27205.0 27525.7 27560.6 27598.9 46702.8 46710.0 47680.0 48328.5 49309.0 33103.7 40020.0 40027.8 40424.0 42372.0 35434.0 39676.0 40266.8 40291.2 40519.0 29121.0 33135.0 37208.0 38077.0 39671.8 27039.9 34508.7 34958.7 35358.0 35476.3 21988.6 22331.0 25957.4 26007.0 26275.3 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:35602.19 Median:2195.21 stddev:98301.33 (4478.0%) stddev-sans-first:98541.48 22:25:17 INFO - PROCESS | 1656 | Values: 361.3 366.4 367.2 376.8 397.9 11370.7 11780.2 11999.3 12011.8 18947.4 17476.6 20719.7 21581.8 21664.9 22086.6 6602.2 6883.5 6919.5 6939.1 6962.6 6461.7 6697.2 6727.5 6783.6 6804.1 6240.8 6374.6 6557.2 6791.0 6801.1 6677.6 6777.3 6788.3 6795.9 6806.4 6388.3 6542.5 6562.1 6632.4 6645.7 22051.8 26759.3 28224.7 28274.6 28441.3 2552.9 2638.6 2763.1 3374.5 3417.8 13189.8 13769.5 14887.3 15840.6 16424.2 1592.3 1926.0 2057.2 2082.6 2084.9 93978.0 107503.0 122187.4 122783.0 124662.0 16206.0 16945.7 17017.6 21634.7 21817.7 29999.9 32753.5 35042.6 35085.3 35143.6 8442.5 8476.6 8641.7 8985.1 9665.6 8418.5 8674.8 8754.7 8805.5 8838.9 8552.6 9822.0 9860.6 9972.5 9978.4 3444.8 3830.1 3906.4 3992.5 4041.7 853.2 884.1 943.9 961.4 984.2 17649.6 19569.2 20557.5 20912.6 21325.4 889.2 915.0 918.7 937.4 1001.6 10536.8 11018.3 11035.9 11039.0 11041.3 575579.0 586737.0 593555.0 593777.0 600264.0 125050.0 127959.4 128975.7 129112.0 130903.7 840.3 934.4 951.2 985.6 987.1 19017.9 19401.5 19416.6 19458.0 19481.0 38047.8 52406.7 52595.5 55105.0 55658.4 54061.6 75429.0 82552.2 83370.0 85257.7 8011.9 8140.8 8143.7 8286.4 8408.0 12221.7 12902.8 13089.5 13217.5 13472.2 8746.9 9228.7 9479.9 11486.8 11532.7 9208.8 9326.0 10641.4 10968.2 11481.4 11243.6 11380.7 11605.8 11613.8 11710.2 7798.5 8066.8 8093.4 8179.3 8204.7 9669.2 9823.5 9992.2 11074.0 11209.6 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5745.75 Median:4809.48 stddev:3263.33 (67.9%) stddev-sans-first:3256.91 22:25:17 INFO - PROCESS | 1656 | Values: 1503.2 1555.2 1754.2 1826.7 1874.9 8098.8 8378.2 8528.4 9141.5 9197.7 8546.6 8836.6 8860.0 8923.7 8980.6 2346.8 2383.8 2394.9 2396.5 2397.6 2359.9 2397.3 2398.1 2403.2 2404.2 2360.2 2362.0 2376.3 2400.7 2404.3 2331.1 2343.3 2343.6 2354.2 2358.1 2333.5 2334.1 2344.3 2347.7 2351.7 8838.7 8864.0 8910.2 8967.3 8967.4 5233.7 5390.3 5394.2 5399.9 5421.3 3560.7 3565.7 3620.3 3632.7 3633.9 1053.4 1053.9 1058.8 1063.7 1070.2 11797.1 11848.2 11929.5 11943.0 12005.7 4589.2 4614.8 4806.2 4812.8 4860.7 9961.8 10040.2 10073.2 10074.4 10101.1 5308.3 5325.1 5329.1 5350.1 5360.5 4437.8 4604.2 4767.2 4785.2 4897.2 3271.4 3370.2 3526.4 4022.8 4179.4 1262.6 1284.8 1309.4 1314.8 1320.3 641.1 741.3 832.8 909.7 941.3 5894.5 5898.1 6078.9 6108.7 6371.4 875.3 886.8 925.2 975.9 1025.6 3696.1 3772.5 3839.3 3941.5 3984.7 10636.0 10927.8 11049.9 11057.9 11100.1 10991.7 10993.6 11002.3 11038.4 11044.8 8359.1 8459.9 8471.7 8476.4 8484.9 4585.2 4915.9 5702.0 5756.1 5876.2 8897.6 9583.0 9970.8 10254.8 10284.7 10042.3 10260.6 10350.2 10429.6 10482.5 6572.7 6805.4 7065.4 7124.5 7227.9 8472.3 8604.1 8642.6 8655.4 8664.8 6162.8 7506.3 7631.5 7632.3 7767.4 7450.7 7457.5 7492.6 7502.4 7681.1 6937.5 7067.6 7083.0 7085.1 7121.1 4939.2 4993.7 5053.7 5128.9 5130.3 4798.8 5002.6 5133.8 5164.8 5229.3 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4437.19 Median:4922.63 stddev:1362.12 (27.7%) stddev-sans-first:1329.66 22:25:17 INFO - PROCESS | 1656 | Values: 276.3 280.8 296.8 310.1 311.3 4531.5 4688.8 5239.5 5379.8 5495.4 4360.7 5196.7 5363.6 5389.7 5418.8 3419.1 3592.0 3750.8 4119.9 4129.7 3435.7 4026.8 4078.2 4147.2 4276.5 4074.2 4099.4 4111.5 4113.2 4124.5 3863.8 4122.6 4122.9 4133.3 4166.4 3370.5 3602.5 3608.6 3614.0 3616.7 4373.8 4984.7 5022.7 5279.7 5314.1 4353.0 4648.3 4739.1 4739.5 4781.5 5127.8 5192.5 5230.2 5693.0 5711.6 2987.9 3584.8 3802.8 3808.7 3876.6 5225.9 5888.6 5976.7 6022.8 6027.7 3882.5 4011.8 4016.6 4018.9 4059.5 3183.7 3300.0 4062.4 4101.9 4111.8 4695.5 4715.5 4732.2 4749.5 4757.3 4950.5 5297.1 5374.6 5755.9 5833.3 1213.6 1305.4 1310.4 1372.6 1398.7 886.0 900.1 929.8 952.3 954.9 5413.1 5425.6 5440.9 5452.3 5467.2 5211.3 5586.5 5864.9 5868.4 5912.4 5357.6 5373.7 5386.3 5389.0 5423.5 2338.8 2615.9 2659.5 2681.7 2701.8 4327.5 4630.4 5044.3 5053.3 5149.9 4748.4 5073.5 5081.3 5119.7 5165.8 4039.4 4577.5 4850.2 4974.5 5076.1 4106.8 4774.3 4913.8 4931.5 4944.1 4012.7 4193.5 4200.5 4305.8 4767.0 4496.5 5068.1 5092.9 5169.5 5179.1 3471.3 3472.9 3592.5 3972.4 4016.8 5905.6 5913.4 5923.5 5927.9 5933.5 5780.9 5808.0 5837.4 5844.5 5853.1 5429.4 5622.6 5713.8 5727.4 5731.2 4818.9 4906.8 5044.4 5289.1 5712.8 5077.6 5251.6 5846.2 5956.3 5958.5 4973.6 5366.8 5550.7 5629.6 5707.9 22:25:17 INFO - PROCESS | 1656 | 22:25:17 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25889.72 Median:3685.63 stddev:42252.12 (1146.4%) stddev-sans-first:42328.50 22:25:17 INFO - PROCESS | 1656 | Values: 752.4 760.8 772.1 793.6 854.7 15080.6 16086.0 16155.8 16199.2 16229.8 14489.9 14618.5 14640.2 14649.5 14664.9 863.3 873.9 902.9 912.5 914.2 902.0 903.0 905.5 905.8 907.5 926.6 941.1 951.2 953.0 953.9 943.2 946.2 947.3 947.5 947.9 679.8 700.5 703.5 703.8 704.7 15319.2 15513.5 15525.2 15563.0 15697.8 13947.3 13990.8 14217.1 14225.9 14283.8 11478.7 11748.1 11795.9 11989.7 12082.0 3705.8 3764.2 3774.5 3781.1 3899.3 119070.4 119120.0 119256.0 119938.0 120416.0 10783.0 10867.7 10885.3 10913.6 11036.6 22007.7 22024.4 22029.7 22053.7 22071.6 7598.3 7811.3 7871.5 7913.8 8086.9 3804.0 4057.8 4241.0 4277.2 4576.2 3611.7 3671.7 3699.6 3733.9 3741.6 601.3 677.3 681.3 686.0 700.2 468.4 639.3 663.6 671.4 674.0 8804.1 9573.8 9646.9 9668.8 9868.5 607.3 655.6 678.8 689.9 698.4 4433.3 4709.2 4710.4 4718.4 4720.1 100728.5 146089.0 163697.0 169632.0 172816.0 134907.7 143236.0 143708.0 144084.0 146137.0 117420.0 122554.4 124967.0 126147.0 128859.4 9033.2 9106.0 9153.6 9156.4 9215.8 65061.0 65259.0 65804.7 67136.7 68199.3 56026.7 82702.6 88101.6 91218.0 97628.0 13158.0 13159.5 13255.9 13304.3 13682.0 14272.0 14577.9 14578.2 14835.6 15013.8 13642.7 13694.5 13741.2 13843.3 14219.4 13314.2 13601.9 13671.1 14282.4 14289.0 13094.7 13427.2 13582.9 13883.5 13893.2 10192.1 11141.1 11291.4 11497.9 11581.8 9771.2 10530.4 10854.6 11046.4 11629.5 22:25:17 INFO - PROCESS | 1656 | -------- Summary: end -------- 22:25:17 INFO - PROCESS | 1656 | 22:25:18 INFO - TEST-INFO | 1656: exit 0 22:25:18 INFO - TEST-OK | dromaeo_css | took 1116583ms 22:25:18 INFO - TEST-START | kraken 22:25:18 INFO - operating with platform_type : mac_ 22:25:18 INFO - Initialising browser for kraken test... 22:25:18 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpr1TJhx/profile http://localhost:49273/getInfo.html) 22:25:21 INFO - PROCESS | 1668 | 2016-10-18 22:25:21.225 plugin-container[1669:9857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 22:25:21 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:21 INFO - PROCESS | 1668 | 2016-10-18 22:25:21.227 plugin-container[1669:9857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 22:25:21 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:21 INFO - PROCESS | 1668 | 2016-10-18 22:25:21.227 plugin-container[1669:9857] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:25:21 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 22:25:21 INFO - PROCESS | 1668 | colorDepth:24 22:25:21 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 22:25:21 INFO - PROCESS | 1668 | __metrics 22:25:21 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 22:25:22 INFO - TEST-INFO | 1668: exit 0 22:25:22 INFO - Browser initialized. 22:25:22 INFO - Running cycle 1/1 for kraken test... 22:25:22 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpr1TJhx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:24 INFO - PROCESS | 1670 | 2016-10-18 22:25:24.537 plugin-container[1671:9970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 22:25:24 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:24 INFO - PROCESS | 1670 | 2016-10-18 22:25:24.538 plugin-container[1671:9970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b23, name = 'com.apple.CFPasteboardClient' 22:25:24 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:24 INFO - PROCESS | 1670 | 2016-10-18 22:25:24.538 plugin-container[1671:9970] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:25:34 INFO - PROCESS | 1670 | RSS: Main: 237121536 22:25:34 INFO - PROCESS | 1670 | 22:25:36 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 22:25:37 INFO - PROCESS | 1670 | RSS: Main: 249614336 22:25:37 INFO - PROCESS | 1670 | 22:25:40 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 22:25:41 INFO - PROCESS | 1670 | RSS: Main: 257167360 22:25:41 INFO - PROCESS | 1670 | 22:25:44 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 22:25:45 INFO - PROCESS | 1670 | RSS: Main: 259981312 22:25:45 INFO - PROCESS | 1670 | 22:25:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 22:25:47 INFO - PROCESS | 1670 | RSS: Main: 261173248 22:25:47 INFO - PROCESS | 1670 | 22:25:49 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:25:50 INFO - PROCESS | 1670 | RSS: Main: 262737920 22:25:50 INFO - PROCESS | 1670 | 22:25:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 22:25:54 INFO - PROCESS | 1670 | RSS: Main: 263421952 22:25:54 INFO - PROCESS | 1670 | 22:25:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 22:25:57 INFO - PROCESS | 1670 | RSS: Main: 263548928 22:25:57 INFO - PROCESS | 1670 | 22:26:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 22:26:01 INFO - PROCESS | 1670 | RSS: Main: 263806976 22:26:01 INFO - PROCESS | 1670 | 22:26:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:26:03 INFO - PROCESS | 1670 | RSS: Main: 263495680 22:26:03 INFO - PROCESS | 1670 | 22:26:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:26:06 INFO - PROCESS | 1670 | RSS: Main: 263548928 22:26:06 INFO - PROCESS | 1670 | 22:26:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:26:08 INFO - PROCESS | 1670 | RSS: Main: 263581696 22:26:08 INFO - PROCESS | 1670 | 22:26:11 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:26:12 INFO - PROCESS | 1670 | RSS: Main: 263995392 22:26:12 INFO - PROCESS | 1670 | 22:26:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:26:16 INFO - PROCESS | 1670 | RSS: Main: 264085504 22:26:16 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 22:26:18 INFO - PROCESS | 1670 | RSS: Main: 265646080 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | __start_tp_report 22:26:18 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 22:26:18 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 22:26:18 INFO - PROCESS | 1670 | |i|pagename|runs| 22:26:18 INFO - PROCESS | 1670 | |0;ai-astar;117;119;118;116;117;119;117;118;120;118 22:26:18 INFO - PROCESS | 1670 | |1;audio-beat-detection;148;115;122;184;124;114;127;144;158;121 22:26:18 INFO - PROCESS | 1670 | |2;audio-dft;196;228;204;291;284;199;196;185;278;272 22:26:18 INFO - PROCESS | 1670 | |3;audio-fft;76;61;61;87;61;60;60;60;87;84 22:26:18 INFO - PROCESS | 1670 | |4;audio-oscillator;71;60;88;105;101;101;113;70;71;87 22:26:18 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;128;91;93;91;92;89;94;90;93;91 22:26:18 INFO - PROCESS | 1670 | |6;imaging-darkroom;101;102;102;101;103;102;101;112;100;100 22:26:18 INFO - PROCESS | 1670 | |7;imaging-desaturate;83;88;87;86;90;136;144;92;100;90 22:26:18 INFO - PROCESS | 1670 | |8;json-parse-financial;57;58;57;58;58;58;58;58;59;59 22:26:18 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;65;109;103;114;71;68;71;72;72;73 22:26:18 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;80;95;114;109;113;92;90;89;89 22:26:18 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;144;149;155;150;170;166;162;147;201;163 22:26:18 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;178;178;175;245;225;178;216;248;188;205 22:26:18 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;71;70;71;71;71;70;78;84;94;88 22:26:18 INFO - PROCESS | 1670 | __end_tp_report 22:26:18 INFO - PROCESS | 1670 | __start_cc_report 22:26:18 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,952 22:26:18 INFO - PROCESS | 1670 | __end_cc_report 22:26:18 INFO - PROCESS | 1670 | __startTimestamp1476854778672__endTimestamp 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | ------- Summary: start ------- 22:26:18 INFO - PROCESS | 1670 | Number of tests: 14 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:117.90 Median:118.00 stddev:1.20 (1.0%) stddev-sans-first:1.22 22:26:18 INFO - PROCESS | 1670 | Values: 117.0 119.0 118.0 116.0 117.0 119.0 117.0 118.0 120.0 118.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:135.50 stddev:22.52 (16.6%) stddev-sans-first:23.45 22:26:18 INFO - PROCESS | 1670 | Values: 148.0 115.0 122.0 184.0 124.0 114.0 127.0 144.0 158.0 121.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:233.30 Median:250.00 stddev:42.91 (17.2%) stddev-sans-first:43.34 22:26:18 INFO - PROCESS | 1670 | Values: 196.0 228.0 204.0 291.0 284.0 199.0 196.0 185.0 278.0 272.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:69.70 Median:68.50 stddev:12.26 (17.9%) stddev-sans-first:12.79 22:26:18 INFO - PROCESS | 1670 | Values: 76.0 61.0 61.0 87.0 61.0 60.0 60.0 60.0 87.0 84.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:70.50 stddev:18.02 (25.6%) stddev-sans-first:18.19 22:26:18 INFO - PROCESS | 1670 | Values: 71.0 60.0 88.0 105.0 101.0 101.0 113.0 70.0 71.0 87.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:91.50 stddev:11.62 (12.7%) stddev-sans-first:1.59 22:26:18 INFO - PROCESS | 1670 | Values: 128.0 91.0 93.0 91.0 92.0 89.0 94.0 90.0 93.0 91.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:102.40 Median:102.00 stddev:3.50 (3.4%) stddev-sans-first:3.68 22:26:18 INFO - PROCESS | 1670 | Values: 101.0 102.0 102.0 101.0 103.0 102.0 101.0 112.0 100.0 100.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:87.50 stddev:21.84 (25.0%) stddev-sans-first:22.32 22:26:18 INFO - PROCESS | 1670 | Values: 83.0 88.0 87.0 86.0 90.0 136.0 144.0 92.0 100.0 90.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 22:26:18 INFO - PROCESS | 1670 | Values: 57.0 58.0 57.0 58.0 58.0 58.0 58.0 58.0 59.0 59.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:81.80 Median:71.00 stddev:18.86 (26.6%) stddev-sans-first:19.00 22:26:18 INFO - PROCESS | 1670 | Values: 65.0 109.0 103.0 114.0 71.0 68.0 71.0 72.0 72.0 73.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:95.70 Median:89.00 stddev:11.96 (13.4%) stddev-sans-first:12.16 22:26:18 INFO - PROCESS | 1670 | Values: 86.0 80.0 95.0 114.0 109.0 113.0 92.0 90.0 89.0 89.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:160.70 Median:162.50 stddev:16.65 (10.2%) stddev-sans-first:16.53 22:26:18 INFO - PROCESS | 1670 | Values: 144.0 149.0 155.0 150.0 170.0 166.0 162.0 147.0 201.0 163.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.60 Median:210.50 stddev:28.53 (13.6%) stddev-sans-first:28.72 22:26:18 INFO - PROCESS | 1670 | Values: 178.0 178.0 175.0 245.0 225.0 178.0 216.0 248.0 188.0 205.0 22:26:18 INFO - PROCESS | 1670 | 22:26:18 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:74.50 stddev:8.83 (11.9%) stddev-sans-first:9.11 22:26:18 INFO - PROCESS | 1670 | Values: 71.0 70.0 71.0 71.0 71.0 70.0 78.0 84.0 94.0 88.0 22:26:18 INFO - PROCESS | 1670 | -------- Summary: end -------- 22:26:18 INFO - PROCESS | 1670 | 22:26:19 INFO - TEST-INFO | 1670: exit 0 22:26:19 INFO - TEST-OK | kraken | took 61250ms 22:26:19 INFO - SUITE-END | took 1177s 22:26:19 INFO - Completed test suite (00:19:38) 22:26:19 INFO - javascript benchmark 22:26:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7481.180940243637, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2983.5226363781794, 3406.0973841931877, 3415.9487607685874, 3453.08493249289, 3455.343484737898, 4723.57487826096, 5657.094864821629, 5724.96950548901, 5753.683547404891, 5863.6806718488315, 4803.135435621587, 5673.8581535461635, 5766.884662306762, 5825.0, 5890.970545147279, 5189.143791274435, 5227.764750586214, 5233.764480598363, 5353.518183363496, 5372.7045012524295, 4341.565843415665, 5372.435894231108, 5395.109806881879, 5416.322959630046, 5435.728213589325, 5241.816536421207, 5277.1820367842665, 5313.0967735485065, 5319.946800531984, 5339.5461385782255, 5290.629655924048, 5326.387465441485, 5329.173978033411, 5336.252924590561, 5357.142857142839, 5216.478352164791, 5217.791288348461, 5256.579473642099, 5290.338707661543, 5314.5216930476445, 4762.595179409755, 5672.0, 5676.233708449347, 5700.030994730906, 5851.356350801409, 4651.930221046687, 6137.631742095444, 6171.87656246877, 6216.87566248677, 6234.158388617524, 6300.59046161998, 6303.086052522404, 6309.116723658692, 6342.460890824285, 6381.074744162071, 5952.375000624872, 5956.195913551658, 5961.4038596140035, 5967.791127310524, 5980.850478738066, 6286.4342209201395, 6294.402031806971, 6302.338254483305, 6346.524010699216, 6372.171617689671, 6223.688815559204, 6232.657203853832, 6241.18864547615, 6241.937580624226, 6248.5313601480075, 6139.768135342631, 6190.133381326618, 6333.714982825784, 6363.5863668861375, 6364.904526432108, 6146.7091910699255, 6219.191505104308, 6249.593776404519, 6281.403266689657, 6295.5907865988565, 6243.781467648701, 6290.685465726695, 6293.0, 6321.936780632227, 6324.778632747832, 4962.801487940442, 4996.275540046673, 5004.924926126111, 5010.2735103409805, 5011.1481048221885, 4393.51671316155, 4408.713433626804, 4438.423005009393, 4454.844080457168, 4529.162105010583, 5831.620944638585, 5900.1149827526215, 5910.202122713378, 5913.526917846562, 5958.85102872423, 6297.055441683692, 6300.21247344082, 6315.873682526416, 6327.968360158262, 6329.2721337045905, 4755.953690188153, 4770.6660533763, 5845.853853653608, 5896.587238893322, 5939.3763654816485, 4797.688150270221, 5476.507114359647, 5483.0, 5525.917111243334, 5531.668099914018, 5292.867678308073, 6453.967730161319, 6478.352164783578, 6522.967385163044, 6528.869422611426, 6183.536234782499, 6222.782202623068, 6225.533085018732, 6269.310375858743, 6286.748530058747, 4997.875053123701, 6007.969960150172, 6332.968335158479, 6458.67706614658, 6465.77369792055, 6160.845978850564, 6215.471684906834, 6281.59169653971, 6293.496520278275, 6304.65324407162, 5833.883322333614, 6169.876602467836, 6240.625562466085, 6262.1546091278915, 6339.651319177489, 6119.015143218386, 6146.385361463817, 6188.9690551546955, 6221.3156552780065, 6254.6247225166635, 6162.26052873658, 6221.2845522765465, 6224.875502490016, 6260.843478913063, 6262.530310226843, 5464.371597266365, 5488.780448782004, 5885.646861188342, 5953.523718102455, 6036.939630603638, 6180.783672571439, 6206.286277078013, 6211.534134939988, 6215.81352559424, 6241.157443745036, 6048.8357307479255, 6058.27300723916, 6082.635041897501, 6093.573449858644, 6094.786682466092], "value": 5709.386357470509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.3071784476819, 487.18396685551704, 523.5968304405608, 523.9764210610522, 585.2128886647457, 30014.84992575034, 38033.42949855755, 40540.391894121574, 40552.98617534563, 40576.39135412967, 41538.96152596179, 42436.0, 42586.361204581954, 42609.78695106528, 43244.35133472992, 9349.205317548, 9623.56693948774, 9627.614895404175, 9665.275104367165, 9679.516024198761, 9528.23774098074, 9620.807583848318, 9634.47010414427, 9649.662261820838, 9679.128878400943, 9635.373700709466, 9644.614215431375, 9675.080867317594, 9698.0, 9700.951495242514, 9572.760680982961, 9617.134457898752, 9618.326717129803, 9653.855192172123, 9662.275329350327, 9375.76560585984, 9379.202767764747, 9476.147146756824, 9489.430634161972, 9501.0, 39529.0, 39800.0, 39918.0, 39992.0, 40051.198976020605, 3365.9158521036925, 3458.706009989154, 3488.5639295088113, 3584.211473475831, 4197.832086716528, 30086.946956856405, 30849.537256941163, 31201.37597248065, 31266.21834454134, 31305.37389252225, 3135.263213144909, 3254.3816674831705, 3283.6387997320276, 3320.3193345364384, 3322.5016247562817, 427999.0, 428390.0, 431466.0, 432990.0, 432992.0, 61418.38581614216, 61985.07022394668, 62025.37974620196, 62089.379106209264, 62151.0, 51997.840172786084, 52070.73964630228, 52793.94412111736, 64525.0, 65453.345466545674, 15775.763363549557, 15868.920655396649, 16014.759778603331, 16043.0, 16126.354945802037, 10758.892411075945, 12483.12618116723, 12494.375281235902, 12579.685507862194, 12613.243205407522, 15081.396744130112, 17554.29782808699, 17800.732989005177, 18244.908775456304, 18250.452486425427, 6409.0, 6457.741690332428, 6542.24764152119, 6602.533669823381, 6617.239017512951, 846.3736834742247, 896.3501461440403, 973.4110862927968, 1087.4508373271447, 1116.6761639124563, 28999.275018124295, 30088.84955575208, 30285.93999210017, 30827.229319267197, 30851.382972340427, 953.4755884263766, 987.7283746969497, 1030.3611760708409, 1087.722630729159, 1129.8361737548173, 15941.36234550605, 15954.521364358856, 16004.679906401807, 16080.67838643244, 16245.350185992427, 538696.3065184649, 656875.7156214188, 662808.0, 671839.0, 671980.0, 58950.705246473495, 59540.70229648824, 59558.0, 59717.402825971185, 59739.402605974254, 1327.9004074694244, 1367.4940272099386, 1368.9589312320647, 1388.9166650001032, 1397.3991183790865, 25921.0, 29120.854395727885, 30008.099757007323, 30150.2462438432, 30739.770409184275, 51653.0, 52248.47751522436, 52272.738636306574, 52432.0, 52555.47444525659, 560798.0, 572974.0, 574669.0, 575717.0, 577117.0, 27111.322216944733, 27205.0, 27525.72474275312, 27560.58659120155, 27598.89604415801, 46702.76648616871, 46710.0, 47680.0, 48328.5167148324, 49309.013819722684, 33103.66896331006, 40020.0, 40027.79986100051, 40423.98940026523, 42372.0, 35434.0, 39676.0, 40266.798666006485, 40291.19417611573, 40519.0, 29121.0, 33135.0, 37208.0, 38077.0, 39671.801640992766, 27039.86480067587, 34508.65491345155, 34958.65041349554, 35358.0, 35476.290474190886, 21988.560228795654, 22331.0, 25957.351066223495, 26007.0, 26275.343116422242], "value": 19756.22982390424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.27023412706336, 366.4448360733488, 367.15920541958917, 376.8341929550996, 397.8985358733523, 11370.715732106692, 11780.234284771484, 11999.340036298001, 12011.819822702644, 18947.431577052692, 17476.563085922826, 20719.689204661903, 21581.78418215821, 21664.8916755416, 22086.558268834615, 6602.1747281589805, 6883.518153729241, 6919.515633905628, 6939.0979172707475, 6962.617056061915, 6461.741530338781, 6697.229818570853, 6727.4618030557685, 6783.6269005204695, 6804.149481314836, 6240.7830473057775, 6374.649394283312, 6557.180352455943, 6791.049253104569, 6801.081853949728, 6677.632730199837, 6777.2545020047755, 6788.287229840845, 6795.898061529081, 6806.353396427331, 6388.329225431309, 6542.544976260113, 6562.114114594517, 6632.436242919358, 6645.667716614198, 22051.779482205133, 26759.331016724544, 28224.717752822413, 28274.575881361798, 28441.288967775763, 2552.9361765955814, 2638.604209368591, 2763.101991852636, 3374.4769560718128, 3417.829108544563, 13189.802152967713, 13769.518066867611, 14887.255637218204, 15840.603984900354, 16424.17879106052, 1592.283472437408, 1925.951851203717, 2057.238821636004, 2082.562661841015, 2084.934377962963, 93978.0, 107503.0, 122187.38906305411, 122783.0, 124662.0, 16206.0, 16945.661086778196, 17017.553507951714, 21634.675479867816, 21817.67273490899, 29999.850000749855, 32753.50869736908, 35042.64957350445, 35085.29829403398, 35143.64856351455, 8442.493450392996, 8476.618552165168, 8641.654333826702, 8985.101489850962, 9665.613375464902, 8418.536980466133, 8674.78313042179, 8754.737357879274, 8805.471671699719, 8838.86741698875, 8552.572371381406, 9821.950890245504, 9860.605575777032, 9972.451515166591, 9978.401295922124, 3444.844981975817, 3830.138218900725, 3906.3554513505524, 3992.5208974923194, 4041.6968727345566, 853.1979938857454, 884.0893879304327, 943.8867335919733, 961.3847137831793, 984.1683777208248, 17649.647007060044, 19569.217231310588, 20557.486062848548, 20912.581748364948, 21325.36023919285, 889.1908363389284, 914.9679761208325, 918.6995495696484, 937.4047479850344, 1001.6193846338344, 10536.841947370796, 11018.283811552223, 11035.944820276009, 11039.0, 11041.33751974884, 575579.0, 586737.0, 593555.0, 593777.0, 600264.0, 125050.0, 127959.36020319839, 128975.71024289825, 129112.0, 130903.69096309105, 840.306746933782, 934.3552948465673, 951.2247518272724, 985.5515740338129, 987.1313244344931, 19017.904910475914, 19401.51496212606, 19416.611667766283, 19458.027098645296, 19480.98044803001, 38047.809760951015, 52406.73796630993, 52595.474045259056, 55105.0, 55658.44341556533, 54061.64845878885, 75429.0, 82552.17447825686, 83370.0, 85257.72113418179, 8011.919880801117, 8140.796480088045, 8143.6742530298125, 8286.41995060364, 8407.95796021016, 12221.69445763863, 12902.80645790295, 13089.476420943056, 13217.471301147847, 13472.191668499921, 8746.868796968178, 9228.723138305862, 9479.905200947902, 11486.793886641783, 11532.731399545875, 9208.769780755267, 9326.0, 10641.414722190353, 10968.23222374426, 11481.425928703364, 11243.60647377338, 11380.715482113013, 11605.825912611486, 11613.825792612948, 11710.238834475731, 7798.454108212371, 8066.7579972600915, 8093.433459657767, 8179.263866252066, 8204.671813127408, 9669.178119859891, 9823.459709715748, 9992.15066719337, 11074.003339699466, 11209.607663732057], "value": 10550.205039683528, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.2483758120936, 1555.152441919154, 1754.184304298501, 1826.6620675175102, 1874.8500119990401, 8098.797530061752, 8378.245957863792, 8528.445651032685, 9141.497217653026, 9197.678081267157, 8546.615402306896, 8836.602352894119, 8859.95570022149, 8923.687670931518, 8980.640774369018, 2346.8474549154334, 2383.84505007174, 2394.8563086214795, 2396.532676128147, 2397.568437681217, 2359.917402890899, 2397.29279862441, 2398.1486572266767, 2403.170906037414, 2404.230646193219, 2360.1503458754833, 2362.0, 2376.3227480168157, 2400.667629465646, 2404.2546810488802, 2331.0908745589236, 2343.2852979841227, 2343.5781559319316, 2354.1878052072007, 2358.068562917655, 2333.451638864882, 2334.1480359668767, 2344.3318654183645, 2347.718273807137, 2351.729551101611, 8838.690645827364, 8864.0, 8910.153535414125, 8967.327450441244, 8967.372283940127, 5233.659812112239, 5390.326209223847, 5394.217838413408, 5399.946000539983, 5421.349438067417, 3560.7151427885706, 3565.6969157621634, 3620.312140693286, 3632.7275454340765, 3633.8909832705062, 1053.4206186597416, 1053.947302634865, 1058.7935352606246, 1063.7393838509538, 1070.1973519860105, 11797.115216358721, 11848.229865058744, 11929.463174157183, 11943.0, 12005.699857503545, 4589.2198326284615, 4614.792334344963, 4806.182948898696, 4812.783424745895, 4860.708357498562, 9961.750956226007, 10040.196784257241, 10073.194144468425, 10074.445905475122, 10101.141402980682, 5308.309919710413, 5325.068113080196, 5329.094054010828, 5350.117230656976, 5360.544353729977, 4437.800298986554, 4604.171249175113, 4767.189577771787, 4785.186518291899, 4897.240927656219, 3271.362084393568, 3370.1574606348413, 3526.3652542542077, 4022.778747168934, 4179.435776170239, 1262.6085913366885, 1284.7623189709748, 1309.4434865182259, 1314.7764879970428, 1320.2672516753103, 641.1344684675651, 741.2735519191223, 832.7751507093172, 909.7407238936871, 941.2517048946104, 5894.498967587803, 5898.1152827076285, 6078.878422431615, 6108.725107370179, 6371.426571608485, 875.312879389676, 886.7650072730731, 925.1950802801606, 975.9365641233297, 1025.600015993763, 3696.1129328960856, 3772.4718539404507, 3839.2897313997, 3941.4876066111224, 3984.6613037891525, 10636.042756151983, 10927.836082458769, 11049.889501105046, 11057.889421105687, 11100.056495197998, 10991.670249892515, 10993.560257589766, 11002.284851484628, 11038.44807759625, 11044.834327485094, 8359.122292159236, 8459.900212972398, 8471.661133554711, 8476.406651534333, 8484.872726909101, 4585.220512512814, 4915.901681966412, 5702.0, 5756.107803290497, 5876.206712093813, 8897.555122243992, 9582.993785652407, 9970.800583988135, 10254.846177307496, 10284.691459256233, 10042.347247428894, 10260.640877569249, 10350.171986241234, 10429.634962776267, 10482.52828622729, 6572.671366431564, 6805.3875151236625, 7065.399441047569, 7124.501284910007, 7227.927720722936, 8472.279856212044, 8604.096569860074, 8642.611082501428, 8655.394122411371, 8664.78338041554, 6162.815115546541, 7506.3244308012545, 7631.54210747357, 7632.313091821763, 7767.417443691633, 7450.664720087718, 7457.515261507984, 7492.587907665131, 7502.399808015237, 7681.116671582839, 6937.479689023243, 7067.5759454430845, 7082.964585177041, 7085.14978202619, 7121.109861267341, 4939.234418665113, 4993.650444468853, 5053.69677819332, 5128.871778205575, 5130.333056702605, 4798.832040878552, 5002.649814512949, 5133.820316288912, 5164.819231327036, 5229.320188375486], "value": 4597.513452371038, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.2720232188184, 280.8371144736053, 296.8426733831068, 310.1223537389189, 311.33529913634385, 4531.478879928805, 4688.812447502104, 5239.532930779388, 5379.825902717396, 5495.395506494282, 4360.738355698661, 5196.688198708081, 5363.6245462817615, 5389.703566303851, 5418.775828382116, 3419.111031131899, 3592.0, 3750.812459377034, 4119.876403707894, 4129.690273229505, 3435.707964822993, 4026.778527181004, 4078.2047500737367, 4147.221361687676, 4276.5295817460055, 4074.1851629674034, 4099.446574712406, 4111.5477297497255, 4113.218488487178, 4124.463819703449, 3863.821534431997, 4122.567130451304, 4122.87671848998, 4133.2973394522705, 4166.375043743433, 3370.4832825228746, 3602.5316708827945, 3608.618311914124, 3613.9819300903328, 3616.7287453440836, 4373.775342903992, 4984.651074424755, 5022.673526220784, 5279.656822306538, 5314.0700377433795, 4352.998810273655, 4648.349231107648, 4739.056986635497, 4739.502352252998, 4781.497942716034, 5127.820526281562, 5192.454792246797, 5230.155835460933, 5693.035902613592, 5711.571632127566, 2987.850607469618, 3584.7849129052343, 3802.8288727007352, 3808.6762625176893, 3876.5541962674088, 5225.895482090375, 5888.5877988540815, 5976.671283079429, 6022.825549017944, 6027.698615069273, 3882.475865758143, 4011.7592944423427, 4016.618421250035, 4018.8593399230886, 4059.4519739834745, 3183.71346578809, 3300.0, 4062.370332598452, 4101.876943691694, 4111.759091295251, 4695.5304469552775, 4715.504871988392, 4732.1718699227495, 4749.45642666128, 4757.26262429324, 4950.480199578992, 5297.099493086108, 5374.570034397317, 5755.884882302415, 5833.308340581184, 1213.5934461955344, 1305.3668970549277, 1310.4430616987743, 1372.6225288045866, 1398.7482253194319, 885.9899714325674, 900.1268769293774, 929.7954450020984, 952.2619969523536, 954.9045095490395, 5413.079776438015, 5425.593080518976, 5440.863978400572, 5452.345718513804, 5467.234587157801, 5211.348581427322, 5586.525145362609, 5864.914990726644, 5868.442497962772, 5912.408759124053, 5357.571394288526, 5373.650712703662, 5386.303314455934, 5389.02997460453, 5423.484768946943, 2338.777816107467, 2615.882285297166, 2659.5372545100117, 2681.6781986161395, 2701.783857291412, 4327.5239723631, 4630.398048253705, 5044.268581055726, 5053.3071421074355, 5149.922751158736, 4748.406449193851, 5073.492650734897, 5081.339425874687, 5119.744012799345, 5165.767540460688, 4039.444813746729, 4577.5052596335745, 4850.235365841507, 4974.452810190876, 5076.05085042081, 4106.7946602670345, 4774.30772537987, 4913.7543122843, 4931.50684931504, 4944.145945270524, 4012.678985681197, 4193.454850869368, 4200.516940551754, 4305.763183024964, 4766.951270720436, 4496.505384407647, 5068.0741318298815, 5092.949070509248, 5169.457206993212, 5179.067767801832, 3471.340445315382, 3472.8610855565494, 3592.4611308303965, 3972.4041393790008, 4016.8393264269102, 5905.641702408556, 5913.438223368773, 5923.526117910643, 5927.940720592739, 5933.525317974638, 5780.855478613068, 5807.970960145172, 5837.357890632112, 5844.503217226414, 5853.1220316952795, 5429.40276569585, 5622.578306626938, 5713.800016999385, 5727.365411910401, 5731.168980497888, 4818.927716084191, 4906.822362632871, 5044.419891712353, 5289.090999949935, 5712.800571779978, 5077.619178561697, 5251.632385732962, 5846.210761547137, 5956.344802071855, 5958.523318134626, 4973.6269779765935, 5366.758495867617, 5550.666959982414, 5629.634073785191, 5707.914381284201], "value": 4006.3593384093115, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.4018405367732, 760.7983884270669, 772.1236396689759, 793.638894303092, 854.7179430787838, 15080.622984425394, 16086.034837909734, 16155.757663635055, 16199.190040497971, 16229.83770162298, 14489.92755036226, 14618.48835290765, 14640.194789286585, 14649.461806510297, 14664.926675366634, 863.3266052479077, 873.8645509945936, 902.8645703144515, 912.4890061565511, 914.1681070226095, 901.9897714559702, 903.0382642485764, 905.483874191711, 905.7961958559338, 907.4918045894287, 926.5645146781002, 941.0965473145789, 951.224751827262, 952.9803110671584, 953.8998405167462, 943.1935694980796, 946.1815529566944, 947.3463310315894, 947.4552132523827, 947.8909925358535, 679.8436359637311, 700.5411455496658, 703.5216053083901, 703.7853454696316, 704.6547191875944, 15319.234038298151, 15513.534593962198, 15525.223738813127, 15563.0, 15697.843021569752, 13947.302634868318, 13990.790138147935, 14217.146971181763, 14225.928870355581, 14283.78574321386, 11478.655640330804, 11748.118891083202, 11795.882041179564, 11989.700257493545, 12082.0, 3705.818393053098, 3764.247150569897, 3774.5281839770028, 3781.149241420686, 3899.298126337258, 119070.4046479762, 119120.0, 119256.0, 119938.0, 120416.0, 10782.975617316342, 10867.728306792234, 10885.346879187275, 10913.618023369145, 11036.613718519813, 22007.708496842963, 22024.44938876509, 22029.66955495669, 22053.669194962087, 22071.558568828856, 7598.278163574451, 7811.296983271533, 7871.48835325702, 7913.762587122395, 8086.948696669396, 3803.9539126739814, 4057.756534607933, 4240.98216428055, 4277.2087163874785, 4576.244919588231, 3611.656892595199, 3671.6879065279218, 3699.574548926854, 3733.9066523337133, 3741.607131251234, 601.2544444888395, 677.3463607618573, 681.3493114076045, 686.0326939016063, 700.2472342232121, 468.43319583304583, 639.3223183425583, 663.598522893652, 671.3990978074694, 673.959562426256, 8804.119588041145, 9573.808523829624, 9646.9035309646, 9668.806623867484, 9868.496683677335, 607.2834055814182, 655.5968079630993, 678.7827895073627, 689.8551304226169, 698.441247002396, 4433.334999749999, 4709.246520556694, 4710.387649605529, 4718.386609740781, 4720.12677654635, 100728.49635751774, 146089.0, 163697.0, 169632.0, 172816.0, 134907.6509234895, 143236.0, 143708.0, 144084.0, 146137.0, 117420.0, 122554.3872280633, 124967.0, 126147.0, 128859.35570322275, 9033.232175265177, 9106.0, 9153.588088535898, 9156.359054866361, 9215.76960575991, 65061.0, 65259.0, 65804.6709766448, 67136.6643166781, 68199.3180068193, 56026.71986640041, 82702.58648706718, 88101.55949220213, 91218.0, 97628.0, 13158.0, 13159.53941612039, 13255.867441325849, 13304.334783260992, 13681.973851960944, 14272.038728045693, 14577.85422145765, 14578.198199099152, 14835.629109271922, 15013.849861501245, 13642.658933526742, 13694.52069177574, 13741.24423156736, 13843.307834608431, 14219.431222751387, 13314.209160677958, 13601.931990339985, 13671.111377760413, 14282.42870285177, 14289.0, 13094.672633183865, 13427.19436833754, 13582.93208533951, 13883.514077007256, 13893.166410015432, 10192.133668637953, 11141.053010493875, 11291.3789741565, 11497.885021149681, 11581.768364632491, 9771.169450596495, 10530.420826854597, 10854.620088296871, 11046.392448415414, 11629.47667354988], "value": 7997.340390336079, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1617.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 118.0, 116.0, 117.0, 119.0, 117.0, 118.0, 120.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 122.0, 184.0, 124.0, 114.0, 127.0, 144.0, 158.0, 121.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 228.0, 204.0, 291.0, 284.0, 199.0, 196.0, 185.0, 278.0, 272.0], "value": 233.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 61.0, 61.0, 87.0, 61.0, 60.0, 60.0, 60.0, 87.0, 84.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 60.0, 88.0, 105.0, 101.0, 101.0, 113.0, 70.0, 71.0, 87.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 91.0, 93.0, 91.0, 92.0, 89.0, 94.0, 90.0, 93.0, 91.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 102.0, 101.0, 103.0, 102.0, 101.0, 112.0, 100.0, 100.0], "value": 102.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 87.0, 86.0, 90.0, 136.0, 144.0, 92.0, 100.0, 90.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 58.0, 58.0, 58.0, 58.0, 58.0, 59.0, 59.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 109.0, 103.0, 114.0, 71.0, 68.0, 71.0, 72.0, 72.0, 73.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 95.0, 114.0, 109.0, 113.0, 92.0, 90.0, 89.0, 89.0], "value": 95.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 149.0, 155.0, 150.0, 170.0, 166.0, 162.0, 147.0, 201.0, 163.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 178.0, 175.0, 245.0, 225.0, 178.0, 216.0, 248.0, 188.0, 205.0], "value": 203.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 71.0, 71.0, 71.0, 70.0, 78.0, 84.0, 94.0, 88.0], "value": 76.8, "unit": "score"}]}]} 22:26:19 INFO - Return code: 0 22:26:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:26:19 ERROR - # TBPL SUCCESS # 22:26:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:26:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:26:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:26:19 INFO - 2016-10-18 22:26:19.967 screenresolution[1675:10353] starting screenresolution argv=screenresolution get 22:26:19 INFO - 2016-10-18 22:26:19.970 screenresolution[1675:10353] Display 0: 1600x1200x32@60 22:26:19 INFO - 2016-10-18 22:26:19.977 screenresolution[1676:10356] starting screenresolution argv=screenresolution list 22:26:19 INFO - Available Modes on Display 0 22:26:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:26:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:26:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:26:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:26:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:26:20 INFO - Intel Iris: 22:26:20 INFO - Chipset Model: Intel Iris 22:26:20 INFO - Type: GPU 22:26:20 INFO - Bus: Built-In 22:26:20 INFO - VRAM (Dynamic, Max): 1536 MB 22:26:20 INFO - Vendor: Intel (0x8086) 22:26:20 INFO - Device ID: 0x0a2e 22:26:20 INFO - Revision ID: 0x0009 22:26:20 INFO - Displays: 22:26:20 INFO - Display: 22:26:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:26:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:26:20 INFO - Display Serial Number: 1600x1200 60 22:26:20 INFO - Main Display: Yes 22:26:20 INFO - Mirror: Off 22:26:20 INFO - Online: Yes 22:26:20 INFO - Rotation: Supported 22:26:20 INFO - Return code: 0 22:26:20 INFO - Running post-action listener: _resource_record_post_action 22:26:20 INFO - [mozharness: 2016-10-19 05:26:20.214380Z] Finished run-tests step (success) 22:26:20 INFO - Running post-run listener: _resource_record_post_run 22:26:20 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38357504; Write bytes: 712131072; Read time: 260; Write time: 2034 22:26:20 INFO - TinderboxPrint: CPU usage
54.5% 22:26:20 INFO - TinderboxPrint: I/O read bytes / time
38,357,504 / 260 22:26:20 INFO - TinderboxPrint: I/O write bytes / time
712,131,072 / 2,034 22:26:20 INFO - TinderboxPrint: CPU idle
2,206.7 (45.5%) 22:26:20 INFO - TinderboxPrint: CPU system
983.7 (20.3%) 22:26:20 INFO - TinderboxPrint: CPU user
1,663.2 (34.3%) 22:26:20 INFO - TinderboxPrint: Swap in / out
445,394,944 / 0 22:26:20 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395411968; Write bytes: 390419968; Read time: 29214; Write time: 814 22:26:20 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 36366848; Write bytes: 301321216; Read time: 242; Write time: 1166 22:26:20 INFO - Running post-run listener: _upload_blobber_files 22:26:20 INFO - Blob upload gear active. 22:26:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:26:20 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 22:26:20 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'] 22:26:20 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 22:26:21 INFO - (blobuploader) - INFO - Open directory for files ... 22:26:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:26:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:26:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:22 INFO - (blobuploader) - INFO - Done attempting. 22:26:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:26:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:23 INFO - (blobuploader) - INFO - Done attempting. 22:26:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:26:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:26:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:25 INFO - (blobuploader) - INFO - Done attempting. 22:26:25 INFO - (blobuploader) - INFO - Iteration through files over. 22:26:25 INFO - Return code: 0 22:26:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:26:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:26:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a1453585c4ff13509963332807e12e837f386dfb4b685efdc617fdcd327ae3b11914467b7e8deb909bdf2d82ff1efea114c9b7c57dc1261c30f60bce759b513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1014be8b37f58532135bcb35dbd060a79350b1cbdadfb320b1afad11d596e572d9a1e4b84037d06a3f55dbb10d86758280b3c38b7bd2e1438799ce3ee0d248e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1bc84c90cccf50eda982eccad91f5b9312436c60ded347603f163c46427135579acb06d5108375979315263808a482cec41593ee9708a3fbb4f238f6f0c35e"} 22:26:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:26:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:26:25 INFO - Contents: 22:26:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a1453585c4ff13509963332807e12e837f386dfb4b685efdc617fdcd327ae3b11914467b7e8deb909bdf2d82ff1efea114c9b7c57dc1261c30f60bce759b513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1014be8b37f58532135bcb35dbd060a79350b1cbdadfb320b1afad11d596e572d9a1e4b84037d06a3f55dbb10d86758280b3c38b7bd2e1438799ce3ee0d248e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1bc84c90cccf50eda982eccad91f5b9312436c60ded347603f163c46427135579acb06d5108375979315263808a482cec41593ee9708a3fbb4f238f6f0c35e"} 22:26:25 INFO - Running post-run listener: copy_logs_to_upload_dir 22:26:25 INFO - Copying logs to upload dir... 22:26:25 INFO - mkdir: /builds/slave/test/build/upload/logs 22:26:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.532199 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 22:26:25.332165) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 22:26:25.335834) ========= 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.iQi12uF0J4/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.xxb81xSE2W/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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/5a1453585c4ff13509963332807e12e837f386dfb4b685efdc617fdcd327ae3b11914467b7e8deb909bdf2d82ff1efea114c9b7c57dc1261c30f60bce759b513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1014be8b37f58532135bcb35dbd060a79350b1cbdadfb320b1afad11d596e572d9a1e4b84037d06a3f55dbb10d86758280b3c38b7bd2e1438799ce3ee0d248e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1bc84c90cccf50eda982eccad91f5b9312436c60ded347603f163c46427135579acb06d5108375979315263808a482cec41593ee9708a3fbb4f238f6f0c35e"} build_url:https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010830 build_url: 'https://queue.taskcluster.net/v1/task/QKLMkwcqSGivjUlX-_d6ug/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/5a1453585c4ff13509963332807e12e837f386dfb4b685efdc617fdcd327ae3b11914467b7e8deb909bdf2d82ff1efea114c9b7c57dc1261c30f60bce759b513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1014be8b37f58532135bcb35dbd060a79350b1cbdadfb320b1afad11d596e572d9a1e4b84037d06a3f55dbb10d86758280b3c38b7bd2e1438799ce3ee0d248e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1bc84c90cccf50eda982eccad91f5b9312436c60ded347603f163c46427135579acb06d5108375979315263808a482cec41593ee9708a3fbb4f238f6f0c35e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 22:26:25.369968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:26:25.370425) ========= 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.iQi12uF0J4/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.xxb81xSE2W/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005170 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 22:26:25.421013) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 22:26:25.421730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 22:26:25.422387) ========= ========= Total master_lag: 0.19 =========