builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0123 starttime: 1478438472.13 results: success (0) buildid: 20161106030203 builduid: ac4cdb2c2b20475288886ee5204f7660 revision: c44c01dfd264370c1558b747525d220a9a89b51c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.133857) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.134259) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.134556) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.159743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.160011) ========= 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.hoHCbT9BxF/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.cDX16I2geQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-06 05:21:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.50M=0.001s 2016-11-06 05:21:12 (9.50 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.604633 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.786892) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.787211) ========= 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.hoHCbT9BxF/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.cDX16I2geQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.029109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:12.837778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-06 05:21:12.838160) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c44c01dfd264370c1558b747525d220a9a89b51c --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 c44c01dfd264370c1558b747525d220a9a89b51c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hoHCbT9BxF/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.cDX16I2geQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-06 05:21:12,915 truncating revision to first 12 chars 2016-11-06 05:21:12,915 Setting DEBUG logging. 2016-11-06 05:21:12,916 attempt 1/10 2016-11-06 05:21:12,916 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c44c01dfd264?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-06 05:21:13,685 unpacking tar archive at: mozilla-central-c44c01dfd264/testing/mozharness/ program finished with exit code 0 elapsedTime=1.018976 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-06 05:21:13.879061) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:13.879390) ========= script_repo_revision: c44c01dfd264370c1558b747525d220a9a89b51c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:13.879805) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:13.880073) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 05:21:13.898111) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-06 05:21:13.898454) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.hoHCbT9BxF/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.cDX16I2geQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:21:14 INFO - MultiFileLogger online at 20161106 05:21:14 in /builds/slave/test 05:21:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:21:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:14 INFO - {'append_to_log': False, 05:21:14 INFO - 'base_work_dir': '/builds/slave/test', 05:21:14 INFO - 'blob_upload_branch': 'Firefox', 05:21:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:14 INFO - 'branch': 'Firefox', 05:21:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:14 INFO - 'config_files': ('talos/mac_config.py',), 05:21:14 INFO - 'default_actions': ('clobber', 05:21:14 INFO - 'read-buildbot-config', 05:21:14 INFO - 'download-and-extract', 05:21:14 INFO - 'populate-webroot', 05:21:14 INFO - 'create-virtualenv', 05:21:14 INFO - 'install', 05:21:14 INFO - 'run-tests'), 05:21:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:14 INFO - 'download_minidump_stackwalk': True, 05:21:14 INFO - 'download_symbols': 'ondemand', 05:21:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:14 INFO - 'installer_path': 'installer.exe', 05:21:14 INFO - 'log_level': 'info', 05:21:14 INFO - 'log_name': 'talos', 05:21:14 INFO - 'log_to_console': True, 05:21:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:21:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:21:14 INFO - 'opt_config_files': (), 05:21:14 INFO - 'pip_index': False, 05:21:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:14 INFO - 'cmd': ('bash', 05:21:14 INFO - '-c', 05:21:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:14 INFO - 'enabled': True, 05:21:14 INFO - 'halt_on_failure': False, 05:21:14 INFO - 'name': 'check_screen_resolution'},), 05:21:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:14 INFO - 'cmd': ('bash', 05:21:14 INFO - '-c', 05:21:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:14 INFO - 'enabled': True, 05:21:14 INFO - 'halt_on_failure': False, 05:21:14 INFO - 'name': 'check_screen_resolution'},), 05:21:14 INFO - 'run_cmd_checks_enabled': True, 05:21:14 INFO - 'sps_profile': False, 05:21:14 INFO - 'sps_profile_interval': 0, 05:21:14 INFO - 'suite': 'dromaeojs', 05:21:14 INFO - 'system_bits': '32', 05:21:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:14 INFO - 'title': 't-yosemite-r7-0123', 05:21:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:14 INFO - 'use_talos_json': True, 05:21:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:14 INFO - 'work_dir': 'build'} 05:21:14 INFO - [mozharness: 2016-11-06 13:21:14.103428Z] Running clobber step. 05:21:14 INFO - Running pre-action listener: _resource_record_pre_action 05:21:14 INFO - Running main action method: clobber 05:21:14 INFO - rmtree: /builds/slave/test/build 05:21:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:16 INFO - Running post-action listener: _resource_record_post_action 05:21:16 INFO - [mozharness: 2016-11-06 13:21:16.252467Z] Finished clobber step (success) 05:21:16 INFO - [mozharness: 2016-11-06 13:21:16.252607Z] Running read-buildbot-config step. 05:21:16 INFO - Running pre-action listener: _resource_record_pre_action 05:21:16 INFO - Running main action method: read_buildbot_config 05:21:16 INFO - Using buildbot properties: 05:21:16 INFO - { 05:21:16 INFO - "project": "", 05:21:16 INFO - "product": "firefox", 05:21:16 INFO - "script_repo_revision": "production", 05:21:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:21:16 INFO - "repository": "", 05:21:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:21:16 INFO - "buildid": "20161106030203", 05:21:16 INFO - "stage_platform": "macosx64", 05:21:16 INFO - "basedir": "/builds/slave/test", 05:21:16 INFO - "nightly_build": "True", 05:21:16 INFO - "buildnumber": 2, 05:21:16 INFO - "slavename": "t-yosemite-r7-0123", 05:21:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:21:16 INFO - "platform": "yosemite_r7", 05:21:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:21:16 INFO - "branch": "mozilla-central", 05:21:16 INFO - "repo_path": "mozilla-central", 05:21:16 INFO - "revision": "c44c01dfd264370c1558b747525d220a9a89b51c", 05:21:16 INFO - "pgo_build": "False", 05:21:16 INFO - "builduid": "ac4cdb2c2b20475288886ee5204f7660", 05:21:16 INFO - "slavebuilddir": "test" 05:21:16 INFO - } 05:21:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:21:16 INFO - Running post-action listener: _resource_record_post_action 05:21:16 INFO - [mozharness: 2016-11-06 13:21:16.256186Z] Finished read-buildbot-config step (success) 05:21:16 INFO - [mozharness: 2016-11-06 13:21:16.256312Z] Running download-and-extract step. 05:21:16 INFO - Running pre-action listener: _resource_record_pre_action 05:21:16 INFO - Running main action method: download_and_extract 05:21:16 INFO - mkdir: /builds/slave/test/build/tests 05:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:16 INFO - trying https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:21:16 INFO - Downloading https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/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 05:21:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/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 05:21:17 INFO - Downloaded 1199 bytes. 05:21:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:21:17 INFO - Using the following test package requirements: 05:21:17 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:21:17 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:21:17 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:21:17 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'jsshell-mac.zip'], 05:21:17 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:21:17 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:21:17 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:21:17 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:21:17 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:21:17 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:17 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:21:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:21:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:21:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:21:17 INFO - Fetch https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:21:18 INFO - Http code: 200 05:21:18 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 05:21:18 INFO - x-amz-cf-id: Fl3ViVDQJtGPpj_XfZYww5OEr6n-Y_M0HSeiv734V8DrV6Sd9_ffXA== 05:21:18 INFO - x-amz-version-id: czYluqG43bXzwV_4iW11JTnx2Gm1UGC. 05:21:18 INFO - Expected file size: 16308141 05:21:18 INFO - Obtained file size: 16308141 05:21:25 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 05:21:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:21:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:21:25 INFO - Fetch https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:21:25 INFO - Http code: 200 05:21:25 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 05:21:25 INFO - x-amz-cf-id: 4Il-DVFepaAtwvtbvAcC7dD9Nh2wrndKhyVt5jObiFbk7L-pb9UqSA== 05:21:25 INFO - x-amz-version-id: czYluqG43bXzwV_4iW11JTnx2Gm1UGC. 05:21:26 INFO - Expected file size: 16308141 05:21:26 INFO - Obtained file size: 16308141 05:21:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:21:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:21:32 INFO - Fetch https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:21:33 INFO - Http code: 200 05:21:33 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 05:21:33 INFO - x-amz-cf-id: FnOQ-AwCFOaSGedRyszB42E1HY2TpnnhD76MlP4ZQO8_ejt2WsLZ2w== 05:21:33 INFO - x-amz-version-id: S8kzuqTFo0jGt0Kmu2ZyYKASBX0gZlpd 05:21:33 INFO - Expected file size: 14409038 05:21:33 INFO - Obtained file size: 14409038 05:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:33 INFO - trying https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/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 05:21:37 INFO - Downloaded 111653804 bytes. 05:21:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:21:37 INFO - mkdir: /builds/slave/test/properties 05:21:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:21:37 INFO - Writing to file /builds/slave/test/properties/build_url 05:21:37 INFO - Contents: 05:21:37 INFO - build_url:https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:21:38 INFO - Running post-action listener: _resource_record_post_action 05:21:38 INFO - Running post-action listener: set_extra_try_arguments 05:21:38 INFO - [mozharness: 2016-11-06 13:21:38.224973Z] Finished download-and-extract step (success) 05:21:38 INFO - [mozharness: 2016-11-06 13:21:38.225101Z] Running populate-webroot step. 05:21:38 INFO - Running pre-action listener: _resource_record_pre_action 05:21:38 INFO - Running main action method: populate_webroot 05:21:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:21:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:21:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:21:38 INFO - '--tppagecycles', 05:21:38 INFO - '7'], 05:21:38 INFO - 'tests': ['tsvgm']}}, 05:21:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:21:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:21:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:21:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:21:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['damp', 'tps']}, 05:21:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'tests': ['damp', 'tps']}, 05:21:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['dromaeo_dom']}, 05:21:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:21:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['basic_compositor_video']}, 05:21:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:21:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['a11yr', 05:21:38 INFO - 'ts_paint', 05:21:38 INFO - 'tpaint', 05:21:38 INFO - 'sessionrestore', 05:21:38 INFO - 'sessionrestore_no_auto_restore', 05:21:38 INFO - 'tabpaint']}, 05:21:38 INFO - 'other-e10s': {'tests': ['a11yr', 05:21:38 INFO - 'ts_paint', 05:21:38 INFO - 'tpaint', 05:21:38 INFO - 'sessionrestore', 05:21:38 INFO - 'sessionrestore_no_auto_restore', 05:21:38 INFO - 'tabpaint']}, 05:21:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:21:38 INFO - 'ts_paint', 05:21:38 INFO - 'tpaint', 05:21:38 INFO - 'sessionrestore', 05:21:38 INFO - 'sessionrestore_no_auto_restore', 05:21:38 INFO - 'tabpaint']}, 05:21:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:21:38 INFO - 'ts_paint', 05:21:38 INFO - 'tpaint', 05:21:38 INFO - 'sessionrestore', 05:21:38 INFO - 'sessionrestore_no_auto_restore', 05:21:38 INFO - 'tabpaint']}, 05:21:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['a11yr', 05:21:38 INFO - 'ts_paint', 05:21:38 INFO - 'tpaint', 05:21:38 INFO - 'sessionrestore', 05:21:38 INFO - 'sessionrestore_no_auto_restore', 05:21:38 INFO - 'tabpaint']}, 05:21:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['a11yr', 05:21:38 INFO - 'ts_paint', 05:21:38 INFO - 'tpaint', 05:21:38 INFO - 'sessionrestore', 05:21:38 INFO - 'sessionrestore_no_auto_restore', 05:21:38 INFO - 'tabpaint']}, 05:21:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['tsvgx', 05:21:38 INFO - 'tsvgr_opacity', 05:21:38 INFO - 'tart', 05:21:38 INFO - 'tscrollx', 05:21:38 INFO - 'cart']}, 05:21:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:21:38 INFO - 'tsvgr_opacity', 05:21:38 INFO - 'tart', 05:21:38 INFO - 'tscrollx', 05:21:38 INFO - 'cart']}, 05:21:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'talos_options': ['--disable-e10s'], 05:21:38 INFO - 'tests': ['tp5o']}, 05:21:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'tests': ['tp5o']}, 05:21:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'talos_options': ['--disable-e10s', 05:21:38 INFO - '--xperf_path', 05:21:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:38 INFO - 'tests': ['tp5n']}, 05:21:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:38 INFO - 'talos_options': ['--xperf_path', 05:21:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:38 INFO - 'tests': ['tp5n']}}, 05:21:38 INFO - 'talos.zip': {'path': '', 05:21:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:21:38 INFO - Running post-action listener: _resource_record_post_action 05:21:38 INFO - [mozharness: 2016-11-06 13:21:38.244069Z] Finished populate-webroot step (success) 05:21:38 INFO - [mozharness: 2016-11-06 13:21:38.244179Z] Running create-virtualenv step. 05:21:38 INFO - Running pre-action listener: _resource_record_pre_action 05:21:38 INFO - Running main action method: create_virtualenv 05:21:38 INFO - Creating virtualenv /builds/slave/test/build/venv 05:21:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:21:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:21:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:21:38 INFO - Using real prefix '/tools/python27' 05:21:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:21:39 INFO - Installing distribute.............................................................................................................................................................................................done. 05:21:41 INFO - Installing pip.................done. 05:21:41 INFO - Return code: 0 05:21:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:21:42 INFO - Reading from file tmpfile_stdout 05:21:42 INFO - Output received: 05:21:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:21:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:42 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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:42 INFO - 'HOME': '/Users/cltbld', 05:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:42 INFO - 'LOGNAME': 'cltbld', 05:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:42 INFO - 'MOZ_NO_REMOTE': '1', 05:21:42 INFO - 'NO_EM_RESTART': '1', 05:21:42 INFO - 'PAGER': '/bin/cat', 05:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:42 INFO - 'PWD': '/builds/slave/test', 05:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:42 INFO - 'SHELL': '/bin/bash', 05:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:21:42 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:42 INFO - 'USER': 'cltbld', 05:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:42 INFO - 'XPC_FLAGS': '0x0', 05:21:42 INFO - 'XPC_SERVICE_NAME': '0', 05:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:21:42 INFO - Cleaning up... 05:21:42 INFO - Return code: 0 05:21:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:42 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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:42 INFO - 'HOME': '/Users/cltbld', 05:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:42 INFO - 'LOGNAME': 'cltbld', 05:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:42 INFO - 'MOZ_NO_REMOTE': '1', 05:21:42 INFO - 'NO_EM_RESTART': '1', 05:21:42 INFO - 'PAGER': '/bin/cat', 05:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:42 INFO - 'PWD': '/builds/slave/test', 05:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:42 INFO - 'SHELL': '/bin/bash', 05:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:21:42 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:42 INFO - 'USER': 'cltbld', 05:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:42 INFO - 'XPC_FLAGS': '0x0', 05:21:42 INFO - 'XPC_SERVICE_NAME': '0', 05:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:42 INFO - Downloading/unpacking psutil>=3.1.1 05:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:21:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:46 INFO - Installing collected packages: psutil 05:21:46 INFO - Running setup.py install for psutil 05:21:46 INFO - building 'psutil._psutil_osx' extension 05:21:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:21:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:21:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:21:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:21:47 INFO - building 'psutil._psutil_posix' extension 05:21:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:21:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:21:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:21:47 INFO - ^ 05:21:47 INFO - 1 warning generated. 05:21:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:21:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:47 INFO - Successfully installed psutil 05:21:47 INFO - Cleaning up... 05:21:47 INFO - Return code: 0 05:21:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:21: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')]} 05:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21: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')]} 05:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:21: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 mozsystemmonitor==0.3 05:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:47 INFO - 'HOME': '/Users/cltbld', 05:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:47 INFO - 'LOGNAME': 'cltbld', 05:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:47 INFO - 'MOZ_NO_REMOTE': '1', 05:21:47 INFO - 'NO_EM_RESTART': '1', 05:21:47 INFO - 'PAGER': '/bin/cat', 05:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:47 INFO - 'PWD': '/builds/slave/test', 05:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:47 INFO - 'SHELL': '/bin/bash', 05:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:21:47 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:47 INFO - 'USER': 'cltbld', 05:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:47 INFO - 'XPC_FLAGS': '0x0', 05:21:47 INFO - 'XPC_SERVICE_NAME': '0', 05:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:21:50 INFO - Installing collected packages: mozsystemmonitor 05:21:50 INFO - Running setup.py install for mozsystemmonitor 05:21:50 INFO - Successfully installed mozsystemmonitor 05:21:50 INFO - Cleaning up... 05:21:50 INFO - Return code: 0 05:21:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:21: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')]} 05:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21: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')]} 05:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21: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', '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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:21: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 jsonschema==2.5.1 05:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:50 INFO - 'HOME': '/Users/cltbld', 05:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:50 INFO - 'LOGNAME': 'cltbld', 05:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:50 INFO - 'MOZ_NO_REMOTE': '1', 05:21:50 INFO - 'NO_EM_RESTART': '1', 05:21:50 INFO - 'PAGER': '/bin/cat', 05:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:50 INFO - 'PWD': '/builds/slave/test', 05:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:50 INFO - 'SHELL': '/bin/bash', 05:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:21:50 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:50 INFO - 'USER': 'cltbld', 05:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:50 INFO - 'XPC_FLAGS': '0x0', 05:21:50 INFO - 'XPC_SERVICE_NAME': '0', 05:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:51 INFO - Downloading/unpacking jsonschema==2.5.1 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:21:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21:53 INFO - Downloading functools32-3.2.3-2.tar.gz 05:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:21:54 INFO - warning: no files found matching '*.txt' 05:21:54 INFO - no previously-included directories found matching 'build' 05:21:54 INFO - no previously-included directories found matching 'dist' 05:21:54 INFO - no previously-included directories found matching '.git*' 05:21:54 INFO - Installing collected packages: jsonschema, functools32 05:21:54 INFO - Running setup.py install for functools32 05:21:54 INFO - warning: no files found matching '*.txt' 05:21:54 INFO - no previously-included directories found matching 'build' 05:21:54 INFO - no previously-included directories found matching 'dist' 05:21:54 INFO - no previously-included directories found matching '.git*' 05:21:54 INFO - Successfully installed jsonschema functools32 05:21:54 INFO - Cleaning up... 05:21:54 INFO - Return code: 0 05:21:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:54 INFO - 'HOME': '/Users/cltbld', 05:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:54 INFO - 'LOGNAME': 'cltbld', 05:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:54 INFO - 'MOZ_NO_REMOTE': '1', 05:21:54 INFO - 'NO_EM_RESTART': '1', 05:21:54 INFO - 'PAGER': '/bin/cat', 05:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:54 INFO - 'PWD': '/builds/slave/test', 05:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:54 INFO - 'SHELL': '/bin/bash', 05:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:21:54 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:54 INFO - 'USER': 'cltbld', 05:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:54 INFO - 'XPC_FLAGS': '0x0', 05:21:54 INFO - 'XPC_SERVICE_NAME': '0', 05:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:21:54 INFO - Cleaning up... 05:21:54 INFO - Return code: 0 05:21:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:54 INFO - 'HOME': '/Users/cltbld', 05:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:54 INFO - 'LOGNAME': 'cltbld', 05:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:54 INFO - 'MOZ_NO_REMOTE': '1', 05:21:54 INFO - 'NO_EM_RESTART': '1', 05:21:54 INFO - 'PAGER': '/bin/cat', 05:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:54 INFO - 'PWD': '/builds/slave/test', 05:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:54 INFO - 'SHELL': '/bin/bash', 05:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:21:54 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:54 INFO - 'USER': 'cltbld', 05:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:54 INFO - 'XPC_FLAGS': '0x0', 05:21:54 INFO - 'XPC_SERVICE_NAME': '0', 05:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:54 INFO - Downloading/unpacking blobuploader==1.2.4 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21: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 05:21:57 INFO - Downloading blobuploader-1.2.4.tar.gz 05:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:21:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:21:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:58 INFO - Downloading docopt-0.6.1.tar.gz 05:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:21:58 INFO - Installing collected packages: blobuploader, requests, docopt 05:21:58 INFO - Running setup.py install for blobuploader 05:21:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:21:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:21:58 INFO - Running setup.py install for requests 05:21:59 INFO - Running setup.py install for docopt 05:21:59 INFO - Successfully installed blobuploader requests docopt 05:21:59 INFO - Cleaning up... 05:21:59 INFO - Return code: 0 05:21:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:59 INFO - 'HOME': '/Users/cltbld', 05:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:59 INFO - 'LOGNAME': 'cltbld', 05:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:59 INFO - 'MOZ_NO_REMOTE': '1', 05:21:59 INFO - 'NO_EM_RESTART': '1', 05:21:59 INFO - 'PAGER': '/bin/cat', 05:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:59 INFO - 'PWD': '/builds/slave/test', 05:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:59 INFO - 'SHELL': '/bin/bash', 05:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:21:59 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:59 INFO - 'USER': 'cltbld', 05:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:59 INFO - 'XPC_FLAGS': '0x0', 05:21:59 INFO - 'XPC_SERVICE_NAME': '0', 05:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-EtBN7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LGLuzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-C3JKDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wIZjcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-jhWYNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-jXZ6Yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-yw7FzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Yj5jyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-E5S8rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-jDFfjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-gtTdAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-JJYC7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-BwBskM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-IsVolr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-OqGHya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-TvHSFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-0k2G46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:01 INFO - Running setup.py install for manifestparser 05:22:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:22:01 INFO - Running setup.py install for mozcrash 05:22:01 INFO - Running setup.py install for mozdebug 05:22:01 INFO - Running setup.py install for mozdevice 05:22:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Running setup.py install for mozfile 05:22:02 INFO - Running setup.py install for mozhttpd 05:22:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Running setup.py install for mozinfo 05:22:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Running setup.py install for mozInstall 05:22:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:22:02 INFO - Running setup.py install for mozleak 05:22:02 INFO - Running setup.py install for mozlog 05:22:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:22:03 INFO - Running setup.py install for moznetwork 05:22:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:22:03 INFO - Running setup.py install for mozprocess 05:22:03 INFO - Running setup.py install for mozprofile 05:22:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:22:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:22:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:22:03 INFO - Running setup.py install for mozrunner 05:22:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:22:03 INFO - Running setup.py install for mozscreenshot 05:22:03 INFO - Running setup.py install for moztest 05:22:03 INFO - Running setup.py install for mozversion 05:22:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:22:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:04 INFO - Cleaning up... 05:22:04 INFO - Return code: 0 05:22:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:04 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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:22:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:04 INFO - 'HOME': '/Users/cltbld', 05:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:04 INFO - 'LOGNAME': 'cltbld', 05:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:04 INFO - 'MOZ_NO_REMOTE': '1', 05:22:04 INFO - 'NO_EM_RESTART': '1', 05:22:04 INFO - 'PAGER': '/bin/cat', 05:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:04 INFO - 'PWD': '/builds/slave/test', 05:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:04 INFO - 'SHELL': '/bin/bash', 05:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:22:04 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:04 INFO - 'USER': 'cltbld', 05:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:04 INFO - 'XPC_FLAGS': '0x0', 05:22:04 INFO - 'XPC_SERVICE_NAME': '0', 05:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fp2vmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6p4qnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:04 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)) 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-bLvEHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KOVwX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-K4dDuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-edJmwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-do0aU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ckplzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LIg97G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xO5ZRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-yT5dEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-kvw3Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-iWLfO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vyCdS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:05 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)) 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-o9MoAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-TFiEya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:06 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)) 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-x85pMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:22:06 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)) 05:22:06 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)) 05:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:08 INFO - Downloading blessings-1.6.tar.gz 05:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:22:08 INFO - Installing collected packages: blessings 05:22:08 INFO - Running setup.py install for blessings 05:22:08 INFO - Successfully installed blessings 05:22:08 INFO - Cleaning up... 05:22:09 INFO - Return code: 0 05:22:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:22:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:09 INFO - Reading from file tmpfile_stdout 05:22:09 INFO - Current package versions: 05:22:09 INFO - blessings == 1.6 05:22:09 INFO - blobuploader == 1.2.4 05:22:09 INFO - docopt == 0.6.1 05:22:09 INFO - functools32 == 3.2.3-2 05:22:09 INFO - jsonschema == 2.5.1 05:22:09 INFO - manifestparser == 1.1 05:22:09 INFO - mozInstall == 1.12 05:22:09 INFO - mozcrash == 0.17 05:22:09 INFO - mozdebug == 0.1 05:22:09 INFO - mozdevice == 0.48 05:22:09 INFO - mozfile == 1.2 05:22:09 INFO - mozhttpd == 0.7 05:22:09 INFO - mozinfo == 0.9 05:22:09 INFO - mozleak == 0.1 05:22:09 INFO - mozlog == 3.4 05:22:09 INFO - moznetwork == 0.27 05:22:09 INFO - mozprocess == 0.23 05:22:09 INFO - mozprofile == 0.28 05:22:09 INFO - mozrunner == 6.12 05:22:09 INFO - mozscreenshot == 0.1 05:22:09 INFO - mozsystemmonitor == 0.3 05:22:09 INFO - moztest == 0.7 05:22:09 INFO - mozversion == 1.4 05:22:09 INFO - psutil == 3.1.1 05:22:09 INFO - requests == 1.2.3 05:22:09 INFO - wsgiref == 0.1.2 05:22:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:09 INFO - 'HOME': '/Users/cltbld', 05:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:09 INFO - 'LOGNAME': 'cltbld', 05:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:09 INFO - 'MOZ_NO_REMOTE': '1', 05:22:09 INFO - 'NO_EM_RESTART': '1', 05:22:09 INFO - 'PAGER': '/bin/cat', 05:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:09 INFO - 'PWD': '/builds/slave/test', 05:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:09 INFO - 'SHELL': '/bin/bash', 05:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:22:09 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:09 INFO - 'USER': 'cltbld', 05:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:09 INFO - 'XPC_FLAGS': '0x0', 05:22:09 INFO - 'XPC_SERVICE_NAME': '0', 05:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:09 INFO - Cleaning up... 05:22:09 INFO - Return code: 0 05:22:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 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 0x10b7e1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa284ca910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, '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.hoHCbT9BxF/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.cDX16I2geQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:09 INFO - 'HOME': '/Users/cltbld', 05:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:09 INFO - 'LOGNAME': 'cltbld', 05:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:09 INFO - 'MOZ_NO_REMOTE': '1', 05:22:09 INFO - 'NO_EM_RESTART': '1', 05:22:09 INFO - 'PAGER': '/bin/cat', 05:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:09 INFO - 'PWD': '/builds/slave/test', 05:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:09 INFO - 'SHELL': '/bin/bash', 05:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:22:09 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:09 INFO - 'USER': 'cltbld', 05:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:09 INFO - 'XPC_FLAGS': '0x0', 05:22:09 INFO - 'XPC_SERVICE_NAME': '0', 05:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:22:09 INFO - Cleaning up... 05:22:09 INFO - Return code: 0 05:22:09 INFO - Running post-action listener: _resource_record_post_action 05:22:09 INFO - Running post-action listener: _start_resource_monitoring 05:22:09 INFO - Starting resource monitoring. 05:22:09 INFO - [mozharness: 2016-11-06 13:22:09.880719Z] Finished create-virtualenv step (success) 05:22:09 INFO - [mozharness: 2016-11-06 13:22:09.881708Z] Running install step. 05:22:09 INFO - Running pre-action listener: _resource_record_pre_action 05:22:09 INFO - Running main action method: install 05:22:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:10 INFO - Reading from file tmpfile_stdout 05:22:10 INFO - Detecting whether we're running mozinstall >=1.0... 05:22:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:22:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:22:10 INFO - Reading from file tmpfile_stdout 05:22:10 INFO - Output received: 05:22:10 INFO - Usage: mozinstall [options] installer 05:22:10 INFO - Options: 05:22:10 INFO - -h, --help show this help message and exit 05:22:10 INFO - -d DEST, --destination=DEST 05:22:10 INFO - Directory to install application into. [default: 05:22:10 INFO - "/builds/slave/test"] 05:22:10 INFO - --app=APP Application being installed. [default: firefox] 05:22:10 INFO - mkdir: /builds/slave/test/build/application 05:22:10 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'] 05:22:10 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 05:22:44 INFO - Reading from file tmpfile_stdout 05:22:44 INFO - Output received: 05:22:44 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:22:44 INFO - Running post-action listener: _resource_record_post_action 05:22:44 INFO - [mozharness: 2016-11-06 13:22:44.206025Z] Finished install step (success) 05:22:44 INFO - [mozharness: 2016-11-06 13:22:44.206166Z] Running run-tests step. 05:22:44 INFO - Running pre-action listener: _resource_record_pre_action 05:22:44 INFO - Running main action method: run_tests 05:22:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:22:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:22:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:22:44 INFO - 2016-11-06 05:22:44.220 screenresolution[1653:9818] starting screenresolution argv=screenresolution get 05:22:44 INFO - 2016-11-06 05:22:44.223 screenresolution[1653:9818] Display 0: 1600x1200x32@60 05:22:44 INFO - 2016-11-06 05:22:44.228 screenresolution[1654:9820] starting screenresolution argv=screenresolution list 05:22:44 INFO - Available Modes on Display 0 05:22:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:22:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:22:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:22:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:22:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:22:44 INFO - Intel Iris: 05:22:44 INFO - Chipset Model: Intel Iris 05:22:44 INFO - Type: GPU 05:22:44 INFO - Bus: Built-In 05:22:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:22:44 INFO - Vendor: Intel (0x8086) 05:22:44 INFO - Device ID: 0x0a2e 05:22:44 INFO - Revision ID: 0x0009 05:22:44 INFO - Displays: 05:22:44 INFO - Display: 05:22:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:22:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:22:44 INFO - Display Serial Number: 1600x1200 60 05:22:44 INFO - Main Display: Yes 05:22:44 INFO - Mirror: Off 05:22:44 INFO - Online: Yes 05:22:44 INFO - Rotation: Supported 05:22:44 INFO - Return code: 0 05:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:22:44 INFO - Python 2.7.3 05:22:44 INFO - Return code: 0 05:22:44 INFO - grabbing minidump binary from tooltool 05:22: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')]} 05:22:44 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 0x10b5f5d30>, '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 0x10b41bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5edb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:22:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:22:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:22:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:22:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:22:44 INFO - Return code: 0 05:22:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:22:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:22:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:22:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:22:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hoHCbT9BxF/Render', 05:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:44 INFO - 'HOME': '/Users/cltbld', 05:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:44 INFO - 'LOGNAME': 'cltbld', 05:22:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:22:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:44 INFO - 'MOZ_NO_REMOTE': '1', 05:22:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:22:44 INFO - 'NO_EM_RESTART': '1', 05:22:44 INFO - 'PAGER': '/bin/cat', 05:22:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:44 INFO - 'PWD': '/builds/slave/test', 05:22:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:44 INFO - 'SHELL': '/bin/bash', 05:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cDX16I2geQ/Listeners', 05:22:44 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:44 INFO - 'USER': 'cltbld', 05:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:44 INFO - 'XPC_FLAGS': '0x0', 05:22:44 INFO - 'XPC_SERVICE_NAME': '0', 05:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:22:44 INFO - mozversion application_buildid: 20161106030203 05:22:44 INFO - mozversion application_changeset: c44c01dfd264370c1558b747525d220a9a89b51c 05:22:44 INFO - mozversion application_display_name: Nightly 05:22:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:22:44 INFO - mozversion application_name: Firefox 05:22:44 INFO - mozversion application_remotingname: firefox 05:22:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:22:44 INFO - mozversion application_vendor: Mozilla 05:22:44 INFO - mozversion application_version: 52.0a1 05:22:44 INFO - mozversion platform_buildid: 20161106030203 05:22:44 INFO - mozversion platform_changeset: c44c01dfd264370c1558b747525d220a9a89b51c 05:22:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:22:44 INFO - mozversion platform_version: 52.0a1 05:22:44 INFO - using testdate: 1478438564 05:22:44 INFO - actual date: 1478438564 05:22:44 INFO - starting webserver on 'localhost:49280' 05:22:44 INFO - SUITE-START | Running 2 tests 05:22:44 INFO - TEST-START | dromaeo_css 05:22:44 INFO - operating with platform_type : mac_ 05:22:44 INFO - Initialising browser for dromaeo_css test... 05:22:44 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpzmlMDU/profile http://localhost:49280/getInfo.html) 05:22:47 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 05:22:47 INFO - PROCESS | 1664 | colorDepth:24 05:22:47 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 05:22:47 INFO - PROCESS | 1664 | __metrics 05:22:47 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:22:47 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:22:48 INFO - TEST-INFO | 1664: exit 0 05:22:48 INFO - Browser initialized. 05:22:48 INFO - Running cycle 1/1 for dromaeo_css test... 05:22:48 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpzmlMDU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:00 INFO - PROCESS | 1668 | RSS: Main: 239157248 05:23:00 INFO - PROCESS | 1668 | 05:23:51 INFO - PROCESS | 1668 | 1478438631966 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] 05:26:04 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 05:26:06 INFO - PROCESS | 1668 | RSS: Main: 404152320 05:26:06 INFO - PROCESS | 1668 | 05:29:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 05:29:09 INFO - PROCESS | 1668 | RSS: Main: 369618944 05:29:09 INFO - PROCESS | 1668 | 05:32:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 05:32:12 INFO - PROCESS | 1668 | RSS: Main: 358363136 05:32:12 INFO - PROCESS | 1668 | 05:35:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 05:35:15 INFO - PROCESS | 1668 | RSS: Main: 351293440 05:35:15 INFO - PROCESS | 1668 | 05:38:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 05:38:19 INFO - PROCESS | 1668 | RSS: Main: 340709376 05:38:19 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 05:41:23 INFO - PROCESS | 1668 | RSS: Main: 562962432 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | __start_tp_report 05:41:23 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 05:41:23 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 05:41:23 INFO - PROCESS | 1668 | |i|pagename|runs| 05:41:23 INFO - PROCESS | 1668 | |0;dojo.html;2871.49748793961;3075.0774767569724;3353.8323083845803;3358.908354784694;3390.796552206866;5614.27014488117;5695.800646977356;5728.283964504437;5815.360310365862;5816.680082595455;5689.829305120853;5718.857028574288;5795.971020144893;5812.912806307909;5821;5143.12566863634;5155;5188.143956247215;5198.428172900978;5254.737263136828;5293.86765330866;5362.731863406833;5366.114591092465;5367.114426119705;5368.570514358861;5138.486151384869;5289.44460831611;5291.550218231455;5310.017646735367;5390.9191362129595;5160;5217.686938783685;5221.6083793715625;5221.634485586011;5224.660397074216;5154;5176.948230517684;5186.740662966874;5187.688738675692;5208.5572726318305;5696.401877802812;5710.086386178192;5718.513926316268;5727.140928860705;5741.971290143564;6173.81478555644;6181.979973304399;6185.474234690057;6188.071789231606;6188.2883468400805;6164.167837341946;6187;6216.440520353145;6254.7498100075945;6255.468285195764;4779.474257831635;5773.93182261283;5777.0467872801355;5781.364049954544;5785.247917770663;6212.316645169028;6292.811215663537;6305.337939516285;6308.716107775161;6318.210223722035;6225.097360882737;6227.906581401283;6229.408206220402;6233.2208473940755;6236.501079913596;6111.500579230221;6148;6177.289611694623;6205.22434695663;6277.52918531112;6204.844878878064;6219.720112594944;6259.843503912348;6263.404976527223;6265.780697675559;6270.874582508324;6276.309605943343;6288.654124023223;6289.528285378615;6298.370162983665;4853.611711063107;4907.018596280758;4943.134951383494;4945.629077819178;4983.426905905795;3638.399664055454;4291.570842915684;4360.520342762357;4385.912281754347;4417.469903611523;5902.852428689317;5903.0260007099205;5905.202797622266;5909.8227053188475;5950.613210141413;6273.309935907047;6315.179026726496;6332.366763323631;6352.968235158887;6354.872902541923;4744.4069491313585;5763.366029736726;5788.27646544182;5818.389069147764;5898.705064746831;5408.972955135199;5436.53789427903;5440.619156658996;5441.102218133963;5494.120940649557;6307.810765677037;6309.49524038085;6312.968435157794;6324.430801227728;6330.4619107375465;6160.630362178284;6240.471896453708;6332.620042797447;6339.049142628643;6347.936520634735;5288.762005709676;6202.5658203925295;6279.686015699288;6281.434670879643;6289.308176100717;6176.011838105882;6232.75068997235;6245.219347581552;6246.531510136849;6264.749410023548;5276.34045744282;6274.74901003973;6283.434490895841;6322;6330.8100756977365;5887.852803679943;5969.91045134332;6083.482903953213;6092.543059270483;6112.358202388863;6116.326815364041;6132.998044146438;6154.261488621394;6174.228221472316;6176.413240742122;6045.727942242697;6050.909236361546;6051.334353221054;6056.606320589148;6066.545009124245;4739.312799644102;5997.460228579449;6052.546059045501;6135.662538560422;6187.133801267916;5915.201447804663;6010.789622363196;6043.45608895184;6051.878962420815;6058.697065146813 05:41:23 INFO - PROCESS | 1668 | |1;ext.html;471.6957562372272;481.5641844131061;489.8016303397125;493.93331900193476;520.1183993131643;29570;38278.043048923755;39262.8036859816;39381.80309098458;39471.40792888109;40452.79773601128;40655.18689626206;40658;40719;40891.79554102233;9194.954025229865;9367.906320936805;9400.05999400058;9403.529823508832;9407.482588457631;9289.349745517817;9392.248620110375;9412.105849944244;9416.670416535388;9416.952915235413;8061.95969020157;8066.516009039447;9413.199878010377;9430.481323527203;9444.150026497613;8044.839103217962;9367.906320936772;9384.530773461367;9395.436273823592;9424.858627120599;9208.585613647401;9293.024232455562;9300.30247731416;9317.813643727155;9322.627094916263;38288.23423531514;38595.61404385948;38645.22709545821;38670.80664596659;38855.8057209715;3200.775945683803;3509.3332266869215;3562.5368702068563;3564.803935783531;3825.756629095555;26920;30081.699183008328;30437;30526;30615.081547553607;3102.565640810288;3110.6422761382505;3286.5727455430642;3322.335532893431;3327.96672033279;426110.86944565387;429229;431613;434515.827420864;437780;59336.703316484;59732;59881.70059149677;59917.700411497666;59963.400365996655;60845.08732369018;60849.39150608437;61041;61118;61146.08280875701;15367.692646147014;15603.219839008005;15604.453844115402;15629.296681649354;15631.609209769847;11922.463489143009;11958.64124076279;11971.820422693667;11991.940040299916;11993.88006119945;17307.53849230159;18075.27698892056;18086.728699069525;18093.909530452263;18105.909470452563;6031.517478601701;7186.820329491805;7245.89131163033;7323.231060738653;7494.362979146725;675.598019178588;833.1834801894178;849.9830003399898;856.7044369692446;1109.5284504085732;26028.219153425427;26812.731872681023;27184.59223111655;28280;28822;972.0473935543207;984.7931934293736;1046.7173863056933;1066.4880857188439;1085.7882712870914;14153.43386264538;14662.413503459955;14822.184779837213;14855.925720371546;14896.553103406915;591972;593022;594980;595742;598069;45422;54222.186667200025;55040.89918201694;55071.449285507435;55189;1120.294214644801;1191.5650787462514;1236.4744983382027;1276.5914907229783;1315.6710822294426;28098.71901280961;29295.85352073226;29395.85302073476;30163.245918852204;30240;46704;47365;47387.815304616284;47398;47469.015753345404;520942;525615;526078;526185;527818;22911.770882291632;25191;25789.87105064538;25883.0940917067;26525.469490609692;43288.567114328456;43780.56219437765;44519.33221001752;44671.55328446674;44981.10037799291;36256.09359766027;36380.63619363772;36575.26849463049;37324.81337593294;37622.8118859404;34842.82578587091;36005.45991810178;36535;37551.06122346963;37622;36066.8196659015;36620;36829;37097.6290237105;37314.25371492609;25087.41650045523;31793;32354.83822580872;32428.351432970736;33048.834755827025;24590.877045615372;24613.63079553844;24738.628920565847;24897.879595417886;25144.874275628506 05:41:23 INFO - PROCESS | 1668 | |2;jquery.html;350.30464527912096;356.55074605996435;374.40469653251324;388.2603640065675;408.65468678966283;14656.853431465683;14828.851711482883;15613.765793513106;16040.11779352135;20502.974851257415;15991.760123598126;16653.000819950783;19858.106385212664;20392.980350982467;20417.979101044963;5674.517665998395;6042.667653279068;6048.243969503812;6056.576039677202;6057.151998720183;5680.687562184078;5868.823935281927;5921.792737254197;5979.820605381824;6000.939990600104;5924.763009479637;5928.170056192136;5936.495397891163;5937.050071988483;5937.346891841894;5633;5861.944849927033;5939;5940.821775346746;5941.762329506814;4967.726775027372;5190.688558686491;5198.896022079537;5200.947990520084;5208.437468759357;24799.752002480105;25219;25269.98920043196;25496.74503254962;25618.359541011436;2319.744828068911;2677.196840947708;2898.7825913056604;3071.3396619726864;3164.0982320038906;14450.783238251435;15551.922240388838;15773.684526309524;15866.761998570031;15912.602184945354;1678.622309980257;1827.232562323827;1830.2678928428602;1882.8305452509203;1918.424472658211;115772.42113789462;120300.39849800782;120663.3966830169;121552;121721.39139304335;9377.343585948918;11116.666500005012;11484.19610627248;11493.942530287295;11517.539298428137;14584.781228281585;14625.707485850224;14641.48754793577;14667.77998330026;14697.85302146965;8027.117017128111;8276.627551760184;8289.41974061822;8449.746507604781;8462.407631465738;6905.309469053155;7861.331786798186;8073.959630201812;8077.434579579373;8234.670613175525;4533;4534.206513860061;4538;4542.750148741785;4597.563231493003;1874.2315650583196;1943.8639295249197;1950.775660798998;1976.4268362174807;1976.8418526517844;828.3207769628847;892.808046270055;908.6728777640044;923.5751554284969;944.6835310171169;19964.800351996295;20853.68719469209;20897.89551052235;20902.954852257328;21204.575908482053;865.4547634989916;866.4151697604092;873.1137895036543;901.7114523352595;937.5452905340904;7816.960915195501;10259;10551.26141170126;10917.945410272898;11050.673919129757;584472;587813;597061.0146949324;600278;605695.9715201396;79331.60334198408;125051;127896.72103278848;132696;135267;905.158202871336;915.1534830281962;947.2422062350092;959.3907868503551;960.3373672166183;14198.858011420167;14213.431462741375;15258.00822946505;18803.247870085044;19229.326973555857;37258.44112338263;50999;51857.481425185266;51951;52043.73978130085;72670.27329726846;73222.63388683023;73672;74015.259847403;75524;7777.805554861174;8000.719974800854;8073.475224110414;8092;8381.455205411628;12176.695582610506;12376.690582735504;12422.565210217599;12643.683907902376;12656.873431265938;10581.576736930745;10738.785224295314;11058.612948546763;11107.83338249943;11122.61070862516;7798.415118865994;10657.25399222047;10956.397398143179;10960.725981850517;11148.832767508657;10967.177461690246;11049.005589496985;11053.392063436588;11251.718707032062;11289.435528223721;7879.054513458422;7960.681572737026;7972.84054318922;8006;8274.310848973988;6774.491913106635;9216.170544651013;9476.573554190216;10843.403612801372;10964.06805421548 05:41:23 INFO - PROCESS | 1668 | |3;mootools.html;1500.8649221570056;1516.2418790604697;1710.8374704403077;1811.5018369948255;1877.0614692653674;8005.7197998070005;8393.496390216591;8397.370197235217;8408;8418.789530261738;7377.4835761496715;7618.695252189906;7632.328684960261;7678.308952194301;8196.91803081969;2106.59974604825;2141.8179454746287;2212.3031245157804;2285.7828506291876;2294.9311520654405;2310.4058199842057;2311.6763653088583;2312.387217387394;2317.455397981473;2317.9420514487106;2260.762619924909;2261.2650888461235;2321.9071237150492;2322.1640209524558;2329.580675478413;2288.9198878039274;2289.988550057256;2314.490812021344;2315.490592069759;2319.744828068911;2304.5966955782837;2308.3882771065682;2332.8950197241165;2332.9066837326486;2335.8948847301913;8270.883430736894;8275.462094963868;8364.874526882102;8376.120507346763;8412.663493460315;5050.343455350818;5220.138677118271;5220.981908527839;5228.398734145584;5240.318758561362;3041;3391.0674564494716;3462;3465.220325426784;3499.3701133795907;1057.6245432871297;1059.8039362717846;1065.6856227412932;1093.907017903479;1094.63876920616;10835;10915.181361397848;10928.289661172077;10929.344239345664;10953.671389858317;3959.2675355059405;4358.193734159127;4392.07766369066;4597.287420449836;4683.648726345537;9395.812083758286;9406.435613863054;9410.482423466776;9419.152276295166;9423;5183.300254465675;5185.325907631984;5193.974030129825;5194.4026436959475;5203.063448579216;4618.907621847544;4685.320628508847;4750.216214324667;4754.976225118921;4775.140474714584;3485.825708714554;3607.5310209672575;3621.420572708354;3672.8163591820835;3731.0858839583934;1256.7926292161874;1265.1523479268913;1268.3594784633885;1278.5013844600612;1280.8527019392702;841.6338892581747;858.1632907914733;863.1972265792873;907.5462268865567;912.1334732004622;5384.730763461811;5453.863653408617;5657.377688454267;5734.713264336767;5755.510781583528;889.6708217959397;969.1665167955553;978.5841017567506;1015.0712098429854;1041.6250149946013;2967.836768977727;3527.4179760339257;3587.210813620999;3595.7303202259936;3688.5798967397745;9747.3664211826;10249.12882404989;10254.025867542572;10258.897411025795;10268.743281417876;8765.342599305077;9595.520223988913;9989.650362237433;10123.9493802532;10129.848052279222;7682.385409167253;7890.487118337404;7891.28978391936;7902.209779022051;7907.0906845712325;5434.619576629598;5460.645058071213;5489.3687225969525;5668.291463567054;5778.104393818965;9561.235101192027;9567.617295308388;9583.424994500352;9584.520773961414;9599.568019439002;9210;9557.330986830855;9568.76078098053;9575.904240957501;9642.758931026781;7602;7787;7833.92166078332;7839.25527074927;7841;7580.204078571671;7640.235976402538;7687.884681729666;7706.460547761603;7769.223077692185;6883.105196324445;6943.270956549692;7146.3568278855155;7155.570665760071;7167.8566428672175;6710.1276834013215;6882.827929301808;6923.723051077901;6925.792226233221;6983.231844497;5477.3427188737605;6731.22590902033;6732.764353247613;6737.59574425536;6739.0902228198565;4509.3686883835635;4614.2617181250835;4643.141018911512;4766.332713420194;4807.927881081717;3865.8453661854337;4318.762468064161;4329.610335069859;4728.952710472989;4799.28010798383 05:41:23 INFO - PROCESS | 1668 | |4;prototype.html;304.4793403280389;304.55382864104087;314.1470906488882;319.514338205927;320;5280.339957505312;5341.385740639827;5348.973255133724;5375.6774593524415;5419.187121931712;4924.384451943507;5054.090263752523;5128;5134.512221338967;5325.840224793263;3229.7739158258933;3445.0698311455953;4049.2711311963835;4069.674426045924;4075.6943229257777;4022.658074063708;4029.234445455354;4056.269871423143;4059.4519739835187;4060.512738471373;3872.051347419872;4005.1589166275116;4013.3912956578165;4023.2154729827694;4023.8189281482255;3597.7587732232337;3787.0153760022317;4005.1589166275116;4006.6794656427414;4043.575424580421;3505.3515099706638;3521.7886926784217;3524.2951409717903;3526.894193174209;3528.311979164064;4557.612602928755;4953.455119936804;4965.702057876503;5057.216131499624;5089.236614507817;4021.9798901005306;4504.211762941472;4578.175928332898;4605.861824145281;4640.489546149955;4738.976305118453;5496.894254746056;5500.624843789053;5523.447655234485;5530.474604912527;3904.7852368119743;3905.7070719696135;3908.9804550977065;3913.8630147944687;3919.2553414851086;4313.935290970638;4366;5028.767951851783;5212.609054320903;5756.510696590794;3309.418653450313;3563.910902227465;3712.7215458840697;3857.723942873662;3950.6641935435237;3331.200511877132;3862.575116737157;4008.5590585035625;4013.979930100331;4055.5538890721996;4008.218397412535;4523.773811309421;4606.631469482433;4667.6732628716345;4687.218856834381;5357.678539287655;5594.916076258859;5618.410066942912;5626.971865140648;5646.426394620371;1123.2193625430248;1145.9885401145882;1262.2805001149256;1262.7474505099017;1272.7136394311206;708.5465302206592;808.7533302342724;823.9423240373236;850.6384786465727;863.7192912303526;4260.957390426056;4729.668923175344;4860.441049279308;4957.752112394366;4971.875703107379;4388.539203383662;4762.809487620457;5377.327834020747;5407.972485227794;5493.258410114663;4141.736770285092;4349.2382337216095;4745.762711864393;4901.632377571696;5000.499950004971;2459.8032157427365;2515.207709571479;2567.987160064225;2590.702069262059;2786.178077467152;4788.749287834712;5521.917171242435;5543.473370029841;5558.749856256478;5732.369439361667;4941.579965702884;5162;5223.571039466667;5366.436524164985;5524.944750552523;5010.540618849227;5631.8043057921295;5680.829575112753;5683.630564013326;5697.943020569741;4699.741514216614;5106.029854327666;5299.735013249399;5358.276632654542;5438.592105592175;5299.647356170372;5353.437889021752;5743.712814359349;5805.76776928935;5839.240898683144;5252.44849290819;5951.583389162717;6015.518758499222;6043.3050199227655;6076.055662188234;3268.6894744999186;3301.257217126127;3642.8724994625063;3859.4789703389683;3891.8637847675195;5396.622236443412;5469.316335458067;5731.595759039062;5763;5893.88016276906;4628.15368548347;5579.69311687861;5617.4101719320515;5623.634463759842;5638.069718496567;4820.975895120502;4832.903341933212;4949.901001979868;5359.946400535945;5613.129964855454;5021.698698078127;5054.494550544916;5327.547158491571;5529.723513824373;5550.944490555043;4604.378408914754;5548.4174161712435;5549.889002220014;5564.85920386328;5670.716464176692;4533.954660453353;5449.836504904859;5475.917861232005;5501.724913754216;5578.832635020955 05:41:23 INFO - PROCESS | 1668 | |5;yui.html;694.5936627073163;722.8337482379056;730.8172956760807;783.8941742864712;789.3606178995012;14837.925810370947;15014.549563513085;15054.774178387333;15060.171690557043;15094.622634434145;13286.800697989514;13437.462501499927;13442.327883605829;13452.991025673065;13461.865381346184;652.305294860974;670.9027191057294;707.6532499075441;800.1038836503095;817.5707753429438;823.2549542663899;830.0703212402088;835.8161204535023;842.153635596225;843.6119385082869;890.1543533643032;892.4734406700046;893.4639216470131;893.5934149961752;894.3426581462652;689.2556039477353;711.249631638621;876.789570503081;877.004599779252;878.4685265414396;637.5760119520513;652.7486917536783;653.4478365780908;654.5777973207255;654.6268626882679;12724.172928759694;12772.297523636196;12774.61676149717;12782.04134689902;12782.169159004636;12905.67735806603;13024;13132.737345253041;13204.801927971088;13264.270465124415;11948;11956.043516518657;12005.579804706793;12054.15620906537;12411.317377544232;4020.6783457323345;4155.21051000312;4336;4535.818567257305;4538.8865278367975;76077;77713.61143194305;85542.57228713817;85972;86276.56861715714;10292.948535257276;10398.740031499274;10434.530446129942;10688;10746.462676866126;19635.116419761143;19636.410907672507;19771.604567908562;19787.901060494893;19940.70088948667;7338.96330518344;7356.926430735732;7454.180040195574;7534.962325188339;7609.695612175562;3786.7159963002887;3963.583823698528;3974.682025437958;4035.051762835731;4063.959360406417;3193.5050067239613;3237.6438591754886;3258.657840926716;3281.097698132985;3283.5731354924183;537.4356925228494;546.0696438091328;573.7934343636288;595.779561562216;610.1305639463747;579.643519235667;604.7490291529055;611.984700382494;612.5099920064021;620.8416853702277;7807.960960195162;8095.797605059921;8133.79665508367;8155.76234219823;8232.547209903392;584.780707234787;595.184597101973;604.0757640809568;610.9083637454329;620.419907386595;3875.9418608720894;3916.686665066788;3920.4903362562686;3941.467901833273;4118.382242663624;115047.42476287566;119337.40331298287;119984.40007799905;121313;121908;66476.33523664798;66688.33311666822;89610;101293;102309.48845255875;68701;87639;89365.10634893567;89837;89889.202215954;7664.271894170045;7677.347425468898;7705.460617756928;7819.491733037562;7960.880586791319;50864.237036443745;52256.21615675692;52664;53562.19656705075;54639.72680136574;55033;67839;74712;76275;76643.61678191573;11488.36813975206;11764.529418823151;11988.700282493008;12009.159358845147;12027.034430548722;12949.223046617233;13101.502174656727;13149.53976610814;13467.191968481922;13582.524611638546;11972.58095966637;12153.513859445524;12288.38558072075;12452.626421207378;12567;11450.271067029555;11988.280703157838;12001.519939202333;12067.939660301643;12379.814302785286;11548.782540672586;11791.410429478663;12024.038076953648;12380.504779809067;12449.066320026215;9409.670661526814;10290.845637315297;10530.736731581772;10537;10899.564017439214;8598.312135029308;8667.739967800977;8724.302055835646;8862.822743545223;10155.949220253851 05:41:23 INFO - PROCESS | 1668 | __end_tp_report 05:41:23 INFO - PROCESS | 1668 | __start_cc_report 05:41:23 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1703 05:41:23 INFO - PROCESS | 1668 | __end_cc_report 05:41:23 INFO - PROCESS | 1668 | __startTimestamp1478439683300__endTimestamp 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | ------- Summary: start ------- 05:41:23 INFO - PROCESS | 1668 | Number of tests: 6 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5738.15 Median:6010.79 stddev:688.11 (11.4%) stddev-sans-first:652.44 05:41:23 INFO - PROCESS | 1668 | Values: 2871.5 3075.1 3353.8 3358.9 3390.8 5614.3 5695.8 5728.3 5815.4 5816.7 5689.8 5718.9 5796.0 5812.9 5821.0 5143.1 5155.0 5188.1 5198.4 5254.7 5293.9 5362.7 5366.1 5367.1 5368.6 5138.5 5289.4 5291.6 5310.0 5390.9 5160.0 5217.7 5221.6 5221.6 5224.7 5154.0 5176.9 5186.7 5187.7 5208.6 5696.4 5710.1 5718.5 5727.1 5742.0 6173.8 6182.0 6185.5 6188.1 6188.3 6164.2 6187.0 6216.4 6254.7 6255.5 4779.5 5773.9 5777.0 5781.4 5785.2 6212.3 6292.8 6305.3 6308.7 6318.2 6225.1 6227.9 6229.4 6233.2 6236.5 6111.5 6148.0 6177.3 6205.2 6277.5 6204.8 6219.7 6259.8 6263.4 6265.8 6270.9 6276.3 6288.7 6289.5 6298.4 4853.6 4907.0 4943.1 4945.6 4983.4 3638.4 4291.6 4360.5 4385.9 4417.5 5902.9 5903.0 5905.2 5909.8 5950.6 6273.3 6315.2 6332.4 6353.0 6354.9 4744.4 5763.4 5788.3 5818.4 5898.7 5409.0 5436.5 5440.6 5441.1 5494.1 6307.8 6309.5 6313.0 6324.4 6330.5 6160.6 6240.5 6332.6 6339.0 6347.9 5288.8 6202.6 6279.7 6281.4 6289.3 6176.0 6232.8 6245.2 6246.5 6264.7 5276.3 6274.7 6283.4 6322.0 6330.8 5887.9 5969.9 6083.5 6092.5 6112.4 6116.3 6133.0 6154.3 6174.2 6176.4 6045.7 6050.9 6051.3 6056.6 6066.5 4739.3 5997.5 6052.5 6135.7 6187.1 5915.2 6010.8 6043.5 6051.9 6058.7 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:64861.33 Median:39059.30 stddev:139213.63 (356.4%) stddev-sans-first:139520.22 05:41:23 INFO - PROCESS | 1668 | Values: 471.7 481.6 489.8 493.9 520.1 29570.0 38278.0 39262.8 39381.8 39471.4 40452.8 40655.2 40658.0 40719.0 40891.8 9195.0 9367.9 9400.1 9403.5 9407.5 9289.3 9392.2 9412.1 9416.7 9417.0 8062.0 8066.5 9413.2 9430.5 9444.2 8044.8 9367.9 9384.5 9395.4 9424.9 9208.6 9293.0 9300.3 9317.8 9322.6 38288.2 38595.6 38645.2 38670.8 38855.8 3200.8 3509.3 3562.5 3564.8 3825.8 26920.0 30081.7 30437.0 30526.0 30615.1 3102.6 3110.6 3286.6 3322.3 3328.0 426110.9 429229.0 431613.0 434515.8 437780.0 59336.7 59732.0 59881.7 59917.7 59963.4 60845.1 60849.4 61041.0 61118.0 61146.1 15367.7 15603.2 15604.5 15629.3 15631.6 11922.5 11958.6 11971.8 11991.9 11993.9 17307.5 18075.3 18086.7 18093.9 18105.9 6031.5 7186.8 7245.9 7323.2 7494.4 675.6 833.2 850.0 856.7 1109.5 26028.2 26812.7 27184.6 28280.0 28822.0 972.0 984.8 1046.7 1066.5 1085.8 14153.4 14662.4 14822.2 14855.9 14896.6 591972.0 593022.0 594980.0 595742.0 598069.0 45422.0 54222.2 55040.9 55071.4 55189.0 1120.3 1191.6 1236.5 1276.6 1315.7 28098.7 29295.9 29395.9 30163.2 30240.0 46704.0 47365.0 47387.8 47398.0 47469.0 520942.0 525615.0 526078.0 526185.0 527818.0 22911.8 25191.0 25789.9 25883.1 26525.5 43288.6 43780.6 44519.3 44671.6 44981.1 36256.1 36380.6 36575.3 37324.8 37622.8 34842.8 36005.5 36535.0 37551.1 37622.0 36066.8 36620.0 36829.0 37097.6 37314.3 25087.4 31793.0 32354.8 32428.4 33048.8 24590.9 24613.6 24738.6 24897.9 25144.9 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34331.80 Median:18816.42 stddev:99236.14 (527.4%) stddev-sans-first:99481.72 05:41:23 INFO - PROCESS | 1668 | Values: 350.3 356.6 374.4 388.3 408.7 14656.9 14828.9 15613.8 16040.1 20503.0 15991.8 16653.0 19858.1 20393.0 20418.0 5674.5 6042.7 6048.2 6056.6 6057.2 5680.7 5868.8 5921.8 5979.8 6000.9 5924.8 5928.2 5936.5 5937.1 5937.3 5633.0 5861.9 5939.0 5940.8 5941.8 4967.7 5190.7 5198.9 5200.9 5208.4 24799.8 25219.0 25270.0 25496.7 25618.4 2319.7 2677.2 2898.8 3071.3 3164.1 14450.8 15551.9 15773.7 15866.8 15912.6 1678.6 1827.2 1830.3 1882.8 1918.4 115772.4 120300.4 120663.4 121552.0 121721.4 9377.3 11116.7 11484.2 11493.9 11517.5 14584.8 14625.7 14641.5 14667.8 14697.9 8027.1 8276.6 8289.4 8449.7 8462.4 6905.3 7861.3 8074.0 8077.4 8234.7 4533.0 4534.2 4538.0 4542.8 4597.6 1874.2 1943.9 1950.8 1976.4 1976.8 828.3 892.8 908.7 923.6 944.7 19964.8 20853.7 20897.9 20903.0 21204.6 865.5 866.4 873.1 901.7 937.5 7817.0 10259.0 10551.3 10917.9 11050.7 584472.0 587813.0 597061.0 600278.0 605696.0 79331.6 125051.0 127896.7 132696.0 135267.0 905.2 915.2 947.2 959.4 960.3 14198.9 14213.4 15258.0 18803.2 19229.3 37258.4 50999.0 51857.5 51951.0 52043.7 72670.3 73222.6 73672.0 74015.3 75524.0 7777.8 8000.7 8073.5 8092.0 8381.5 12176.7 12376.7 12422.6 12643.7 12656.9 10581.6 10738.8 11058.6 11107.8 11122.6 7798.4 10657.3 10956.4 10960.7 11148.8 10967.2 11049.0 11053.4 11251.7 11289.4 7879.1 7960.7 7972.8 8006.0 8274.3 6774.5 9216.2 9476.6 10843.4 10964.1 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5414.90 Median:5189.65 stddev:2993.49 (57.7%) stddev-sans-first:2987.44 05:41:23 INFO - PROCESS | 1668 | Values: 1500.9 1516.2 1710.8 1811.5 1877.1 8005.7 8393.5 8397.4 8408.0 8418.8 7377.5 7618.7 7632.3 7678.3 8196.9 2106.6 2141.8 2212.3 2285.8 2294.9 2310.4 2311.7 2312.4 2317.5 2317.9 2260.8 2261.3 2321.9 2322.2 2329.6 2288.9 2290.0 2314.5 2315.5 2319.7 2304.6 2308.4 2332.9 2332.9 2335.9 8270.9 8275.5 8364.9 8376.1 8412.7 5050.3 5220.1 5221.0 5228.4 5240.3 3041.0 3391.1 3462.0 3465.2 3499.4 1057.6 1059.8 1065.7 1093.9 1094.6 10835.0 10915.2 10928.3 10929.3 10953.7 3959.3 4358.2 4392.1 4597.3 4683.6 9395.8 9406.4 9410.5 9419.2 9423.0 5183.3 5185.3 5194.0 5194.4 5203.1 4618.9 4685.3 4750.2 4755.0 4775.1 3485.8 3607.5 3621.4 3672.8 3731.1 1256.8 1265.2 1268.4 1278.5 1280.9 841.6 858.2 863.2 907.5 912.1 5384.7 5453.9 5657.4 5734.7 5755.5 889.7 969.2 978.6 1015.1 1041.6 2967.8 3527.4 3587.2 3595.7 3688.6 9747.4 10249.1 10254.0 10258.9 10268.7 8765.3 9595.5 9989.7 10123.9 10129.8 7682.4 7890.5 7891.3 7902.2 7907.1 5434.6 5460.6 5489.4 5668.3 5778.1 9561.2 9567.6 9583.4 9584.5 9599.6 9210.0 9557.3 9568.8 9575.9 9642.8 7602.0 7787.0 7833.9 7839.3 7841.0 7580.2 7640.2 7687.9 7706.5 7769.2 6883.1 6943.3 7146.4 7155.6 7167.9 6710.1 6882.8 6923.7 6925.8 6983.2 5477.3 6731.2 6732.8 6737.6 6739.1 4509.4 4614.3 4643.1 4766.3 4807.9 3865.8 4318.8 4329.6 4729.0 4799.3 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4414.25 Median:4932.98 stddev:1366.07 (27.7%) stddev-sans-first:1334.62 05:41:23 INFO - PROCESS | 1668 | Values: 304.5 304.6 314.1 319.5 320.0 5280.3 5341.4 5349.0 5375.7 5419.2 4924.4 5054.1 5128.0 5134.5 5325.8 3229.8 3445.1 4049.3 4069.7 4075.7 4022.7 4029.2 4056.3 4059.5 4060.5 3872.1 4005.2 4013.4 4023.2 4023.8 3597.8 3787.0 4005.2 4006.7 4043.6 3505.4 3521.8 3524.3 3526.9 3528.3 4557.6 4953.5 4965.7 5057.2 5089.2 4022.0 4504.2 4578.2 4605.9 4640.5 4739.0 5496.9 5500.6 5523.4 5530.5 3904.8 3905.7 3909.0 3913.9 3919.3 4313.9 4366.0 5028.8 5212.6 5756.5 3309.4 3563.9 3712.7 3857.7 3950.7 3331.2 3862.6 4008.6 4014.0 4055.6 4008.2 4523.8 4606.6 4667.7 4687.2 5357.7 5594.9 5618.4 5627.0 5646.4 1123.2 1146.0 1262.3 1262.7 1272.7 708.5 808.8 823.9 850.6 863.7 4261.0 4729.7 4860.4 4957.8 4971.9 4388.5 4762.8 5377.3 5408.0 5493.3 4141.7 4349.2 4745.8 4901.6 5000.5 2459.8 2515.2 2568.0 2590.7 2786.2 4788.7 5521.9 5543.5 5558.7 5732.4 4941.6 5162.0 5223.6 5366.4 5524.9 5010.5 5631.8 5680.8 5683.6 5697.9 4699.7 5106.0 5299.7 5358.3 5438.6 5299.6 5353.4 5743.7 5805.8 5839.2 5252.4 5951.6 6015.5 6043.3 6076.1 3268.7 3301.3 3642.9 3859.5 3891.9 5396.6 5469.3 5731.6 5763.0 5893.9 4628.2 5579.7 5617.4 5623.6 5638.1 4821.0 4832.9 4949.9 5359.9 5613.1 5021.7 5054.5 5327.5 5529.7 5550.9 4604.4 5548.4 5549.9 5564.9 5670.7 4534.0 5449.8 5475.9 5501.7 5578.8 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:19936.65 Median:4537.35 stddev:29877.52 (658.5%) stddev-sans-first:29926.40 05:41:23 INFO - PROCESS | 1668 | Values: 694.6 722.8 730.8 783.9 789.4 14837.9 15014.5 15054.8 15060.2 15094.6 13286.8 13437.5 13442.3 13453.0 13461.9 652.3 670.9 707.7 800.1 817.6 823.3 830.1 835.8 842.2 843.6 890.2 892.5 893.5 893.6 894.3 689.3 711.2 876.8 877.0 878.5 637.6 652.7 653.4 654.6 654.6 12724.2 12772.3 12774.6 12782.0 12782.2 12905.7 13024.0 13132.7 13204.8 13264.3 11948.0 11956.0 12005.6 12054.2 12411.3 4020.7 4155.2 4336.0 4535.8 4538.9 76077.0 77713.6 85542.6 85972.0 86276.6 10292.9 10398.7 10434.5 10688.0 10746.5 19635.1 19636.4 19771.6 19787.9 19940.7 7339.0 7356.9 7454.2 7535.0 7609.7 3786.7 3963.6 3974.7 4035.1 4064.0 3193.5 3237.6 3258.7 3281.1 3283.6 537.4 546.1 573.8 595.8 610.1 579.6 604.7 612.0 612.5 620.8 7808.0 8095.8 8133.8 8155.8 8232.5 584.8 595.2 604.1 610.9 620.4 3875.9 3916.7 3920.5 3941.5 4118.4 115047.4 119337.4 119984.4 121313.0 121908.0 66476.3 66688.3 89610.0 101293.0 102309.5 68701.0 87639.0 89365.1 89837.0 89889.2 7664.3 7677.3 7705.5 7819.5 7960.9 50864.2 52256.2 52664.0 53562.2 54639.7 55033.0 67839.0 74712.0 76275.0 76643.6 11488.4 11764.5 11988.7 12009.2 12027.0 12949.2 13101.5 13149.5 13467.2 13582.5 11972.6 12153.5 12288.4 12452.6 12567.0 11450.3 11988.3 12001.5 12067.9 12379.8 11548.8 11791.4 12024.0 12380.5 12449.1 9409.7 10290.8 10530.7 10537.0 10899.6 8598.3 8667.7 8724.3 8862.8 10155.9 05:41:23 INFO - PROCESS | 1668 | -------- Summary: end -------- 05:41:23 INFO - PROCESS | 1668 | 05:41:23 INFO - TEST-INFO | 1668: exit 0 05:41:23 INFO - TEST-OK | dromaeo_css | took 1119113ms 05:41:23 INFO - TEST-START | kraken 05:41:23 INFO - operating with platform_type : mac_ 05:41:23 INFO - Initialising browser for kraken test... 05:41:23 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpEsHWDg/profile http://localhost:49280/getInfo.html) 05:41:26 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 05:41:26 INFO - PROCESS | 1675 | colorDepth:24 05:41:26 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 05:41:26 INFO - PROCESS | 1675 | __metrics 05:41:26 INFO - PROCESS | 1675 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:41:26 INFO - PROCESS | 1675 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:41:26 INFO - TEST-INFO | 1675: exit 0 05:41:26 INFO - Browser initialized. 05:41:26 INFO - Running cycle 1/1 for kraken test... 05:41:26 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpEsHWDg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:38 INFO - PROCESS | 1677 | RSS: Main: 238981120 05:41:38 INFO - PROCESS | 1677 | 05:41:41 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 05:41:42 INFO - PROCESS | 1677 | RSS: Main: 289685504 05:41:42 INFO - PROCESS | 1677 | 05:41:45 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 05:41:45 INFO - PROCESS | 1677 | RSS: Main: 289681408 05:41:45 INFO - PROCESS | 1677 | 05:41:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 05:41:49 INFO - PROCESS | 1677 | RSS: Main: 381865984 05:41:49 INFO - PROCESS | 1677 | 05:41:51 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 05:41:52 INFO - PROCESS | 1677 | RSS: Main: 283590656 05:41:52 INFO - PROCESS | 1677 | 05:41:54 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:41:55 INFO - PROCESS | 1677 | RSS: Main: 289021952 05:41:55 INFO - PROCESS | 1677 | 05:41:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 05:41:58 INFO - PROCESS | 1677 | RSS: Main: 324558848 05:41:58 INFO - PROCESS | 1677 | 05:42:01 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 05:42:01 INFO - PROCESS | 1677 | RSS: Main: 328409088 05:42:01 INFO - PROCESS | 1677 | 05:42:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 05:42:05 INFO - PROCESS | 1677 | RSS: Main: 325570560 05:42:05 INFO - PROCESS | 1677 | 05:42:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:42:07 INFO - PROCESS | 1677 | RSS: Main: 302465024 05:42:07 INFO - PROCESS | 1677 | 05:42:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:42:09 INFO - PROCESS | 1677 | RSS: Main: 306470912 05:42:09 INFO - PROCESS | 1677 | 05:42:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:42:12 INFO - PROCESS | 1677 | RSS: Main: 310317056 05:42:12 INFO - PROCESS | 1677 | 05:42:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:42:16 INFO - PROCESS | 1677 | RSS: Main: 316399616 05:42:16 INFO - PROCESS | 1677 | 05:42:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:19 INFO - PROCESS | 1677 | RSS: Main: 316612608 05:42:19 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 05:42:21 INFO - PROCESS | 1677 | RSS: Main: 359870464 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | __start_tp_report 05:42:21 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:42:21 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:42:21 INFO - PROCESS | 1677 | |i|pagename|runs| 05:42:21 INFO - PROCESS | 1677 | |0;ai-astar;114;114;115;113;113;115;114;114;114;114 05:42:21 INFO - PROCESS | 1677 | |1;audio-beat-detection;148;113;113;149;113;113;111;128;176;115 05:42:21 INFO - PROCESS | 1677 | |2;audio-dft;182;185;185;186;184;184;182;182;182;186 05:42:21 INFO - PROCESS | 1677 | |3;audio-fft;79;59;59;86;59;60;59;59;82;58 05:42:21 INFO - PROCESS | 1677 | |4;audio-oscillator;86;72;91;67;68;68;89;68;68;91 05:42:21 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;89;86;87;84;87;84;87;84;87;84 05:42:21 INFO - PROCESS | 1677 | |6;imaging-darkroom;96;95;95;96;95;97;95;96;94;96 05:42:21 INFO - PROCESS | 1677 | |7;imaging-desaturate;84;82;83;82;83;84;84;86;82;85 05:42:21 INFO - PROCESS | 1677 | |8;json-parse-financial;55;55;52;53;53;55;57;57;56;55 05:42:21 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;66;66;85;113;109;78;69;69;70;71 05:42:21 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;87;84;86;88;90;89;86;85;89;93 05:42:21 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;153;157;153;147;145;146;146;140;188;156 05:42:21 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;178;174;175;173;174;173;171;175;173;173 05:42:21 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;68;68;68;67;67;65;67;69;66;66 05:42:21 INFO - PROCESS | 1677 | __end_tp_report 05:42:21 INFO - PROCESS | 1677 | __start_cc_report 05:42:21 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1948 05:42:21 INFO - PROCESS | 1677 | __end_cc_report 05:42:21 INFO - PROCESS | 1677 | __startTimestamp1478439741835__endTimestamp 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:42:21 INFO - PROCESS | 1677 | Number of tests: 14 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:42:21 INFO - PROCESS | 1677 | Values: 114.0 114.0 115.0 113.0 113.0 115.0 114.0 114.0 114.0 114.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:121.50 stddev:22.37 (18.4%) stddev-sans-first:22.51 05:42:21 INFO - PROCESS | 1677 | Values: 148.0 113.0 113.0 149.0 113.0 113.0 111.0 128.0 176.0 115.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.69 (0.9%) stddev-sans-first:1.66 05:42:21 INFO - PROCESS | 1677 | Values: 182.0 185.0 185.0 186.0 184.0 184.0 182.0 182.0 182.0 186.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:66.00 Median:59.50 stddev:11.40 (19.2%) stddev-sans-first:11.08 05:42:21 INFO - PROCESS | 1677 | Values: 79.0 59.0 59.0 86.0 59.0 60.0 59.0 59.0 82.0 58.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:79.00 stddev:10.88 (13.8%) stddev-sans-first:11.02 05:42:21 INFO - PROCESS | 1677 | Values: 86.0 72.0 91.0 67.0 68.0 68.0 89.0 68.0 68.0 91.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:1.79 (2.1%) stddev-sans-first:1.51 05:42:21 INFO - PROCESS | 1677 | Values: 89.0 86.0 87.0 84.0 87.0 84.0 87.0 84.0 87.0 84.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:96.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 05:42:21 INFO - PROCESS | 1677 | Values: 96.0 95.0 95.0 96.0 95.0 97.0 95.0 96.0 94.0 96.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:83.50 Median:84.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 05:42:21 INFO - PROCESS | 1677 | Values: 84.0 82.0 83.0 82.0 83.0 84.0 84.0 86.0 82.0 85.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.69 (3.1%) stddev-sans-first:1.79 05:42:21 INFO - PROCESS | 1677 | Values: 55.0 55.0 52.0 53.0 53.0 55.0 57.0 57.0 56.0 55.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:69.50 stddev:17.55 (25.3%) stddev-sans-first:17.91 05:42:21 INFO - PROCESS | 1677 | Values: 66.0 66.0 85.0 113.0 109.0 78.0 69.0 69.0 70.0 71.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.50 stddev:2.67 (3.0%) stddev-sans-first:2.82 05:42:21 INFO - PROCESS | 1677 | Values: 87.0 84.0 86.0 88.0 90.0 89.0 86.0 85.0 89.0 93.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:153.00 stddev:13.40 (8.8%) stddev-sans-first:14.22 05:42:21 INFO - PROCESS | 1677 | Values: 153.0 157.0 153.0 147.0 145.0 146.0 146.0 140.0 188.0 156.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.00 stddev:1.85 (1.1%) stddev-sans-first:1.24 05:42:21 INFO - PROCESS | 1677 | Values: 178.0 174.0 175.0 173.0 174.0 173.0 171.0 175.0 173.0 173.0 05:42:21 INFO - PROCESS | 1677 | 05:42:21 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:1.20 (1.8%) stddev-sans-first:1.22 05:42:21 INFO - PROCESS | 1677 | Values: 68.0 68.0 68.0 67.0 67.0 65.0 67.0 69.0 66.0 66.0 05:42:21 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:42:21 INFO - PROCESS | 1677 | 05:42:22 INFO - TEST-INFO | 1677: exit 0 05:42:22 INFO - TEST-OK | kraken | took 58798ms 05:42:22 INFO - SUITE-END | took 1177s 05:42:22 INFO - Completed test suite (00:19:38) 05:42:22 INFO - javascript benchmark 05:42:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2871.49748793961, 3075.0774767569724, 3353.8323083845803, 3358.908354784694, 3390.796552206866, 5614.27014488117, 5695.800646977356, 5728.283964504437, 5815.360310365862, 5816.680082595455, 5689.829305120853, 5718.857028574288, 5795.971020144893, 5812.912806307909, 5821.0, 5143.12566863634, 5155.0, 5188.143956247215, 5198.428172900978, 5254.737263136828, 5293.86765330866, 5362.731863406833, 5366.114591092465, 5367.114426119705, 5368.570514358861, 5138.486151384869, 5289.44460831611, 5291.550218231455, 5310.017646735367, 5390.9191362129595, 5160.0, 5217.686938783685, 5221.6083793715625, 5221.634485586011, 5224.660397074216, 5154.0, 5176.948230517684, 5186.740662966874, 5187.688738675692, 5208.5572726318305, 5696.401877802812, 5710.086386178192, 5718.513926316268, 5727.140928860705, 5741.971290143564, 6173.81478555644, 6181.979973304399, 6185.474234690057, 6188.071789231606, 6188.2883468400805, 6164.167837341946, 6187.0, 6216.440520353145, 6254.7498100075945, 6255.468285195764, 4779.474257831635, 5773.93182261283, 5777.0467872801355, 5781.364049954544, 5785.247917770663, 6212.316645169028, 6292.811215663537, 6305.337939516285, 6308.716107775161, 6318.210223722035, 6225.097360882737, 6227.906581401283, 6229.408206220402, 6233.2208473940755, 6236.501079913596, 6111.500579230221, 6148.0, 6177.289611694623, 6205.22434695663, 6277.52918531112, 6204.844878878064, 6219.720112594944, 6259.843503912348, 6263.404976527223, 6265.780697675559, 6270.874582508324, 6276.309605943343, 6288.654124023223, 6289.528285378615, 6298.370162983665, 4853.611711063107, 4907.018596280758, 4943.134951383494, 4945.629077819178, 4983.426905905795, 3638.399664055454, 4291.570842915684, 4360.520342762357, 4385.912281754347, 4417.469903611523, 5902.852428689317, 5903.0260007099205, 5905.202797622266, 5909.8227053188475, 5950.613210141413, 6273.309935907047, 6315.179026726496, 6332.366763323631, 6352.968235158887, 6354.872902541923, 4744.4069491313585, 5763.366029736726, 5788.27646544182, 5818.389069147764, 5898.705064746831, 5408.972955135199, 5436.53789427903, 5440.619156658996, 5441.102218133963, 5494.120940649557, 6307.810765677037, 6309.49524038085, 6312.968435157794, 6324.430801227728, 6330.4619107375465, 6160.630362178284, 6240.471896453708, 6332.620042797447, 6339.049142628643, 6347.936520634735, 5288.762005709676, 6202.5658203925295, 6279.686015699288, 6281.434670879643, 6289.308176100717, 6176.011838105882, 6232.75068997235, 6245.219347581552, 6246.531510136849, 6264.749410023548, 5276.34045744282, 6274.74901003973, 6283.434490895841, 6322.0, 6330.8100756977365, 5887.852803679943, 5969.91045134332, 6083.482903953213, 6092.543059270483, 6112.358202388863, 6116.326815364041, 6132.998044146438, 6154.261488621394, 6174.228221472316, 6176.413240742122, 6045.727942242697, 6050.909236361546, 6051.334353221054, 6056.606320589148, 6066.545009124245, 4739.312799644102, 5997.460228579449, 6052.546059045501, 6135.662538560422, 6187.133801267916, 5915.201447804663, 6010.789622363196, 6043.45608895184, 6051.878962420815, 6058.697065146813], "value": 5691.600055340578, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.6957562372272, 481.5641844131061, 489.8016303397125, 493.93331900193476, 520.1183993131643, 29570.0, 38278.043048923755, 39262.8036859816, 39381.80309098458, 39471.40792888109, 40452.79773601128, 40655.18689626206, 40658.0, 40719.0, 40891.79554102233, 9194.954025229865, 9367.906320936805, 9400.05999400058, 9403.529823508832, 9407.482588457631, 9289.349745517817, 9392.248620110375, 9412.105849944244, 9416.670416535388, 9416.952915235413, 8061.95969020157, 8066.516009039447, 9413.199878010377, 9430.481323527203, 9444.150026497613, 8044.839103217962, 9367.906320936772, 9384.530773461367, 9395.436273823592, 9424.858627120599, 9208.585613647401, 9293.024232455562, 9300.30247731416, 9317.813643727155, 9322.627094916263, 38288.23423531514, 38595.61404385948, 38645.22709545821, 38670.80664596659, 38855.8057209715, 3200.775945683803, 3509.3332266869215, 3562.5368702068563, 3564.803935783531, 3825.756629095555, 26920.0, 30081.699183008328, 30437.0, 30526.0, 30615.081547553607, 3102.565640810288, 3110.6422761382505, 3286.5727455430642, 3322.335532893431, 3327.96672033279, 426110.86944565387, 429229.0, 431613.0, 434515.827420864, 437780.0, 59336.703316484, 59732.0, 59881.70059149677, 59917.700411497666, 59963.400365996655, 60845.08732369018, 60849.39150608437, 61041.0, 61118.0, 61146.08280875701, 15367.692646147014, 15603.219839008005, 15604.453844115402, 15629.296681649354, 15631.609209769847, 11922.463489143009, 11958.64124076279, 11971.820422693667, 11991.940040299916, 11993.88006119945, 17307.53849230159, 18075.27698892056, 18086.728699069525, 18093.909530452263, 18105.909470452563, 6031.517478601701, 7186.820329491805, 7245.89131163033, 7323.231060738653, 7494.362979146725, 675.598019178588, 833.1834801894178, 849.9830003399898, 856.7044369692446, 1109.5284504085732, 26028.219153425427, 26812.731872681023, 27184.59223111655, 28280.0, 28822.0, 972.0473935543207, 984.7931934293736, 1046.7173863056933, 1066.4880857188439, 1085.7882712870914, 14153.43386264538, 14662.413503459955, 14822.184779837213, 14855.925720371546, 14896.553103406915, 591972.0, 593022.0, 594980.0, 595742.0, 598069.0, 45422.0, 54222.186667200025, 55040.89918201694, 55071.449285507435, 55189.0, 1120.294214644801, 1191.5650787462514, 1236.4744983382027, 1276.5914907229783, 1315.6710822294426, 28098.71901280961, 29295.85352073226, 29395.85302073476, 30163.245918852204, 30240.0, 46704.0, 47365.0, 47387.815304616284, 47398.0, 47469.015753345404, 520942.0, 525615.0, 526078.0, 526185.0, 527818.0, 22911.770882291632, 25191.0, 25789.87105064538, 25883.0940917067, 26525.469490609692, 43288.567114328456, 43780.56219437765, 44519.33221001752, 44671.55328446674, 44981.10037799291, 36256.09359766027, 36380.63619363772, 36575.26849463049, 37324.81337593294, 37622.8118859404, 34842.82578587091, 36005.45991810178, 36535.0, 37551.06122346963, 37622.0, 36066.8196659015, 36620.0, 36829.0, 37097.6290237105, 37314.25371492609, 25087.41650045523, 31793.0, 32354.83822580872, 32428.351432970736, 33048.834755827025, 24590.877045615372, 24613.63079553844, 24738.628920565847, 24897.879595417886, 25144.874275628506], "value": 18975.15982524664, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.30464527912096, 356.55074605996435, 374.40469653251324, 388.2603640065675, 408.65468678966283, 14656.853431465683, 14828.851711482883, 15613.765793513106, 16040.11779352135, 20502.974851257415, 15991.760123598126, 16653.000819950783, 19858.106385212664, 20392.980350982467, 20417.979101044963, 5674.517665998395, 6042.667653279068, 6048.243969503812, 6056.576039677202, 6057.151998720183, 5680.687562184078, 5868.823935281927, 5921.792737254197, 5979.820605381824, 6000.939990600104, 5924.763009479637, 5928.170056192136, 5936.495397891163, 5937.050071988483, 5937.346891841894, 5633.0, 5861.944849927033, 5939.0, 5940.821775346746, 5941.762329506814, 4967.726775027372, 5190.688558686491, 5198.896022079537, 5200.947990520084, 5208.437468759357, 24799.752002480105, 25219.0, 25269.98920043196, 25496.74503254962, 25618.359541011436, 2319.744828068911, 2677.196840947708, 2898.7825913056604, 3071.3396619726864, 3164.0982320038906, 14450.783238251435, 15551.922240388838, 15773.684526309524, 15866.761998570031, 15912.602184945354, 1678.622309980257, 1827.232562323827, 1830.2678928428602, 1882.8305452509203, 1918.424472658211, 115772.42113789462, 120300.39849800782, 120663.3966830169, 121552.0, 121721.39139304335, 9377.343585948918, 11116.666500005012, 11484.19610627248, 11493.942530287295, 11517.539298428137, 14584.781228281585, 14625.707485850224, 14641.48754793577, 14667.77998330026, 14697.85302146965, 8027.117017128111, 8276.627551760184, 8289.41974061822, 8449.746507604781, 8462.407631465738, 6905.309469053155, 7861.331786798186, 8073.959630201812, 8077.434579579373, 8234.670613175525, 4533.0, 4534.206513860061, 4538.0, 4542.750148741785, 4597.563231493003, 1874.2315650583196, 1943.8639295249197, 1950.775660798998, 1976.4268362174807, 1976.8418526517844, 828.3207769628847, 892.808046270055, 908.6728777640044, 923.5751554284969, 944.6835310171169, 19964.800351996295, 20853.68719469209, 20897.89551052235, 20902.954852257328, 21204.575908482053, 865.4547634989916, 866.4151697604092, 873.1137895036543, 901.7114523352595, 937.5452905340904, 7816.960915195501, 10259.0, 10551.26141170126, 10917.945410272898, 11050.673919129757, 584472.0, 587813.0, 597061.0146949324, 600278.0, 605695.9715201396, 79331.60334198408, 125051.0, 127896.72103278848, 132696.0, 135267.0, 905.158202871336, 915.1534830281962, 947.2422062350092, 959.3907868503551, 960.3373672166183, 14198.858011420167, 14213.431462741375, 15258.00822946505, 18803.247870085044, 19229.326973555857, 37258.44112338263, 50999.0, 51857.481425185266, 51951.0, 52043.73978130085, 72670.27329726846, 73222.63388683023, 73672.0, 74015.259847403, 75524.0, 7777.805554861174, 8000.719974800854, 8073.475224110414, 8092.0, 8381.455205411628, 12176.695582610506, 12376.690582735504, 12422.565210217599, 12643.683907902376, 12656.873431265938, 10581.576736930745, 10738.785224295314, 11058.612948546763, 11107.83338249943, 11122.61070862516, 7798.415118865994, 10657.25399222047, 10956.397398143179, 10960.725981850517, 11148.832767508657, 10967.177461690246, 11049.005589496985, 11053.392063436588, 11251.718707032062, 11289.435528223721, 7879.054513458422, 7960.681572737026, 7972.84054318922, 8006.0, 8274.310848973988, 6774.491913106635, 9216.170544651013, 9476.573554190216, 10843.403612801372, 10964.06805421548], "value": 9388.955656282495, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.8649221570056, 1516.2418790604697, 1710.8374704403077, 1811.5018369948255, 1877.0614692653674, 8005.7197998070005, 8393.496390216591, 8397.370197235217, 8408.0, 8418.789530261738, 7377.4835761496715, 7618.695252189906, 7632.328684960261, 7678.308952194301, 8196.91803081969, 2106.59974604825, 2141.8179454746287, 2212.3031245157804, 2285.7828506291876, 2294.9311520654405, 2310.4058199842057, 2311.6763653088583, 2312.387217387394, 2317.455397981473, 2317.9420514487106, 2260.762619924909, 2261.2650888461235, 2321.9071237150492, 2322.1640209524558, 2329.580675478413, 2288.9198878039274, 2289.988550057256, 2314.490812021344, 2315.490592069759, 2319.744828068911, 2304.5966955782837, 2308.3882771065682, 2332.8950197241165, 2332.9066837326486, 2335.8948847301913, 8270.883430736894, 8275.462094963868, 8364.874526882102, 8376.120507346763, 8412.663493460315, 5050.343455350818, 5220.138677118271, 5220.981908527839, 5228.398734145584, 5240.318758561362, 3041.0, 3391.0674564494716, 3462.0, 3465.220325426784, 3499.3701133795907, 1057.6245432871297, 1059.8039362717846, 1065.6856227412932, 1093.907017903479, 1094.63876920616, 10835.0, 10915.181361397848, 10928.289661172077, 10929.344239345664, 10953.671389858317, 3959.2675355059405, 4358.193734159127, 4392.07766369066, 4597.287420449836, 4683.648726345537, 9395.812083758286, 9406.435613863054, 9410.482423466776, 9419.152276295166, 9423.0, 5183.300254465675, 5185.325907631984, 5193.974030129825, 5194.4026436959475, 5203.063448579216, 4618.907621847544, 4685.320628508847, 4750.216214324667, 4754.976225118921, 4775.140474714584, 3485.825708714554, 3607.5310209672575, 3621.420572708354, 3672.8163591820835, 3731.0858839583934, 1256.7926292161874, 1265.1523479268913, 1268.3594784633885, 1278.5013844600612, 1280.8527019392702, 841.6338892581747, 858.1632907914733, 863.1972265792873, 907.5462268865567, 912.1334732004622, 5384.730763461811, 5453.863653408617, 5657.377688454267, 5734.713264336767, 5755.510781583528, 889.6708217959397, 969.1665167955553, 978.5841017567506, 1015.0712098429854, 1041.6250149946013, 2967.836768977727, 3527.4179760339257, 3587.210813620999, 3595.7303202259936, 3688.5798967397745, 9747.3664211826, 10249.12882404989, 10254.025867542572, 10258.897411025795, 10268.743281417876, 8765.342599305077, 9595.520223988913, 9989.650362237433, 10123.9493802532, 10129.848052279222, 7682.385409167253, 7890.487118337404, 7891.28978391936, 7902.209779022051, 7907.0906845712325, 5434.619576629598, 5460.645058071213, 5489.3687225969525, 5668.291463567054, 5778.104393818965, 9561.235101192027, 9567.617295308388, 9583.424994500352, 9584.520773961414, 9599.568019439002, 9210.0, 9557.330986830855, 9568.76078098053, 9575.904240957501, 9642.758931026781, 7602.0, 7787.0, 7833.92166078332, 7839.25527074927, 7841.0, 7580.204078571671, 7640.235976402538, 7687.884681729666, 7706.460547761603, 7769.223077692185, 6883.105196324445, 6943.270956549692, 7146.3568278855155, 7155.570665760071, 7167.8566428672175, 6710.1276834013215, 6882.827929301808, 6923.723051077901, 6925.792226233221, 6983.231844497, 5477.3427188737605, 6731.22590902033, 6732.764353247613, 6737.59574425536, 6739.0902228198565, 4509.3686883835635, 4614.2617181250835, 4643.141018911512, 4766.332713420194, 4807.927881081717, 3865.8453661854337, 4318.762468064161, 4329.610335069859, 4728.952710472989, 4799.28010798383], "value": 4393.251385981364, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.4793403280389, 304.55382864104087, 314.1470906488882, 319.514338205927, 320.0, 5280.339957505312, 5341.385740639827, 5348.973255133724, 5375.6774593524415, 5419.187121931712, 4924.384451943507, 5054.090263752523, 5128.0, 5134.512221338967, 5325.840224793263, 3229.7739158258933, 3445.0698311455953, 4049.2711311963835, 4069.674426045924, 4075.6943229257777, 4022.658074063708, 4029.234445455354, 4056.269871423143, 4059.4519739835187, 4060.512738471373, 3872.051347419872, 4005.1589166275116, 4013.3912956578165, 4023.2154729827694, 4023.8189281482255, 3597.7587732232337, 3787.0153760022317, 4005.1589166275116, 4006.6794656427414, 4043.575424580421, 3505.3515099706638, 3521.7886926784217, 3524.2951409717903, 3526.894193174209, 3528.311979164064, 4557.612602928755, 4953.455119936804, 4965.702057876503, 5057.216131499624, 5089.236614507817, 4021.9798901005306, 4504.211762941472, 4578.175928332898, 4605.861824145281, 4640.489546149955, 4738.976305118453, 5496.894254746056, 5500.624843789053, 5523.447655234485, 5530.474604912527, 3904.7852368119743, 3905.7070719696135, 3908.9804550977065, 3913.8630147944687, 3919.2553414851086, 4313.935290970638, 4366.0, 5028.767951851783, 5212.609054320903, 5756.510696590794, 3309.418653450313, 3563.910902227465, 3712.7215458840697, 3857.723942873662, 3950.6641935435237, 3331.200511877132, 3862.575116737157, 4008.5590585035625, 4013.979930100331, 4055.5538890721996, 4008.218397412535, 4523.773811309421, 4606.631469482433, 4667.6732628716345, 4687.218856834381, 5357.678539287655, 5594.916076258859, 5618.410066942912, 5626.971865140648, 5646.426394620371, 1123.2193625430248, 1145.9885401145882, 1262.2805001149256, 1262.7474505099017, 1272.7136394311206, 708.5465302206592, 808.7533302342724, 823.9423240373236, 850.6384786465727, 863.7192912303526, 4260.957390426056, 4729.668923175344, 4860.441049279308, 4957.752112394366, 4971.875703107379, 4388.539203383662, 4762.809487620457, 5377.327834020747, 5407.972485227794, 5493.258410114663, 4141.736770285092, 4349.2382337216095, 4745.762711864393, 4901.632377571696, 5000.499950004971, 2459.8032157427365, 2515.207709571479, 2567.987160064225, 2590.702069262059, 2786.178077467152, 4788.749287834712, 5521.917171242435, 5543.473370029841, 5558.749856256478, 5732.369439361667, 4941.579965702884, 5162.0, 5223.571039466667, 5366.436524164985, 5524.944750552523, 5010.540618849227, 5631.8043057921295, 5680.829575112753, 5683.630564013326, 5697.943020569741, 4699.741514216614, 5106.029854327666, 5299.735013249399, 5358.276632654542, 5438.592105592175, 5299.647356170372, 5353.437889021752, 5743.712814359349, 5805.76776928935, 5839.240898683144, 5252.44849290819, 5951.583389162717, 6015.518758499222, 6043.3050199227655, 6076.055662188234, 3268.6894744999186, 3301.257217126127, 3642.8724994625063, 3859.4789703389683, 3891.8637847675195, 5396.622236443412, 5469.316335458067, 5731.595759039062, 5763.0, 5893.88016276906, 4628.15368548347, 5579.69311687861, 5617.4101719320515, 5623.634463759842, 5638.069718496567, 4820.975895120502, 4832.903341933212, 4949.901001979868, 5359.946400535945, 5613.129964855454, 5021.698698078127, 5054.494550544916, 5327.547158491571, 5529.723513824373, 5550.944490555043, 4604.378408914754, 5548.4174161712435, 5549.889002220014, 5564.85920386328, 5670.716464176692, 4533.954660453353, 5449.836504904859, 5475.917861232005, 5501.724913754216, 5578.832635020955], "value": 3975.2159962098362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.5936627073163, 722.8337482379056, 730.8172956760807, 783.8941742864712, 789.3606178995012, 14837.925810370947, 15014.549563513085, 15054.774178387333, 15060.171690557043, 15094.622634434145, 13286.800697989514, 13437.462501499927, 13442.327883605829, 13452.991025673065, 13461.865381346184, 652.305294860974, 670.9027191057294, 707.6532499075441, 800.1038836503095, 817.5707753429438, 823.2549542663899, 830.0703212402088, 835.8161204535023, 842.153635596225, 843.6119385082869, 890.1543533643032, 892.4734406700046, 893.4639216470131, 893.5934149961752, 894.3426581462652, 689.2556039477353, 711.249631638621, 876.789570503081, 877.004599779252, 878.4685265414396, 637.5760119520513, 652.7486917536783, 653.4478365780908, 654.5777973207255, 654.6268626882679, 12724.172928759694, 12772.297523636196, 12774.61676149717, 12782.04134689902, 12782.169159004636, 12905.67735806603, 13024.0, 13132.737345253041, 13204.801927971088, 13264.270465124415, 11948.0, 11956.043516518657, 12005.579804706793, 12054.15620906537, 12411.317377544232, 4020.6783457323345, 4155.21051000312, 4336.0, 4535.818567257305, 4538.8865278367975, 76077.0, 77713.61143194305, 85542.57228713817, 85972.0, 86276.56861715714, 10292.948535257276, 10398.740031499274, 10434.530446129942, 10688.0, 10746.462676866126, 19635.116419761143, 19636.410907672507, 19771.604567908562, 19787.901060494893, 19940.70088948667, 7338.96330518344, 7356.926430735732, 7454.180040195574, 7534.962325188339, 7609.695612175562, 3786.7159963002887, 3963.583823698528, 3974.682025437958, 4035.051762835731, 4063.959360406417, 3193.5050067239613, 3237.6438591754886, 3258.657840926716, 3281.097698132985, 3283.5731354924183, 537.4356925228494, 546.0696438091328, 573.7934343636288, 595.779561562216, 610.1305639463747, 579.643519235667, 604.7490291529055, 611.984700382494, 612.5099920064021, 620.8416853702277, 7807.960960195162, 8095.797605059921, 8133.79665508367, 8155.76234219823, 8232.547209903392, 584.780707234787, 595.184597101973, 604.0757640809568, 610.9083637454329, 620.419907386595, 3875.9418608720894, 3916.686665066788, 3920.4903362562686, 3941.467901833273, 4118.382242663624, 115047.42476287566, 119337.40331298287, 119984.40007799905, 121313.0, 121908.0, 66476.33523664798, 66688.33311666822, 89610.0, 101293.0, 102309.48845255875, 68701.0, 87639.0, 89365.10634893567, 89837.0, 89889.202215954, 7664.271894170045, 7677.347425468898, 7705.460617756928, 7819.491733037562, 7960.880586791319, 50864.237036443745, 52256.21615675692, 52664.0, 53562.19656705075, 54639.72680136574, 55033.0, 67839.0, 74712.0, 76275.0, 76643.61678191573, 11488.36813975206, 11764.529418823151, 11988.700282493008, 12009.159358845147, 12027.034430548722, 12949.223046617233, 13101.502174656727, 13149.53976610814, 13467.191968481922, 13582.524611638546, 11972.58095966637, 12153.513859445524, 12288.38558072075, 12452.626421207378, 12567.0, 11450.271067029555, 11988.280703157838, 12001.519939202333, 12067.939660301643, 12379.814302785286, 11548.782540672586, 11791.410429478663, 12024.038076953648, 12380.504779809067, 12449.066320026215, 9409.670661526814, 10290.845637315297, 10530.736731581772, 10537.0, 10899.564017439214, 8598.312135029308, 8667.739967800977, 8724.302055835646, 8862.822743545223, 10155.949220253851], "value": 7001.767360969078, "unit": "score"}], "name": "dromaeo_css", "value": 7061.652712623515, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 113.0, 113.0, 115.0, 114.0, 114.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 113.0, 113.0, 149.0, 113.0, 113.0, 111.0, 128.0, 176.0, 115.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 185.0, 186.0, 184.0, 184.0, 182.0, 182.0, 182.0, 186.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 86.0, 59.0, 60.0, 59.0, 59.0, 82.0, 58.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 91.0, 67.0, 68.0, 68.0, 89.0, 68.0, 68.0, 91.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 97.0, 95.0, 96.0, 94.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 83.0, 82.0, 83.0, 84.0, 84.0, 86.0, 82.0, 85.0], "value": 83.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 52.0, 53.0, 53.0, 55.0, 57.0, 57.0, 56.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 85.0, 113.0, 109.0, 78.0, 69.0, 69.0, 70.0, 71.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 88.0, 90.0, 89.0, 86.0, 85.0, 89.0, 93.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 157.0, 153.0, 147.0, 145.0, 146.0, 146.0, 140.0, 188.0, 156.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 174.0, 175.0, 173.0, 174.0, 173.0, 171.0, 175.0, 173.0, 173.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 67.0, 65.0, 67.0, 69.0, 66.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1449.6, "alertThreshold": 2.0}]} 05:42:23 INFO - Return code: 0 05:42:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:42:23 INFO - # TBPL SUCCESS # 05:42:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:23 INFO - 2016-11-06 05:42:23.166 screenresolution[1681:12151] starting screenresolution argv=screenresolution get 05:42:23 INFO - 2016-11-06 05:42:23.169 screenresolution[1681:12151] Display 0: 1600x1200x32@60 05:42:23 INFO - 2016-11-06 05:42:23.175 screenresolution[1682:12154] starting screenresolution argv=screenresolution list 05:42:23 INFO - Available Modes on Display 0 05:42:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:23 INFO - Intel Iris: 05:42:23 INFO - Chipset Model: Intel Iris 05:42:23 INFO - Type: GPU 05:42:23 INFO - Bus: Built-In 05:42:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:23 INFO - Vendor: Intel (0x8086) 05:42:23 INFO - Device ID: 0x0a2e 05:42:23 INFO - Revision ID: 0x0009 05:42:23 INFO - Displays: 05:42:23 INFO - Display: 05:42:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:23 INFO - Display Serial Number: 1600x1200 60 05:42:23 INFO - Main Display: Yes 05:42:23 INFO - Mirror: Off 05:42:23 INFO - Online: Yes 05:42:23 INFO - Rotation: Supported 05:42:23 INFO - Return code: 0 05:42:23 INFO - Running post-action listener: _resource_record_post_action 05:42:23 INFO - [mozharness: 2016-11-06 13:42:23.423055Z] Finished run-tests step (success) 05:42:23 INFO - Running post-run listener: _resource_record_post_run 05:42:23 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 33139712; Write bytes: 706638848; Read time: 306; Write time: 1855 05:42:23 INFO - TinderboxPrint: CPU usage
53.5% 05:42:23 INFO - TinderboxPrint: I/O read bytes / time
33,139,712 / 306 05:42:23 INFO - TinderboxPrint: I/O write bytes / time
706,638,848 / 1,855 05:42:23 INFO - TinderboxPrint: CPU idle
2,258.4 (46.5%) 05:42:23 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 05:42:23 INFO - TinderboxPrint: CPU user
1,626.2 (33.5%) 05:42:23 INFO - TinderboxPrint: Swap in / out
432,115,712 / 0 05:42:23 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398731776; Write bytes: 392697344; Read time: 29569; Write time: 701 05:42:23 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 31063040; Write bytes: 299802112; Read time: 285; Write time: 1115 05:42:23 INFO - Running post-run listener: _upload_blobber_files 05:42:23 INFO - Blob upload gear active. 05:42:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:42:23 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 05:42:23 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'] 05:42:23 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 05:42:24 INFO - (blobuploader) - INFO - Open directory for files ... 05:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:25 INFO - (blobuploader) - INFO - Done attempting. 05:42:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:42:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:42:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:26 INFO - (blobuploader) - INFO - Done attempting. 05:42:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:42:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:42:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:28 INFO - (blobuploader) - INFO - Done attempting. 05:42:28 INFO - (blobuploader) - INFO - Iteration through files over. 05:42:28 INFO - Return code: 0 05:42:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:42:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:42:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a6ea9feaab700cb458fc7a983f55002078652efbfff9446da46b70b2bf0405b7815ca92a960cecfe217226ef05d602af7e458d981c65133b1d1c7e469489587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a671804a7845c91b19e0706b463e4b4def8fbe7cc539e66feabad6e93ba18766cafb2273adac9647173b9a3cb44f9d8e98770740770d5e411bee244f264722", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1c958c10b7dafa08217269045774b1bd18f8e7b93b384e43ebb39d0e08e4cc21f30af5a5752bc575d2d1c07e0edf737147a1d18a590a0df289da4519e786c17"} 05:42:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:42:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:42:28 INFO - Contents: 05:42:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a6ea9feaab700cb458fc7a983f55002078652efbfff9446da46b70b2bf0405b7815ca92a960cecfe217226ef05d602af7e458d981c65133b1d1c7e469489587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a671804a7845c91b19e0706b463e4b4def8fbe7cc539e66feabad6e93ba18766cafb2273adac9647173b9a3cb44f9d8e98770740770d5e411bee244f264722", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1c958c10b7dafa08217269045774b1bd18f8e7b93b384e43ebb39d0e08e4cc21f30af5a5752bc575d2d1c07e0edf737147a1d18a590a0df289da4519e786c17"} 05:42:28 INFO - Running post-run listener: copy_logs_to_upload_dir 05:42:28 INFO - Copying logs to upload dir... 05:42:28 INFO - mkdir: /builds/slave/test/build/upload/logs 05:42:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.624404 ========= master_lag: 1.65 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-06 05:42:30.168419) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 05:42:30.172762) ========= 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.hoHCbT9BxF/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.cDX16I2geQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a6ea9feaab700cb458fc7a983f55002078652efbfff9446da46b70b2bf0405b7815ca92a960cecfe217226ef05d602af7e458d981c65133b1d1c7e469489587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a671804a7845c91b19e0706b463e4b4def8fbe7cc539e66feabad6e93ba18766cafb2273adac9647173b9a3cb44f9d8e98770740770d5e411bee244f264722", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1c958c10b7dafa08217269045774b1bd18f8e7b93b384e43ebb39d0e08e4cc21f30af5a5752bc575d2d1c07e0edf737147a1d18a590a0df289da4519e786c17"} build_url:https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010727 build_url: 'https://queue.taskcluster.net/v1/task/A4nVJoQ9S5WMNMTdEdSw-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a6ea9feaab700cb458fc7a983f55002078652efbfff9446da46b70b2bf0405b7815ca92a960cecfe217226ef05d602af7e458d981c65133b1d1c7e469489587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a671804a7845c91b19e0706b463e4b4def8fbe7cc539e66feabad6e93ba18766cafb2273adac9647173b9a3cb44f9d8e98770740770d5e411bee244f264722", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1c958c10b7dafa08217269045774b1bd18f8e7b93b384e43ebb39d0e08e4cc21f30af5a5752bc575d2d1c07e0edf737147a1d18a590a0df289da4519e786c17"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 05:42:30.212851) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 05:42:30.213244) ========= 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.hoHCbT9BxF/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.cDX16I2geQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.004996 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 05:42:30.262251) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 05:42:30.265647) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 05:42:30.266006) ========= ========= Total master_lag: 1.78 =========