builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1475684174.6 results: success (0) buildid: 20161005064400 builduid: d1d328e664484416baeb562142a7cc48 revision: e8fa13708c070d1fadf488ed9d951464745b4e17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.601352) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.601830) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.602106) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.627538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.627886) ========= 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.lj3riFmeqf/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.oPKXAB5dVR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-05 09:16:14-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 58.5M=0s 2016-10-05 09:16:14 (58.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132414 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.782881) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.783196) ========= 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.lj3riFmeqf/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.oPKXAB5dVR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.029040 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:14.833850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 09:16:14.834211) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e8fa13708c070d1fadf488ed9d951464745b4e17 --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 e8fa13708c070d1fadf488ed9d951464745b4e17 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lj3riFmeqf/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.oPKXAB5dVR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-05 09:16:14,907 truncating revision to first 12 chars 2016-10-05 09:16:14,907 Setting DEBUG logging. 2016-10-05 09:16:14,907 attempt 1/10 2016-10-05 09:16:14,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e8fa13708c07?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 09:16:16,008 unpacking tar archive at: mozilla-central-e8fa13708c07/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376405 ========= master_lag: 0.57 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 09:16:16.781756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:16.782089) ========= script_repo_revision: e8fa13708c070d1fadf488ed9d951464745b4e17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:16.782472) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:16.782733) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 09:16:16.801764) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-05 09:16:16.802154) ========= /tools/buildbot/bin/python 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', '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:16:17 INFO - MultiFileLogger online at 20161005 09:16:17 in /builds/slave/test 09:16:17 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 09:16:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:17 INFO - {'append_to_log': False, 09:16:17 INFO - 'base_work_dir': '/builds/slave/test', 09:16:17 INFO - 'blob_upload_branch': 'Firefox', 09:16:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:17 INFO - 'branch': 'Firefox', 09:16:17 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:17 INFO - 'config_files': ('talos/mac_config.py',), 09:16:17 INFO - 'default_actions': ('clobber', 09:16:17 INFO - 'read-buildbot-config', 09:16:17 INFO - 'download-and-extract', 09:16:17 INFO - 'populate-webroot', 09:16:17 INFO - 'create-virtualenv', 09:16:17 INFO - 'install', 09:16:17 INFO - 'run-tests'), 09:16:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:17 INFO - 'download_minidump_stackwalk': True, 09:16:17 INFO - 'download_symbols': 'ondemand', 09:16:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:17 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:17 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:17 INFO - 'installer_path': 'installer.exe', 09:16:17 INFO - 'log_level': 'info', 09:16:17 INFO - 'log_name': 'talos', 09:16:17 INFO - 'log_to_console': True, 09:16:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:16:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:16:17 INFO - 'opt_config_files': (), 09:16:17 INFO - 'pip_index': False, 09:16:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:17 INFO - 'cmd': ('bash', 09:16:17 INFO - '-c', 09:16:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:17 INFO - 'enabled': True, 09:16:17 INFO - 'halt_on_failure': False, 09:16:17 INFO - 'name': 'check_screen_resolution'},), 09:16:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:17 INFO - 'cmd': ('bash', 09:16:17 INFO - '-c', 09:16:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:17 INFO - 'enabled': True, 09:16:17 INFO - 'halt_on_failure': False, 09:16:17 INFO - 'name': 'check_screen_resolution'},), 09:16:17 INFO - 'run_cmd_checks_enabled': True, 09:16:17 INFO - 'sps_profile': False, 09:16:17 INFO - 'sps_profile_interval': 0, 09:16:17 INFO - 'suite': 'dromaeojs', 09:16:17 INFO - 'system_bits': '32', 09:16:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:17 INFO - 'title': 't-yosemite-r7-0110', 09:16:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:17 INFO - 'use_talos_json': True, 09:16:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:17 INFO - 'work_dir': 'build'} 09:16:17 INFO - [mozharness: 2016-10-05 16:16:17.021090Z] Running clobber step. 09:16:17 INFO - Running pre-action listener: _resource_record_pre_action 09:16:17 INFO - Running main action method: clobber 09:16:17 INFO - rmtree: /builds/slave/test/build 09:16:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:19 INFO - Running post-action listener: _resource_record_post_action 09:16:19 INFO - [mozharness: 2016-10-05 16:16:19.345269Z] Finished clobber step (success) 09:16:19 INFO - [mozharness: 2016-10-05 16:16:19.345397Z] Running read-buildbot-config step. 09:16:19 INFO - Running pre-action listener: _resource_record_pre_action 09:16:19 INFO - Running main action method: read_buildbot_config 09:16:19 INFO - Using buildbot properties: 09:16:19 INFO - { 09:16:19 INFO - "project": "", 09:16:19 INFO - "product": "firefox", 09:16:19 INFO - "script_repo_revision": "production", 09:16:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:16:19 INFO - "repository": "", 09:16:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:16:19 INFO - "buildid": "20161005064400", 09:16:19 INFO - "pgo_build": "False", 09:16:19 INFO - "basedir": "/builds/slave/test", 09:16:19 INFO - "buildnumber": 41, 09:16:19 INFO - "slavename": "t-yosemite-r7-0110", 09:16:19 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:16:19 INFO - "platform": "yosemite_r7", 09:16:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:16:19 INFO - "branch": "mozilla-central", 09:16:19 INFO - "repo_path": "mozilla-central", 09:16:19 INFO - "revision": "e8fa13708c070d1fadf488ed9d951464745b4e17", 09:16:19 INFO - "stage_platform": "macosx64", 09:16:19 INFO - "builduid": "d1d328e664484416baeb562142a7cc48", 09:16:19 INFO - "slavebuilddir": "test" 09:16:19 INFO - } 09:16:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:16:19 INFO - Running post-action listener: _resource_record_post_action 09:16:19 INFO - [mozharness: 2016-10-05 16:16:19.348723Z] Finished read-buildbot-config step (success) 09:16:19 INFO - [mozharness: 2016-10-05 16:16:19.348819Z] Running download-and-extract step. 09:16:19 INFO - Running pre-action listener: _resource_record_pre_action 09:16:19 INFO - Running main action method: download_and_extract 09:16:19 INFO - mkdir: /builds/slave/test/build/tests 09:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:19 INFO - trying https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:16:19 INFO - Downloading https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/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 09:16:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/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 09:16:20 INFO - Downloaded 1199 bytes. 09:16:20 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:16:20 INFO - Using the following test package requirements: 09:16:20 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:16:20 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:16:20 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:16:20 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'jsshell-mac.zip'], 09:16:20 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:16:20 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:16:20 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:16:20 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:16:20 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:16:20 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:20 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:16:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:16:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:16:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:16:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:16:20 INFO - Http code: 200 09:16:20 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 09:16:20 INFO - x-amz-cf-id: KKljDP-rCUJmf8X5_BE4CvoE1AtxuUMBNBCE9CUiTWiBusiMOQGSEA== 09:16:20 INFO - x-amz-version-id: K55p4CNjEgRMQyH_kFMejc7KHRQs5N3x 09:16:21 INFO - Expected file size: 16477119 09:16:21 INFO - Obtained file size: 16477119 09:16:27 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 09:16:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:16:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:16:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:16:28 INFO - Http code: 200 09:16:28 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 09:16:28 INFO - x-amz-cf-id: LTUFbx0ZVPLOYfPBrKxtOL3N9Lc0EevPr3A6DQ5LeGrswShpHRjgyA== 09:16:28 INFO - x-amz-version-id: K55p4CNjEgRMQyH_kFMejc7KHRQs5N3x 09:16:28 INFO - Expected file size: 16477119 09:16:28 INFO - Obtained file size: 16477119 09:16:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:16:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:16:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:16:36 INFO - Http code: 200 09:16:36 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 09:16:36 INFO - x-amz-cf-id: 2lnD0cBs4x_VXZzGSE836BQECX188L89G0vGP7fgbeWj08BkoZb4_A== 09:16:36 INFO - x-amz-version-id: 2Q2I8mz_sXIfSD.7MwO8JXg4Yu2GWX6q 09:16:36 INFO - Expected file size: 14409006 09:16:36 INFO - Obtained file size: 14409006 09:16:37 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')]} 09:16:37 INFO - trying https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:16:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/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 09:16:39 INFO - Downloaded 111672937 bytes. 09:16:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:16:39 INFO - mkdir: /builds/slave/test/properties 09:16:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:39 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:39 INFO - Contents: 09:16:39 INFO - build_url:https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:16:41 INFO - Running post-action listener: _resource_record_post_action 09:16:41 INFO - Running post-action listener: set_extra_try_arguments 09:16:41 INFO - [mozharness: 2016-10-05 16:16:41.072688Z] Finished download-and-extract step (success) 09:16:41 INFO - [mozharness: 2016-10-05 16:16:41.072810Z] Running populate-webroot step. 09:16:41 INFO - Running pre-action listener: _resource_record_pre_action 09:16:41 INFO - Running main action method: populate_webroot 09:16:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:41 INFO - '--tppagecycles', 09:16:41 INFO - '7'], 09:16:41 INFO - 'tests': ['tsvgm']}}, 09:16:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['damp', 'tps']}, 09:16:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'tests': ['damp', 'tps']}, 09:16:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['dromaeo_dom']}, 09:16:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['basic_compositor_video']}, 09:16:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['a11yr', 09:16:41 INFO - 'ts_paint', 09:16:41 INFO - 'tpaint', 09:16:41 INFO - 'sessionrestore', 09:16:41 INFO - 'sessionrestore_no_auto_restore', 09:16:41 INFO - 'tabpaint']}, 09:16:41 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:41 INFO - 'ts_paint', 09:16:41 INFO - 'tpaint', 09:16:41 INFO - 'sessionrestore', 09:16:41 INFO - 'sessionrestore_no_auto_restore', 09:16:41 INFO - 'tabpaint']}, 09:16:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:41 INFO - 'ts_paint', 09:16:41 INFO - 'tpaint', 09:16:41 INFO - 'sessionrestore', 09:16:41 INFO - 'sessionrestore_no_auto_restore', 09:16:41 INFO - 'tabpaint']}, 09:16:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:41 INFO - 'ts_paint', 09:16:41 INFO - 'tpaint', 09:16:41 INFO - 'sessionrestore', 09:16:41 INFO - 'sessionrestore_no_auto_restore', 09:16:41 INFO - 'tabpaint']}, 09:16:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['a11yr', 09:16:41 INFO - 'ts_paint', 09:16:41 INFO - 'tpaint', 09:16:41 INFO - 'sessionrestore', 09:16:41 INFO - 'sessionrestore_no_auto_restore', 09:16:41 INFO - 'tabpaint']}, 09:16:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['a11yr', 09:16:41 INFO - 'ts_paint', 09:16:41 INFO - 'tpaint', 09:16:41 INFO - 'sessionrestore', 09:16:41 INFO - 'sessionrestore_no_auto_restore', 09:16:41 INFO - 'tabpaint']}, 09:16:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['tsvgx', 09:16:41 INFO - 'tsvgr_opacity', 09:16:41 INFO - 'tart', 09:16:41 INFO - 'tscrollx', 09:16:41 INFO - 'cart']}, 09:16:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:41 INFO - 'tsvgr_opacity', 09:16:41 INFO - 'tart', 09:16:41 INFO - 'tscrollx', 09:16:41 INFO - 'cart']}, 09:16:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'talos_options': ['--disable-e10s'], 09:16:41 INFO - 'tests': ['tp5o']}, 09:16:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'tests': ['tp5o']}, 09:16:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'talos_options': ['--disable-e10s', 09:16:41 INFO - '--xperf_path', 09:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:41 INFO - 'tests': ['tp5n']}, 09:16:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:41 INFO - 'talos_options': ['--xperf_path', 09:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:41 INFO - 'tests': ['tp5n']}}, 09:16:41 INFO - 'talos.zip': {'path': '', 09:16:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:41 INFO - Running post-action listener: _resource_record_post_action 09:16:41 INFO - [mozharness: 2016-10-05 16:16:41.091761Z] Finished populate-webroot step (success) 09:16:41 INFO - [mozharness: 2016-10-05 16:16:41.091871Z] Running create-virtualenv step. 09:16:41 INFO - Running pre-action listener: _resource_record_pre_action 09:16:41 INFO - Running main action method: create_virtualenv 09:16:41 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:41 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 09:16:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:41 INFO - Using real prefix '/tools/python27' 09:16:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:44 INFO - Installing pip.................done. 09:16:44 INFO - Return code: 0 09:16:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:45 INFO - Reading from file tmpfile_stdout 09:16:45 INFO - Output received: 09:16:45 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) 09:16:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:45 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')]} 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 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')]} 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:16:45 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 09:16:45 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 09:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:45 INFO - 'HOME': '/Users/cltbld', 09:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:45 INFO - 'LOGNAME': 'cltbld', 09:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:45 INFO - 'MOZ_NO_REMOTE': '1', 09:16:45 INFO - 'NO_EM_RESTART': '1', 09:16:45 INFO - 'PAGER': '/bin/cat', 09:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:45 INFO - 'PWD': '/builds/slave/test', 09:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:45 INFO - 'SHELL': '/bin/bash', 09:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:16:45 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:45 INFO - 'USER': 'cltbld', 09:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:45 INFO - 'XPC_FLAGS': '0x0', 09:16:45 INFO - 'XPC_SERVICE_NAME': '0', 09:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:45 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 09:16:45 INFO - Cleaning up... 09:16:45 INFO - Return code: 0 09:16:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:45 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')]} 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 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')]} 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:16:45 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 09:16:45 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 09:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:45 INFO - 'HOME': '/Users/cltbld', 09:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:45 INFO - 'LOGNAME': 'cltbld', 09:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:45 INFO - 'MOZ_NO_REMOTE': '1', 09:16:45 INFO - 'NO_EM_RESTART': '1', 09:16:45 INFO - 'PAGER': '/bin/cat', 09:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:45 INFO - 'PWD': '/builds/slave/test', 09:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:45 INFO - 'SHELL': '/bin/bash', 09:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:16:45 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:45 INFO - 'USER': 'cltbld', 09:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:45 INFO - 'XPC_FLAGS': '0x0', 09:16:45 INFO - 'XPC_SERVICE_NAME': '0', 09:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:45 INFO - Downloading/unpacking psutil>=3.1.1 09:16:45 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 09:16:45 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 09:16:45 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 09:16:45 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 09:16:45 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 09:16:45 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 09:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:49 INFO - Installing collected packages: psutil 09:16:49 INFO - Running setup.py install for psutil 09:16:49 INFO - building 'psutil._psutil_osx' extension 09:16:49 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 09:16:49 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 09:16:49 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 09:16:49 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 09:16:50 INFO - building 'psutil._psutil_posix' extension 09:16:50 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 09:16:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:50 INFO - ^ 09:16:50 INFO - 1 warning generated. 09:16:50 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 09:16:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:50 INFO - Successfully installed psutil 09:16:50 INFO - Cleaning up... 09:16:50 INFO - Return code: 0 09:16:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:16: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16: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 mozsystemmonitor==0.3 09:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:50 INFO - 'HOME': '/Users/cltbld', 09:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:50 INFO - 'LOGNAME': 'cltbld', 09:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:50 INFO - 'MOZ_NO_REMOTE': '1', 09:16:50 INFO - 'NO_EM_RESTART': '1', 09:16:50 INFO - 'PAGER': '/bin/cat', 09:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:50 INFO - 'PWD': '/builds/slave/test', 09:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:50 INFO - 'SHELL': '/bin/bash', 09:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:16:50 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:50 INFO - 'USER': 'cltbld', 09:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:50 INFO - 'XPC_FLAGS': '0x0', 09:16:50 INFO - 'XPC_SERVICE_NAME': '0', 09:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:53 INFO - Installing collected packages: mozsystemmonitor 09:16:53 INFO - Running setup.py install for mozsystemmonitor 09:16:53 INFO - Successfully installed mozsystemmonitor 09:16:53 INFO - Cleaning up... 09:16:53 INFO - Return code: 0 09:16:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:53 INFO - 'HOME': '/Users/cltbld', 09:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:53 INFO - 'LOGNAME': 'cltbld', 09:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:53 INFO - 'MOZ_NO_REMOTE': '1', 09:16:53 INFO - 'NO_EM_RESTART': '1', 09:16:53 INFO - 'PAGER': '/bin/cat', 09:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:53 INFO - 'PWD': '/builds/slave/test', 09:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:53 INFO - 'SHELL': '/bin/bash', 09:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:16:53 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:53 INFO - 'USER': 'cltbld', 09:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:53 INFO - 'XPC_FLAGS': '0x0', 09:16:53 INFO - 'XPC_SERVICE_NAME': '0', 09:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:53 INFO - Downloading/unpacking jsonschema==2.5.1 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:56 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 09:16:56 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 09:16:56 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 09:16:56 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 09:16:56 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 09:16:56 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 09:16:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:56 INFO - warning: no files found matching '*.txt' 09:16:56 INFO - no previously-included directories found matching 'build' 09:16:56 INFO - no previously-included directories found matching 'dist' 09:16:56 INFO - no previously-included directories found matching '.git*' 09:16:56 INFO - Installing collected packages: jsonschema, functools32 09:16:56 INFO - Running setup.py install for functools32 09:16:56 INFO - warning: no files found matching '*.txt' 09:16:56 INFO - no previously-included directories found matching 'build' 09:16:56 INFO - no previously-included directories found matching 'dist' 09:16:56 INFO - no previously-included directories found matching '.git*' 09:16:56 INFO - Successfully installed jsonschema functools32 09:16:56 INFO - Cleaning up... 09:16:57 INFO - Return code: 0 09:16:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:57 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')]} 09:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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')]} 09:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:16:57 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 09:16:57 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 09:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:57 INFO - 'HOME': '/Users/cltbld', 09:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PAGER': '/bin/cat', 09:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test', 09:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:16:57 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - 'XPC_FLAGS': '0x0', 09:16:57 INFO - 'XPC_SERVICE_NAME': '0', 09:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:57 INFO - Cleaning up... 09:16:57 INFO - Return code: 0 09:16:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:57 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')]} 09:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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')]} 09:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:16:57 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 09:16:57 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 09:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:57 INFO - 'HOME': '/Users/cltbld', 09:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PAGER': '/bin/cat', 09:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test', 09:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:16:57 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - 'XPC_FLAGS': '0x0', 09:16:57 INFO - 'XPC_SERVICE_NAME': '0', 09:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:17:00 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 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 09:17:01 INFO - Downloading docopt-0.6.1.tar.gz 09:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:01 INFO - Running setup.py install for blobuploader 09:17:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:01 INFO - Running setup.py install for requests 09:17:01 INFO - Running setup.py install for docopt 09:17:02 INFO - Successfully installed blobuploader requests docopt 09:17:02 INFO - Cleaning up... 09:17:02 INFO - Return code: 0 09:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:02 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')]} 09:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:02 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')]} 09:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:02 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:17:02 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 09:17:02 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 09:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:02 INFO - 'HOME': '/Users/cltbld', 09:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:02 INFO - 'LOGNAME': 'cltbld', 09:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:02 INFO - 'MOZ_NO_REMOTE': '1', 09:17:02 INFO - 'NO_EM_RESTART': '1', 09:17:02 INFO - 'PAGER': '/bin/cat', 09:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:02 INFO - 'PWD': '/builds/slave/test', 09:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:02 INFO - 'SHELL': '/bin/bash', 09:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:17:02 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:02 INFO - 'USER': 'cltbld', 09:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:02 INFO - 'XPC_FLAGS': '0x0', 09:17:02 INFO - 'XPC_SERVICE_NAME': '0', 09:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-MJ4UtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-3ZsVVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-aaGlwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LvJt9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-fxLfkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xdCCn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6_9PVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-rbNn8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TqsIsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-SUiMC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0Ev9rT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-zq_O9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-eVf_g4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-so1Ouf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_qQ2cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-btSuZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PYD6Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:04 INFO - Running setup.py install for manifestparser 09:17:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Running setup.py install for mozcrash 09:17:04 INFO - Running setup.py install for mozdebug 09:17:04 INFO - Running setup.py install for mozdevice 09:17:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Running setup.py install for mozfile 09:17:04 INFO - Running setup.py install for mozhttpd 09:17:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Running setup.py install for mozinfo 09:17:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Running setup.py install for mozInstall 09:17:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Running setup.py install for mozleak 09:17:05 INFO - Running setup.py install for mozlog 09:17:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Running setup.py install for moznetwork 09:17:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Running setup.py install for mozprocess 09:17:06 INFO - Running setup.py install for mozprofile 09:17:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:06 INFO - Running setup.py install for mozrunner 09:17:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:06 INFO - Running setup.py install for mozscreenshot 09:17:06 INFO - Running setup.py install for moztest 09:17:06 INFO - Running setup.py install for mozversion 09:17:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:06 INFO - Cleaning up... 09:17:06 INFO - Return code: 0 09:17:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:06 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:17:06 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 09:17:06 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 09:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:06 INFO - 'HOME': '/Users/cltbld', 09:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:06 INFO - 'LOGNAME': 'cltbld', 09:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:06 INFO - 'MOZ_NO_REMOTE': '1', 09:17:06 INFO - 'NO_EM_RESTART': '1', 09:17:06 INFO - 'PAGER': '/bin/cat', 09:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:06 INFO - 'PWD': '/builds/slave/test', 09:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:06 INFO - 'SHELL': '/bin/bash', 09:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:17:06 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:06 INFO - 'USER': 'cltbld', 09:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:06 INFO - 'XPC_FLAGS': '0x0', 09:17:06 INFO - 'XPC_SERVICE_NAME': '0', 09:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-WnllvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:07 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)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-JixwqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:07 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)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-20vpHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:07 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)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YyodcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:07 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)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-RH7Lyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:07 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)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-enznBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:07 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)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-X9aQ7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:07 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)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iUu2Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LHUbFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YUHs7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-qGoHzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-4B8pp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-MWhrcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Qt_nO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-OPm8Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-9BgozU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:08 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)) 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-kRPDwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:08 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)) 09:17:08 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)) 09:17:08 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)) 09:17:08 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)) 09:17:08 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)) 09:17:08 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)) 09:17:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:11 INFO - Downloading blessings-1.6.tar.gz 09:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:12 INFO - Installing collected packages: blessings 09:17:12 INFO - Running setup.py install for blessings 09:17:12 INFO - Successfully installed blessings 09:17:12 INFO - Cleaning up... 09:17:12 INFO - Return code: 0 09:17:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:12 INFO - Reading from file tmpfile_stdout 09:17:12 INFO - Current package versions: 09:17:12 INFO - blessings == 1.6 09:17:12 INFO - blobuploader == 1.2.4 09:17:12 INFO - docopt == 0.6.1 09:17:12 INFO - functools32 == 3.2.3-2 09:17:12 INFO - jsonschema == 2.5.1 09:17:12 INFO - manifestparser == 1.1 09:17:12 INFO - mozInstall == 1.12 09:17:12 INFO - mozcrash == 0.17 09:17:12 INFO - mozdebug == 0.1 09:17:12 INFO - mozdevice == 0.48 09:17:12 INFO - mozfile == 1.2 09:17:12 INFO - mozhttpd == 0.7 09:17:12 INFO - mozinfo == 0.9 09:17:12 INFO - mozleak == 0.1 09:17:12 INFO - mozlog == 3.4 09:17:12 INFO - moznetwork == 0.27 09:17:12 INFO - mozprocess == 0.23 09:17:12 INFO - mozprofile == 0.28 09:17:12 INFO - mozrunner == 6.12 09:17:12 INFO - mozscreenshot == 0.1 09:17:12 INFO - mozsystemmonitor == 0.3 09:17:12 INFO - moztest == 0.7 09:17:12 INFO - mozversion == 1.4 09:17:12 INFO - psutil == 3.1.1 09:17:12 INFO - requests == 1.2.3 09:17:12 INFO - wsgiref == 0.1.2 09:17:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:12 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:17:12 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 09:17:12 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 09:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:12 INFO - 'HOME': '/Users/cltbld', 09:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:12 INFO - 'LOGNAME': 'cltbld', 09:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:12 INFO - 'MOZ_NO_REMOTE': '1', 09:17:12 INFO - 'NO_EM_RESTART': '1', 09:17:12 INFO - 'PAGER': '/bin/cat', 09:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:12 INFO - 'PWD': '/builds/slave/test', 09:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:12 INFO - 'SHELL': '/bin/bash', 09:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:17:12 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:12 INFO - 'USER': 'cltbld', 09:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:12 INFO - 'XPC_FLAGS': '0x0', 09:17:12 INFO - 'XPC_SERVICE_NAME': '0', 09:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 INFO - Cleaning up... 09:17:13 INFO - Return code: 0 09:17:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:13 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 0x107101490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107077570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840e03b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, '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.lj3riFmeqf/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.oPKXAB5dVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 09:17:13 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 09:17:13 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 09:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:13 INFO - 'HOME': '/Users/cltbld', 09:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:13 INFO - 'LOGNAME': 'cltbld', 09:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:13 INFO - 'MOZ_NO_REMOTE': '1', 09:17:13 INFO - 'NO_EM_RESTART': '1', 09:17:13 INFO - 'PAGER': '/bin/cat', 09:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:13 INFO - 'PWD': '/builds/slave/test', 09:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:13 INFO - 'SHELL': '/bin/bash', 09:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:17:13 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:13 INFO - 'USER': 'cltbld', 09:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:13 INFO - 'XPC_FLAGS': '0x0', 09:17:13 INFO - 'XPC_SERVICE_NAME': '0', 09:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:13 INFO - Cleaning up... 09:17:13 INFO - Return code: 0 09:17:13 INFO - Running post-action listener: _resource_record_post_action 09:17:13 INFO - Running post-action listener: _start_resource_monitoring 09:17:13 INFO - Starting resource monitoring. 09:17:13 INFO - [mozharness: 2016-10-05 16:17:13.544156Z] Finished create-virtualenv step (success) 09:17:13 INFO - [mozharness: 2016-10-05 16:17:13.545027Z] Running install step. 09:17:13 INFO - Running pre-action listener: _resource_record_pre_action 09:17:13 INFO - Running main action method: install 09:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:13 INFO - Reading from file tmpfile_stdout 09:17:13 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:13 INFO - Reading from file tmpfile_stdout 09:17:13 INFO - Output received: 09:17:13 INFO - Usage: mozinstall [options] installer 09:17:13 INFO - Options: 09:17:13 INFO - -h, --help show this help message and exit 09:17:13 INFO - -d DEST, --destination=DEST 09:17:13 INFO - Directory to install application into. [default: 09:17:13 INFO - "/builds/slave/test"] 09:17:13 INFO - --app=APP Application being installed. [default: firefox] 09:17:13 INFO - mkdir: /builds/slave/test/build/application 09:17:13 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'] 09:17:13 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 09:17:48 INFO - Reading from file tmpfile_stdout 09:17:48 INFO - Output received: 09:17:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:17:48 INFO - Running post-action listener: _resource_record_post_action 09:17:48 INFO - [mozharness: 2016-10-05 16:17:48.506682Z] Finished install step (success) 09:17:48 INFO - [mozharness: 2016-10-05 16:17:48.506817Z] Running run-tests step. 09:17:48 INFO - Running pre-action listener: _resource_record_pre_action 09:17:48 INFO - Running main action method: run_tests 09:17:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:48 INFO - 2016-10-05 09:17:48.521 screenresolution[1638:7459] starting screenresolution argv=screenresolution get 09:17:48 INFO - 2016-10-05 09:17:48.523 screenresolution[1638:7459] Display 0: 1600x1200x32@60 09:17:48 INFO - 2016-10-05 09:17:48.529 screenresolution[1639:7461] starting screenresolution argv=screenresolution list 09:17:48 INFO - Available Modes on Display 0 09:17:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:48 INFO - Intel Iris: 09:17:48 INFO - Chipset Model: Intel Iris 09:17:48 INFO - Type: GPU 09:17:48 INFO - Bus: Built-In 09:17:48 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:48 INFO - Vendor: Intel (0x8086) 09:17:48 INFO - Device ID: 0x0a2e 09:17:48 INFO - Revision ID: 0x0009 09:17:48 INFO - Displays: 09:17:48 INFO - Display: 09:17:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:48 INFO - Display Serial Number: 1600x1200 60 09:17:48 INFO - Main Display: Yes 09:17:48 INFO - Mirror: Off 09:17:48 INFO - Online: Yes 09:17:48 INFO - Rotation: Supported 09:17:48 INFO - Return code: 0 09:17:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:48 INFO - Python 2.7.3 09:17:48 INFO - Return code: 0 09:17:48 INFO - grabbing minidump binary from tooltool 09:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:48 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 0x106f15d30>, '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 0x106d3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f0db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:48 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 09:17:48 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 09:17:48 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 09:17:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:48 INFO - Return code: 0 09:17:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:17:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:17:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:48 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 09:17:48 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 09:17:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lj3riFmeqf/Render', 09:17:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:48 INFO - 'HOME': '/Users/cltbld', 09:17:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:48 INFO - 'LOGNAME': 'cltbld', 09:17:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:48 INFO - 'MOZ_NO_REMOTE': '1', 09:17:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:48 INFO - 'NO_EM_RESTART': '1', 09:17:48 INFO - 'PAGER': '/bin/cat', 09:17:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:48 INFO - 'PWD': '/builds/slave/test', 09:17:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:48 INFO - 'SHELL': '/bin/bash', 09:17:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oPKXAB5dVR/Listeners', 09:17:48 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 09:17:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:48 INFO - 'USER': 'cltbld', 09:17:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:48 INFO - 'XPC_FLAGS': '0x0', 09:17:48 INFO - 'XPC_SERVICE_NAME': '0', 09:17:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:48 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 09:17:49 INFO - mozversion application_buildid: 20161005064400 09:17:49 INFO - mozversion application_changeset: e8fa13708c070d1fadf488ed9d951464745b4e17 09:17:49 INFO - mozversion application_display_name: Nightly 09:17:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:49 INFO - mozversion application_name: Firefox 09:17:49 INFO - mozversion application_remotingname: firefox 09:17:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:17:49 INFO - mozversion application_vendor: Mozilla 09:17:49 INFO - mozversion application_version: 52.0a1 09:17:49 INFO - mozversion platform_buildid: 20161005064400 09:17:49 INFO - mozversion platform_changeset: e8fa13708c070d1fadf488ed9d951464745b4e17 09:17:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:17:49 INFO - mozversion platform_version: 52.0a1 09:17:49 INFO - using testdate: 1475684269 09:17:49 INFO - actual date: 1475684269 09:17:49 INFO - starting webserver on 'localhost:49267' 09:17:49 INFO - SUITE-START | Running 2 tests 09:17:49 INFO - TEST-START | dromaeo_css 09:17:49 INFO - operating with platform_type : mac_ 09:17:49 INFO - Initialising browser for dromaeo_css test... 09:17:49 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmptogiM1/profile http://localhost:49267/getInfo.html) 09:17:51 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:17:51 INFO - PROCESS | 1649 | colorDepth:24 09:17:51 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:17:51 INFO - PROCESS | 1649 | __metrics 09:17:51 INFO - PROCESS | 1649 | 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" 09:17:51 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:52 INFO - TEST-INFO | 1649: exit 0 09:17:52 INFO - Browser initialized. 09:17:52 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:52 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmptogiM1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:04 INFO - PROCESS | 1651 | RSS: Main: 235421696 09:18:04 INFO - PROCESS | 1651 | 09:18:55 INFO - PROCESS | 1651 | 1475684335821 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] 09:21:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 09:21:11 INFO - PROCESS | 1651 | RSS: Main: 396812288 09:21:11 INFO - PROCESS | 1651 | 09:24:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 09:24:14 INFO - PROCESS | 1651 | RSS: Main: 373067776 09:24:14 INFO - PROCESS | 1651 | 09:27:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 09:27:17 INFO - PROCESS | 1651 | RSS: Main: 332951552 09:27:17 INFO - PROCESS | 1651 | 09:30:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 09:30:20 INFO - PROCESS | 1651 | RSS: Main: 332726272 09:30:20 INFO - PROCESS | 1651 | 09:33:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 09:33:24 INFO - PROCESS | 1651 | RSS: Main: 324538368 09:33:24 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 09:36:27 INFO - PROCESS | 1651 | RSS: Main: 608342016 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | __start_tp_report 09:36:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 09:36:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 09:36:27 INFO - PROCESS | 1651 | |i|pagename|runs| 09:36:27 INFO - PROCESS | 1651 | |0;dojo.html;3296.653851345608;3297.7032067113955;3322.800631962081;3347.0795531228923;3354.1279267390473;5527.226188333637;5572.164175373696;5666.121751128571;5705.600607957439;5728.22668939693;5659.801906933259;5661.207430959669;5748.5401167906575;5752;5759.280089988752;4700.788464519105;5032.421271553763;5082.796688132489;5099.184130539114;5209.765560549766;5214.608904332172;5232.424433312332;5234.345706786652;5247.685138891659;5278.970600732859;4159.81445288093;5163.225516172604;5171.948280517222;5225.137852254374;5284.497972692588;5086.92369614456;5157.4068982067165;5166.3800343958965;5179.093658609729;5187.351581052369;5074.797008119671;5090.465501122403;5121.103806833827;5139.997700448414;5187.936473023021;5570.693611851347;5617.0731829248125;5663.801766938179;5664;5691.544676425876;6029.306629737593;6163.568550201488;6189.226346706661;6192.318844927055;6195.597286176429;5283.841484755463;6122.733612273388;6195.721192546346;6200.844978875519;6215.098810672473;5721.485066344049;5725.198472213893;5728.598998070095;5742.540596752333;5758.280214973171;6205.627662340274;6269.62382257066;6274.905876411857;6288.308286088527;6294.402031806971;5908.96593096215;5975.671338076448;5985.401459854067;6077.36187700294;6081.057436097412;6094.3367212805715;6127.100598814382;6135.049067394561;6141.788955975845;6146.938530614726;6099.922829902115;6128.228138398591;6147.801178770186;6157.476614487729;6169.56813023084;6171.352008039181;6180.103884936659;6192.040233763683;6193.473554747807;6203.96898015507;4768.880777980509;4835.984443266953;4853.927191092066;4867.367242258484;4899.632527560376;4336.9132617348105;4369.978150109229;4370.169667763115;4393.275109606879;4420.160169567772;5616.269884914935;5696.117101849137;5699.5725320600295;5710.857228569236;5716.914246286309;5169.612279079084;6248.843778905563;6272.090546870678;6276.497880169667;6282.308946015934;4924.96575719103;5580.720963951786;5614.522765564891;5637;5651.604387692904;5374.892502149935;5380.78476860929;5383.138697808332;5384.623076384615;5439.945600544023;6254.061890716338;6264.091706702594;6272.686365681789;6283.37166283368;6286.4342209201395;6079.574429790049;6146.692665366803;6257.9687101564205;6259.561830671809;6279.560430769802;6192;6220.813375598739;6250.593711408744;6273.247210334789;6325.304216536086;5417.83746487606;6094.268687757498;6102.908456373247;6110.7861224856915;6112.694365281807;5992.13114098462;6161.72272247741;6186.81439556814;6214.1921550198185;6229.906551401823;4936.506349365035;5814.30228372598;5879.323877754113;5938.049912014057;6026.337102918587;6003.459688628044;6052.334243233153;6059.787907423219;6076.848078798065;6078.513718902565;5882.764689412546;5884.852878678067;5892.558058145742;5903;5920.467157955804;5854.677992710442;5946.762129514944;5951.464368206709;5962.2249107615735;6012.488938440281;5864.179014937827;5866.765329386947;5887.61730487517;5918.852028699317;5936.465718085393 09:36:27 INFO - PROCESS | 1651 | |1;ext.html;491.70989116421305;497.87055365604954;499.91751361025433;503.6700960870631;528.6114705691316;29871.850640746794;33337.33325333492;33395.99999999997;39686.40470392943;43286.7835660822;41467.37798933018;41874;41907.790461047654;41937.37093943593;41949.79025104878;9670.85493717593;9870.555824987892;9871.111599956002;9878;9910.900890991106;9313.068693130701;9858.605655773761;9862.852057219148;9867.851982220272;9877.950610246975;9814.263930205227;9879.901200988006;9881.011898810133;9898.703038908809;9904.207663386911;9759.316847820655;9843.310968232227;9844.458554779449;9862.161716254128;9869.950650246703;8266.256036956642;9610.855837162448;9736.610535578568;9745.415275083518;9755.756106097333;39884.40173397402;40133;40183.7990810047;40229.597704023174;40237;3253.7885037472724;3304.6091386721887;3333.333333333343;3343.164208947763;3413.266147778215;23498.798244045833;30530.08409747689;30624.865643138113;31153;32262;3148;3288.93422131556;3318.2699806042633;3340.6659334066644;3366.175287054663;492848;496322;498112;499481.50259248837;499552;59352;59742.999999999134;59778.40221597729;59816.40183598195;59871.101933471036;63518.68240658767;63625.681871591274;63639.72720545563;63744.36255637477;63837.680811595645;15623.687526249412;15823.604409889615;15934.203289835461;15940.760888586681;15962.361505539648;12136.817947730791;12196.939015304866;12210.9620682243;12224.205426647239;12234.75530489403;15003.924980375028;18223.17995690197;18296.268149274147;18421.63156736858;18429.170687318834;6256.874862502816;7345.853082938312;7630.504017238862;7740;7745.264199901;820.3601191070974;914.3782228084833;977.9168770654574;1006.4263369879098;1033.7674023344687;24800;29080;29143.979960701698;29777.553336699966;30034.399312014073;993.2699465892563;999.6301368493704;1039.9116075133697;1068.556549032143;1106.280917403678;15120.790336773032;15215.391384344724;15277.847221527643;15346;15381.568458525795;632166;669949;670660;670985;672801;55180.724096380065;55861.720691397095;55946.720266398406;55987;56110;1310.7181955879532;1358.130796290375;1358.8777010068943;1365.6858922447693;1370.0546622830216;28837.134885953456;28957.85521072381;29013.41973160567;29803.85098074496;29836;47208.52791472041;47674;47707.76146119247;47756.28365574589;47915.5208447925;584189;585634;586898;587507;587818;25191.74808251894;25327;25503.6174457387;25639.487210256062;25772;45050.77474612606;45997.77001114973;46131;46344;46509.302360463946;34243.31513369669;36890.8155459221;37090.62909370872;38915.6108438912;39107.6089239104;36985.44521832228;37774.811125944194;37800.81099594484;38694.80652596831;38785.6121438782;36908.81545592255;37103.25793484169;37957.810210948766;38196.809015955856;38453;28081;32635;34390.14024649404;34422.31155376929;34999.65000350066;18830.435086947415;24164.75835241625;24725.258242252763;25238;25785.871070644527 09:36:27 INFO - PROCESS | 1651 | |2;jquery.html;334.81585128179495;344.26842958712734;362.8349101158973;364.05709213137965;383.9385698288274;15144.772828407582;15254.847451525482;16216.837831621695;18388.356407525738;21140.788592114113;19896.10467528961;20153.496162595944;20486.692699609517;20647.69028464574;20873.582528349423;5834.883302333961;6207.565470417073;6224.968875155618;6237.937620623804;6241.968790156043;4612.492625811158;6116.877662446771;6171.506279497629;6182.443580077792;6193.597416167957;5960.135780311853;6010.609310394811;6090.634561926298;6128.57100003;6164.4143806338325;5950.107483877409;6075.605085669439;6078;6085.786997455112;6097.756089756404;5149.382074151088;5236.7119808410525;5239.13554263546;5415.512603865671;5472.069748142825;21630.891845540827;21763.129474820988;23491.7650823493;23541.293761187022;24054.759452405426;1986.3544348086784;2061.597988392264;2161.1571487120036;2284.977150228493;2304.481491664379;9480.762980925532;10372.376035824223;10373.014563616445;11334.206605537616;15727.921360393124;1824.4982629776785;1868.7383766272733;1869.541962219265;1888.1125870840683;1905.6760350740408;112150;112370.43814780956;125258.4948301038;125671.37164314212;129038.3548082263;10956.726081848017;11111;11231.71920701989;11385;11508.36703981289;14250.501232456814;14295.142291462338;14323.928380358031;14329.78505322421;14332;8160.102388737234;8263.586820658944;8281.668733250723;8285.95857020711;8439.873401898976;8112.756617301372;8175.918240817635;8301.916980830236;8307.293880020266;8328.625211865481;3647.0699971507097;4423.424954755926;4428.357888106207;4460.130274596422;4527.411436513232;1643.6794825009008;1683.5117815833516;1840.693107893384;1907.427771668494;1909.7230901519201;655.8589903170841;775.4571799740204;867.492516877621;896.6682327538853;938.1275413865171;16235.756463653055;19311.103334499658;20099.19603215884;20148.697769533468;20460.69308960367;884.6284560484612;893.6738090596886;906.2342320738965;918.2241006349624;994.955296938212;7954.522728636184;10672.78654426907;10782.946085269681;10788.784224315626;10872.565097396173;565501;575597;578219;585983;586356;74983;113215.43392282986;125632.37183814023;145630;147680;953.594722243044;971.3734641156415;1021.7241344836852;1025.6512785652915;1026.6714651311509;14248.71502569922;17109.374609412087;17337.653246935242;19291.228350865807;19318.420447386037;56728.71635641796;57901.420985789606;58207;58593.4140658605;58636.706816465645;64813.73151939659;68956.6552167236;71977;75890;78828.60585697264;6810.31896810315;6863.485238607024;7181;7199.604021778852;7450.8882366763455;9392.765180870532;12719.364031798557;12980.675483112998;13311.86688133145;13370;10739.248252622241;10834.449884692292;11271.436428178722;11318.717032074264;11326.150538709795;10954.780904382027;11286.379249141377;11416.372099534605;11574.421278935852;11713;9333.25333973294;11487.966083052564;11546.48040838181;11666.88333116658;11977.221480603734;7940.523568585903;8008.399370047153;8224.62989165477;8289;8344.874826877724;10422.322549034288;11030.669079927615;11353.659390218307;11433.314001159957;11472.598459053894 09:36:27 INFO - PROCESS | 1651 | |3;mootools.html;1333.1934179821205;1527.2821773766332;1717.9398721044759;1896.4405500377388;1932.6231384879954;8544.530050847201;9084.954575227122;9089.500077495735;9089.727308180765;9099.99900010999;7767.68929242831;7857.607119644023;8171.019477662689;8175.95912020439;8497.872531912011;2252.763459836718;2351.412146963259;2357.7760112789256;2360.881955902198;2361.9055237790553;2344.8358614896965;2345.870977096259;2375.6317770745477;2378.4172877645;2382.868942208178;2355.4346956730346;2361.6693662887124;2370.9407264818433;2379.179183181808;2379.7620237976153;2327.464683122891;2352.4471749139025;2358.5282943411303;2360.5160942006846;2365.6451532270125;2305.827062970284;2342.121704360865;2348.377679914824;2353.3410645019253;2355.187460326185;8852.557372131432;8890.799742034807;8908;8938.374313798036;8939.73180804577;5080.74596270185;5337.626366154371;5342.038433082043;5343.385510666284;5346.4118946915805;3568.4825700273313;3583.623719509466;3651.050726811022;3653.8173091345325;3654.214343916071;1062.9627963021258;1078.9244752867303;1080.616381184676;1093.2565855218447;1102.779444111181;11546.53813847445;11649.242799218024;11660.941695291469;11711.945924866803;11746.530138794438;4450.577195166454;4549.499555048942;4714.575688188011;4764.213904705754;4768.499307572724;9903.405795652285;9929.404235745735;9929.404235745878;9931.80136397268;9940.155086817556;5294.655847369986;5301.284326615934;5302.761375738024;5307.787688492417;5311.256424100629;4374.803133858984;4446.866594002185;4568.725876447424;4825.30033145199;4832.830850920201;3826.942595861064;3875.2636998970106;3957.5053118360206;3995.2808494471274;3999.460072890123;1117.6032508459543;1213.1386715431966;1240.7580521798163;1249.731307768834;1279.9552015679405;838.3000194837379;839.8661806561093;850.4769566716549;897.838389089957;957.6121670723368;5575.609707320448;5639.3232812062815;5903.645781253139;5983.222181116514;6030.085738882071;897.7665806890255;908.3641450984205;917.4678686361895;941.6610020392653;947.9052094790603;3803.1633040730994;3838.3091043612417;3888.51393575803;3954.367301231789;3960.801959902051;10687.519061642244;10988.780224395627;11085;11143.275687080313;11152.944235278772;9245.815083698288;11059.281146725438;11067.833982490269;11072.224944253825;11078.335299882034;7343.449241306924;8120.269175774209;8127.187281271826;8158;8286.129956354616;5841.58562985786;5850.444207800252;5960.6125601837475;5994;6056.485198758155;7597.772066838003;10123.088921996761;10150.441725705157;10174.49127543605;10179.7455063624;9732.12410883024;10100.646477373257;10162.847557286794;10337.121344685786;10359.015893490106;7182.102237220348;7514.286142816423;7571.129320128035;7613.9238607613215;7733.845323093619;7208.9639551804;8418.116097809641;8504.702335418231;8523.105073967392;8526.275266602408;7381.704731810667;7481.7755467336065;7482.289182527651;7646.961765191361;7722.53664780115;7262.273772622695;7400.888986665097;7429.962850185931;7447.888281675888;7459.589722565094;6889.827754306182;7058.223595404399;7064.505484616233;7073;7078;4885.389326334208;5049.166887463674;5054.494550544916;5123.129068058513;5201.375834899836;4969.130402179532;4976.651634385703;5115.769790359517;5124.898791495469;5196.324477818011 09:36:27 INFO - PROCESS | 1651 | |4;prototype.html;293.4966532396939;293.55819491665045;304.7470599402495;308.8532946850245;311.32597925491314;4076.653484453818;4347.413099231603;5185.766640501177;5214.374275086991;5279.8416047518585;4211.452511173551;4288.935665965013;5007.824726134586;5134.948650513494;5189.558887494558;3901.356276214421;4005.259027079985;4019.5176578810438;4024.134811015631;4036.475258216435;3532.311199316134;3817.351050321438;4058.69559783016;4064.796760161995;4082.83668653255;3948.6643635291034;3968.6825053995613;3977.076848516338;3977.542582602994;3996.440498330236;3505.929881402383;3932.58707835676;3954.545227298854;3962.960370396288;3987.6809855211513;3015.9547606785914;3017.8038427502142;3429.6227414984332;3454.809985450799;3469.8785542505893;4829.372181616367;4873.829415970424;5050.419201791804;5157.613179011551;5216.973915130438;4477.664175186841;4497.977510112429;4519.276915693506;4521.638268938477;4544.409226800528;4939.728314942677;5443.503036664909;5449.482299181572;5450.974709036484;5462.371827239839;3464.532288141119;3739.7284923125994;3749.212665340282;3763.8306276217363;3770.4155855842387;5717.9714101429645;5751;5762.366139724627;5768.423157684198;5769.855753606152;3209.935801283961;3777.490038844721;3793.6206379361843;3793.905152371158;3793.943090853639;3851.401668307677;3860.613938606173;3874.748141370802;3892.9221415572097;3903.941440878389;4070.5115386153852;4186.225548273579;4477.910441791146;4498.730076195438;4520.141173177151;5425.267588875451;5509.696966666792;5521.420250873681;5525.530329921921;5574.080276754371;1243.055277988717;1244.3031902134833;1268.892144167756;1294.4692676002794;1298.8506321772927;862.6714859117016;876.7939534209455;890.7951171230653;895.4403497813867;896.8296023755465;5105.591552675777;5121.8719532011255;5128.9484206317775;5137.974310128426;5214.584447187099;4679.3916790816975;4898.804047838046;4905.70565766048;5337.386200586904;5504.339479262514;4800.279958006327;5070.340855688737;5087.19404611365;5094.439611642716;5119.385673719177;2576.510463012059;2679.0891097027106;2930.4725149473297;2944.8969286074885;2957.137003687663;5494.423085576037;5783.595148339576;5858.794942177004;5862.677552734641;5869.325027621793;4877.219644856806;5119.897602047939;5420.512153906088;5813.1460230707835;5891.293044834561;4961.742407204713;5868.501177399969;5874.82631426375;5901.5868889177345;5907.025340818716;4767.6662633615315;5106.821261255838;5710.2862142232225;5742.397048309867;5760.279965004374;5200.893638076585;5581.5813813963305;5599.832005039855;5607.971960140337;5609.691466969356;4926.655134140576;5352.785888564414;5900.9610050823;5902.61632993854;5905.704714764331;3027.9697203027686;3636.5272514573994;3643.836027378721;3775.301569209705;3793.336166170854;4680.625549956064;5632.295963004624;5640.492355688008;5652.434756524314;5667;4586.628827811623;5298.7350632469;5310.2831117798605;5405.405405405481;5501.9174712380145;4602.861914142581;5488.231647569423;5527.778888844562;5539.556835453234;5540.889182216414;4526.8682829292675;5022.3219865317715;5353;5393.568514518907;5418.729063546885;4671.360681215103;5344.793124125494;5538.6399884007415;5539.612227144061;5560.555155587463;4519.711882113582;4754.786034628381;5416.674999500043;5478.835634930959;5485.286912701324 09:36:27 INFO - PROCESS | 1651 | |5;yui.html;677.762783025941;718.593994393168;727.8253219227383;776.8329809091044;779.9275995506179;15002.924985375072;15718.978266412689;15728.842711572895;15761.842381576182;16166;14103.858961410382;14130.858691413083;14213.360398782053;14315.21266330354;14346.42614295427;680.1600023970393;701.0500771454656;703.5075447186964;830.6220669595319;843.6878355008655;862.3833958719514;864.6411739128254;875.0374587953232;876.0932434929849;876.8728534362514;924.4129977464291;925.4493576322078;926.4256161180044;926.6247169896203;927.0312523413049;720.9567425954459;729.3763831923682;918.393860052369;922.1930810540777;923.4090182283344;663.8506336074392;678.1421501800227;679.952403331767;683.8153698501377;686.0189928402383;14903.254837258093;14941.850581494155;14964.17697026674;14973.625659358495;15042.849571504254;13716.108452950526;13948.16311021342;14226.644333891632;14259.714805703932;14279.78580321296;12762;12906.870931290754;12961.805572916413;13024.86975130246;13038.934805325913;4478.596926276651;4571.2457444521615;4607.746573938432;4785.880352991203;4795.256735206049;116326.83673163244;118666.22000669996;118872;119023;121494;11119;11139.498722557504;11165.66503004895;11187.160962927814;11368.94315528417;22304.442388940082;22423;22500.549989000454;22510.774892251196;22752;7897.763067107881;7906.446548741533;7913.287804097658;7976.322012628992;8069.241275559653;3988.880333589939;4191.706580539394;4200.012996945716;4300.80646370914;4351.325544540601;3807.809609519513;4033.2941735196223;4043.4137050127565;4047.5547689754103;4089.427480152781;718.9568625882464;731.8170457385654;731.8536292741367;734.0933946575974;735.1876176824638;580.5993864233666;594.9553783466171;601.8736065426222;602.4156568128908;657.1457105762489;7599.392048636207;8692.869606955901;9571.061788118164;9703.417794932186;9706.417614943126;472.28920474686066;566.9886602268014;584.4593750780512;654.9017647352936;660.8281846719888;4474.351219073216;4647.976760116246;4723.055388922229;4757.976210118927;4772.856814295576;97085;121104;130690.34654826856;150915.2271193207;168697;132599;140144;140398;141400;143421.9604627666;110206;112179.5128194863;113581;114040;117100.02049702745;6783.4573234142135;8882.911170888467;9051.773705657411;9101.932663662757;9114.589843456928;61806.38193618006;61851.54079721034;64325.39186520375;65063.34936650573;68259;94419.52790236005;98598.52102218616;100477.49761251439;101418;103309.48345258526;11661.53353865836;11670.358130302575;11722.413879306172;11978.880211198126;12403.193792403465;12976.286304253357;13139.802902956271;13302.46790128384;13739.725205495633;13765.724685506435;9825.312228143961;10854.945725271638;12535.874641253835;12536.686582835502;12796.936015319863;11909.880901190878;12111.394430278275;12368.567100151451;12524.123311368117;12668.936655316664;11984.520619175135;11987.460564274454;12292.893639572123;12365.505379784709;12401.62795116148;9801.656942006995;10246.333988290737;10255.820580633024;10272.537735801758;10337.9483102584;7974.760757177294;9247.630094796134;9314.25485961135;10109.898901010896;10480.528376222936 09:36:27 INFO - PROCESS | 1651 | __end_tp_report 09:36:27 INFO - PROCESS | 1651 | __start_cc_report 09:36:27 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1733 09:36:27 INFO - PROCESS | 1651 | __end_cc_report 09:36:27 INFO - PROCESS | 1651 | __startTimestamp1475685387871__endTimestamp 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | ------- Summary: start ------- 09:36:27 INFO - PROCESS | 1651 | Number of tests: 6 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5657.25 Median:5866.77 stddev:648.05 (11.0%) stddev-sans-first:623.02 09:36:27 INFO - PROCESS | 1651 | Values: 3296.7 3297.7 3322.8 3347.1 3354.1 5527.2 5572.2 5666.1 5705.6 5728.2 5659.8 5661.2 5748.5 5752.0 5759.3 4700.8 5032.4 5082.8 5099.2 5209.8 5214.6 5232.4 5234.3 5247.7 5279.0 4159.8 5163.2 5171.9 5225.1 5284.5 5086.9 5157.4 5166.4 5179.1 5187.4 5074.8 5090.5 5121.1 5140.0 5187.9 5570.7 5617.1 5663.8 5664.0 5691.5 6029.3 6163.6 6189.2 6192.3 6195.6 5283.8 6122.7 6195.7 6200.8 6215.1 5721.5 5725.2 5728.6 5742.5 5758.3 6205.6 6269.6 6274.9 6288.3 6294.4 5909.0 5975.7 5985.4 6077.4 6081.1 6094.3 6127.1 6135.0 6141.8 6146.9 6099.9 6128.2 6147.8 6157.5 6169.6 6171.4 6180.1 6192.0 6193.5 6204.0 4768.9 4836.0 4853.9 4867.4 4899.6 4336.9 4370.0 4370.2 4393.3 4420.2 5616.3 5696.1 5699.6 5710.9 5716.9 5169.6 6248.8 6272.1 6276.5 6282.3 4925.0 5580.7 5614.5 5637.0 5651.6 5374.9 5380.8 5383.1 5384.6 5439.9 6254.1 6264.1 6272.7 6283.4 6286.4 6079.6 6146.7 6258.0 6259.6 6279.6 6192.0 6220.8 6250.6 6273.2 6325.3 5417.8 6094.3 6102.9 6110.8 6112.7 5992.1 6161.7 6186.8 6214.2 6229.9 4936.5 5814.3 5879.3 5938.0 6026.3 6003.5 6052.3 6059.8 6076.8 6078.5 5882.8 5884.9 5892.6 5903.0 5920.5 5854.7 5946.8 5951.5 5962.2 6012.5 5864.2 5866.8 5887.6 5918.9 5936.5 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:70773.97 Median:40158.40 stddev:156916.14 (390.7%) stddev-sans-first:157267.61 09:36:27 INFO - PROCESS | 1651 | Values: 491.7 497.9 499.9 503.7 528.6 29871.9 33337.3 33396.0 39686.4 43286.8 41467.4 41874.0 41907.8 41937.4 41949.8 9670.9 9870.6 9871.1 9878.0 9910.9 9313.1 9858.6 9862.9 9867.9 9878.0 9814.3 9879.9 9881.0 9898.7 9904.2 9759.3 9843.3 9844.5 9862.2 9870.0 8266.3 9610.9 9736.6 9745.4 9755.8 39884.4 40133.0 40183.8 40229.6 40237.0 3253.8 3304.6 3333.3 3343.2 3413.3 23498.8 30530.1 30624.9 31153.0 32262.0 3148.0 3288.9 3318.3 3340.7 3366.2 492848.0 496322.0 498112.0 499481.5 499552.0 59352.0 59743.0 59778.4 59816.4 59871.1 63518.7 63625.7 63639.7 63744.4 63837.7 15623.7 15823.6 15934.2 15940.8 15962.4 12136.8 12196.9 12211.0 12224.2 12234.8 15003.9 18223.2 18296.3 18421.6 18429.2 6256.9 7345.9 7630.5 7740.0 7745.3 820.4 914.4 977.9 1006.4 1033.8 24800.0 29080.0 29144.0 29777.6 30034.4 993.3 999.6 1039.9 1068.6 1106.3 15120.8 15215.4 15277.8 15346.0 15381.6 632166.0 669949.0 670660.0 670985.0 672801.0 55180.7 55861.7 55946.7 55987.0 56110.0 1310.7 1358.1 1358.9 1365.7 1370.1 28837.1 28957.9 29013.4 29803.9 29836.0 47208.5 47674.0 47707.8 47756.3 47915.5 584189.0 585634.0 586898.0 587507.0 587818.0 25191.7 25327.0 25503.6 25639.5 25772.0 45050.8 45997.8 46131.0 46344.0 46509.3 34243.3 36890.8 37090.6 38915.6 39107.6 36985.4 37774.8 37800.8 38694.8 38785.6 36908.8 37103.3 37957.8 38196.8 38453.0 28081.0 32635.0 34390.1 34422.3 34999.7 18830.4 24164.8 24725.3 25238.0 25785.9 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:34079.88 Median:23516.53 stddev:96739.53 (411.4%) stddev-sans-first:96977.73 09:36:27 INFO - PROCESS | 1651 | Values: 334.8 344.3 362.8 364.1 383.9 15144.8 15254.8 16216.8 18388.4 21140.8 19896.1 20153.5 20486.7 20647.7 20873.6 5834.9 6207.6 6225.0 6237.9 6242.0 4612.5 6116.9 6171.5 6182.4 6193.6 5960.1 6010.6 6090.6 6128.6 6164.4 5950.1 6075.6 6078.0 6085.8 6097.8 5149.4 5236.7 5239.1 5415.5 5472.1 21630.9 21763.1 23491.8 23541.3 24054.8 1986.4 2061.6 2161.2 2285.0 2304.5 9480.8 10372.4 10373.0 11334.2 15727.9 1824.5 1868.7 1869.5 1888.1 1905.7 112150.0 112370.4 125258.5 125671.4 129038.4 10956.7 11111.0 11231.7 11385.0 11508.4 14250.5 14295.1 14323.9 14329.8 14332.0 8160.1 8263.6 8281.7 8286.0 8439.9 8112.8 8175.9 8301.9 8307.3 8328.6 3647.1 4423.4 4428.4 4460.1 4527.4 1643.7 1683.5 1840.7 1907.4 1909.7 655.9 775.5 867.5 896.7 938.1 16235.8 19311.1 20099.2 20148.7 20460.7 884.6 893.7 906.2 918.2 995.0 7954.5 10672.8 10782.9 10788.8 10872.6 565501.0 575597.0 578219.0 585983.0 586356.0 74983.0 113215.4 125632.4 145630.0 147680.0 953.6 971.4 1021.7 1025.7 1026.7 14248.7 17109.4 17337.7 19291.2 19318.4 56728.7 57901.4 58207.0 58593.4 58636.7 64813.7 68956.7 71977.0 75890.0 78828.6 6810.3 6863.5 7181.0 7199.6 7450.9 9392.8 12719.4 12980.7 13311.9 13370.0 10739.2 10834.4 11271.4 11318.7 11326.2 10954.8 11286.4 11416.4 11574.4 11713.0 9333.3 11488.0 11546.5 11666.9 11977.2 7940.5 8008.4 8224.6 8289.0 8344.9 10422.3 11030.7 11353.7 11433.3 11472.6 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5710.15 Median:5051.83 stddev:3206.62 (63.5%) stddev-sans-first:3198.74 09:36:27 INFO - PROCESS | 1651 | Values: 1333.2 1527.3 1717.9 1896.4 1932.6 8544.5 9085.0 9089.5 9089.7 9100.0 7767.7 7857.6 8171.0 8176.0 8497.9 2252.8 2351.4 2357.8 2360.9 2361.9 2344.8 2345.9 2375.6 2378.4 2382.9 2355.4 2361.7 2370.9 2379.2 2379.8 2327.5 2352.4 2358.5 2360.5 2365.6 2305.8 2342.1 2348.4 2353.3 2355.2 8852.6 8890.8 8908.0 8938.4 8939.7 5080.7 5337.6 5342.0 5343.4 5346.4 3568.5 3583.6 3651.1 3653.8 3654.2 1063.0 1078.9 1080.6 1093.3 1102.8 11546.5 11649.2 11660.9 11711.9 11746.5 4450.6 4549.5 4714.6 4764.2 4768.5 9903.4 9929.4 9929.4 9931.8 9940.2 5294.7 5301.3 5302.8 5307.8 5311.3 4374.8 4446.9 4568.7 4825.3 4832.8 3826.9 3875.3 3957.5 3995.3 3999.5 1117.6 1213.1 1240.8 1249.7 1280.0 838.3 839.9 850.5 897.8 957.6 5575.6 5639.3 5903.6 5983.2 6030.1 897.8 908.4 917.5 941.7 947.9 3803.2 3838.3 3888.5 3954.4 3960.8 10687.5 10988.8 11085.0 11143.3 11152.9 9245.8 11059.3 11067.8 11072.2 11078.3 7343.4 8120.3 8127.2 8158.0 8286.1 5841.6 5850.4 5960.6 5994.0 6056.5 7597.8 10123.1 10150.4 10174.5 10179.7 9732.1 10100.6 10162.8 10337.1 10359.0 7182.1 7514.3 7571.1 7613.9 7733.8 7209.0 8418.1 8504.7 8523.1 8526.3 7381.7 7481.8 7482.3 7647.0 7722.5 7262.3 7400.9 7430.0 7447.9 7459.6 6889.8 7058.2 7064.5 7073.0 7078.0 4885.4 5049.2 5054.5 5123.1 5201.4 4969.1 4976.7 5115.8 5124.9 5196.3 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4430.95 Median:4950.74 stddev:1367.79 (27.6%) stddev-sans-first:1335.91 09:36:27 INFO - PROCESS | 1651 | Values: 293.5 293.6 304.7 308.9 311.3 4076.7 4347.4 5185.8 5214.4 5279.8 4211.5 4288.9 5007.8 5134.9 5189.6 3901.4 4005.3 4019.5 4024.1 4036.5 3532.3 3817.4 4058.7 4064.8 4082.8 3948.7 3968.7 3977.1 3977.5 3996.4 3505.9 3932.6 3954.5 3963.0 3987.7 3016.0 3017.8 3429.6 3454.8 3469.9 4829.4 4873.8 5050.4 5157.6 5217.0 4477.7 4498.0 4519.3 4521.6 4544.4 4939.7 5443.5 5449.5 5451.0 5462.4 3464.5 3739.7 3749.2 3763.8 3770.4 5718.0 5751.0 5762.4 5768.4 5769.9 3209.9 3777.5 3793.6 3793.9 3793.9 3851.4 3860.6 3874.7 3892.9 3903.9 4070.5 4186.2 4477.9 4498.7 4520.1 5425.3 5509.7 5521.4 5525.5 5574.1 1243.1 1244.3 1268.9 1294.5 1298.9 862.7 876.8 890.8 895.4 896.8 5105.6 5121.9 5128.9 5138.0 5214.6 4679.4 4898.8 4905.7 5337.4 5504.3 4800.3 5070.3 5087.2 5094.4 5119.4 2576.5 2679.1 2930.5 2944.9 2957.1 5494.4 5783.6 5858.8 5862.7 5869.3 4877.2 5119.9 5420.5 5813.1 5891.3 4961.7 5868.5 5874.8 5901.6 5907.0 4767.7 5106.8 5710.3 5742.4 5760.3 5200.9 5581.6 5599.8 5608.0 5609.7 4926.7 5352.8 5901.0 5902.6 5905.7 3028.0 3636.5 3643.8 3775.3 3793.3 4680.6 5632.3 5640.5 5652.4 5667.0 4586.6 5298.7 5310.3 5405.4 5501.9 4602.9 5488.2 5527.8 5539.6 5540.9 4526.9 5022.3 5353.0 5393.6 5418.7 4671.4 5344.8 5538.6 5539.6 5560.6 4519.7 4754.8 5416.7 5478.8 5485.3 09:36:27 INFO - PROCESS | 1651 | 09:36:27 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:25168.04 Median:4045.48 stddev:40658.05 (1005.0%) stddev-sans-first:40730.53 09:36:27 INFO - PROCESS | 1651 | Values: 677.8 718.6 727.8 776.8 779.9 15002.9 15719.0 15728.8 15761.8 16166.0 14103.9 14130.9 14213.4 14315.2 14346.4 680.2 701.1 703.5 830.6 843.7 862.4 864.6 875.0 876.1 876.9 924.4 925.4 926.4 926.6 927.0 721.0 729.4 918.4 922.2 923.4 663.9 678.1 680.0 683.8 686.0 14903.3 14941.9 14964.2 14973.6 15042.8 13716.1 13948.2 14226.6 14259.7 14279.8 12762.0 12906.9 12961.8 13024.9 13038.9 4478.6 4571.2 4607.7 4785.9 4795.3 116326.8 118666.2 118872.0 119023.0 121494.0 11119.0 11139.5 11165.7 11187.2 11368.9 22304.4 22423.0 22500.5 22510.8 22752.0 7897.8 7906.4 7913.3 7976.3 8069.2 3988.9 4191.7 4200.0 4300.8 4351.3 3807.8 4033.3 4043.4 4047.6 4089.4 719.0 731.8 731.9 734.1 735.2 580.6 595.0 601.9 602.4 657.1 7599.4 8692.9 9571.1 9703.4 9706.4 472.3 567.0 584.5 654.9 660.8 4474.4 4648.0 4723.1 4758.0 4772.9 97085.0 121104.0 130690.3 150915.2 168697.0 132599.0 140144.0 140398.0 141400.0 143422.0 110206.0 112179.5 113581.0 114040.0 117100.0 6783.5 8882.9 9051.8 9101.9 9114.6 61806.4 61851.5 64325.4 65063.3 68259.0 94419.5 98598.5 100477.5 101418.0 103309.5 11661.5 11670.4 11722.4 11978.9 12403.2 12976.3 13139.8 13302.5 13739.7 13765.7 9825.3 10854.9 12535.9 12536.7 12796.9 11909.9 12111.4 12368.6 12524.1 12668.9 11984.5 11987.5 12292.9 12365.5 12401.6 9801.7 10246.3 10255.8 10272.5 10337.9 7974.8 9247.6 9314.3 10109.9 10480.5 09:36:27 INFO - PROCESS | 1651 | -------- Summary: end -------- 09:36:27 INFO - PROCESS | 1651 | 09:36:28 INFO - TEST-INFO | 1651: exit 0 09:36:28 INFO - TEST-OK | dromaeo_css | took 1119431ms 09:36:28 INFO - TEST-START | kraken 09:36:28 INFO - operating with platform_type : mac_ 09:36:28 INFO - Initialising browser for kraken test... 09:36:28 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp1PnDqy/profile http://localhost:49267/getInfo.html) 09:36:31 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 09:36:31 INFO - PROCESS | 1662 | colorDepth:24 09:36:31 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 09:36:31 INFO - PROCESS | 1662 | __metrics 09:36:31 INFO - PROCESS | 1662 | 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" 09:36:31 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:36:31 INFO - TEST-INFO | 1662: exit 0 09:36:31 INFO - Browser initialized. 09:36:31 INFO - Running cycle 1/1 for kraken test... 09:36:31 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp1PnDqy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:44 INFO - PROCESS | 1663 | RSS: Main: 235991040 09:36:44 INFO - PROCESS | 1663 | 09:36:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:47 INFO - PROCESS | 1663 | RSS: Main: 294551552 09:36:47 INFO - PROCESS | 1663 | 09:36:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 09:36:50 INFO - PROCESS | 1663 | RSS: Main: 293404672 09:36:50 INFO - PROCESS | 1663 | 09:36:54 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 09:36:55 INFO - PROCESS | 1663 | RSS: Main: 385884160 09:36:55 INFO - PROCESS | 1663 | 09:36:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 09:36:57 INFO - PROCESS | 1663 | RSS: Main: 289660928 09:36:57 INFO - PROCESS | 1663 | 09:36:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:00 INFO - PROCESS | 1663 | RSS: Main: 291241984 09:37:00 INFO - PROCESS | 1663 | 09:37:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:04 INFO - PROCESS | 1663 | RSS: Main: 329371648 09:37:04 INFO - PROCESS | 1663 | 09:37:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:07 INFO - PROCESS | 1663 | RSS: Main: 329916416 09:37:07 INFO - PROCESS | 1663 | 09:37:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 09:37:11 INFO - PROCESS | 1663 | RSS: Main: 330813440 09:37:11 INFO - PROCESS | 1663 | 09:37:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:13 INFO - PROCESS | 1663 | RSS: Main: 304791552 09:37:13 INFO - PROCESS | 1663 | 09:37:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:15 INFO - PROCESS | 1663 | RSS: Main: 304136192 09:37:15 INFO - PROCESS | 1663 | 09:37:17 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:18 INFO - PROCESS | 1663 | RSS: Main: 315981824 09:37:18 INFO - PROCESS | 1663 | 09:37:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:22 INFO - PROCESS | 1663 | RSS: Main: 321150976 09:37:22 INFO - PROCESS | 1663 | 09:37:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:26 INFO - PROCESS | 1663 | RSS: Main: 321449984 09:37:26 INFO - PROCESS | 1663 | 09:37:27 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 09:37:28 INFO - PROCESS | 1663 | RSS: Main: 365383680 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | __start_tp_report 09:37:28 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 09:37:28 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 09:37:28 INFO - PROCESS | 1663 | |i|pagename|runs| 09:37:28 INFO - PROCESS | 1663 | |0;ai-astar;113;113;112;113;112;113;112;113;112;114 09:37:28 INFO - PROCESS | 1663 | |1;audio-beat-detection;150;115;114;152;111;140;115;114;146;116 09:37:28 INFO - PROCESS | 1663 | |2;audio-dft;184;186;185;187;186;192;264;185;181;184 09:37:28 INFO - PROCESS | 1663 | |3;audio-fft;80;59;58;88;75;59;60;60;83;57 09:37:28 INFO - PROCESS | 1663 | |4;audio-oscillator;91;71;94;70;68;69;93;68;68;90 09:37:28 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;93;131;135;87;89;86;90;87;89;85 09:37:28 INFO - PROCESS | 1663 | |6;imaging-darkroom;97;98;97;98;98;97;98;97;114;137 09:37:28 INFO - PROCESS | 1663 | |7;imaging-desaturate;89;85;87;88;88;85;87;87;86;86 09:37:28 INFO - PROCESS | 1663 | |8;json-parse-financial;53;53;53;53;53;55;54;54;55;55 09:37:28 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;65;66;75;76;70;70;69;71;70;76 09:37:28 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;89;91;89;89;87;90;91;93;87;90 09:37:28 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;153;155;156;159;144;145;148;146;232;147 09:37:28 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;228;187;177;190;178;178;175;173;174;172 09:37:28 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;70;70;70;69;70;70;70;68;67;69 09:37:28 INFO - PROCESS | 1663 | __end_tp_report 09:37:28 INFO - PROCESS | 1663 | __start_cc_report 09:37:28 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2052 09:37:28 INFO - PROCESS | 1663 | __end_cc_report 09:37:28 INFO - PROCESS | 1663 | __startTimestamp1475685448165__endTimestamp 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | ------- Summary: start ------- 09:37:28 INFO - PROCESS | 1663 | Number of tests: 14 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:37:28 INFO - PROCESS | 1663 | Values: 113.0 113.0 112.0 113.0 112.0 113.0 112.0 113.0 112.0 114.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:128.00 stddev:17.28 (13.5%) stddev-sans-first:16.25 09:37:28 INFO - PROCESS | 1663 | Values: 150.0 115.0 114.0 152.0 111.0 140.0 115.0 114.0 146.0 116.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:193.40 Median:186.00 stddev:24.96 (13.4%) stddev-sans-first:26.24 09:37:28 INFO - PROCESS | 1663 | Values: 184.0 186.0 185.0 187.0 186.0 192.0 264.0 185.0 181.0 184.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:67.90 Median:67.50 stddev:12.15 (18.0%) stddev-sans-first:12.07 09:37:28 INFO - PROCESS | 1663 | Values: 80.0 59.0 58.0 88.0 75.0 59.0 60.0 60.0 83.0 57.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:80.50 stddev:11.96 (14.9%) stddev-sans-first:11.76 09:37:28 INFO - PROCESS | 1663 | Values: 91.0 71.0 94.0 70.0 68.0 69.0 93.0 68.0 68.0 90.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:88.00 stddev:19.03 (21.6%) stddev-sans-first:20.12 09:37:28 INFO - PROCESS | 1663 | Values: 93.0 131.0 135.0 87.0 89.0 86.0 90.0 87.0 89.0 85.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:97.50 stddev:13.00 (13.3%) stddev-sans-first:13.60 09:37:28 INFO - PROCESS | 1663 | Values: 97.0 98.0 97.0 98.0 98.0 97.0 98.0 97.0 114.0 137.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:87.00 stddev:1.32 (1.5%) stddev-sans-first:1.13 09:37:28 INFO - PROCESS | 1663 | Values: 89.0 85.0 87.0 88.0 88.0 85.0 87.0 87.0 86.0 86.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 09:37:28 INFO - PROCESS | 1663 | Values: 53.0 53.0 53.0 53.0 53.0 55.0 54.0 54.0 55.0 55.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.50 stddev:3.85 (5.5%) stddev-sans-first:3.47 09:37:28 INFO - PROCESS | 1663 | Values: 65.0 66.0 75.0 76.0 70.0 70.0 69.0 71.0 70.0 76.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:90.00 stddev:1.84 (2.0%) stddev-sans-first:1.94 09:37:28 INFO - PROCESS | 1663 | Values: 89.0 91.0 89.0 89.0 87.0 90.0 91.0 93.0 87.0 90.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:154.00 stddev:26.34 (17.1%) stddev-sans-first:27.86 09:37:28 INFO - PROCESS | 1663 | Values: 153.0 155.0 156.0 159.0 144.0 145.0 148.0 146.0 232.0 147.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:178.00 stddev:16.80 (9.4%) stddev-sans-first:6.24 09:37:28 INFO - PROCESS | 1663 | Values: 228.0 187.0 177.0 190.0 178.0 178.0 175.0 173.0 174.0 172.0 09:37:28 INFO - PROCESS | 1663 | 09:37:28 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:70.00 stddev:1.06 (1.5%) stddev-sans-first:1.09 09:37:28 INFO - PROCESS | 1663 | Values: 70.0 70.0 70.0 69.0 70.0 70.0 70.0 68.0 67.0 69.0 09:37:28 INFO - PROCESS | 1663 | -------- Summary: end -------- 09:37:28 INFO - PROCESS | 1663 | 09:37:29 INFO - TEST-INFO | 1663: exit 0 09:37:29 INFO - TEST-OK | kraken | took 60642ms 09:37:29 INFO - SUITE-END | took 1180s 09:37:29 INFO - Completed test suite (00:19:40) 09:37:29 INFO - javascript benchmark 09:37:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.653851345608, 3297.7032067113955, 3322.800631962081, 3347.0795531228923, 3354.1279267390473, 5527.226188333637, 5572.164175373696, 5666.121751128571, 5705.600607957439, 5728.22668939693, 5659.801906933259, 5661.207430959669, 5748.5401167906575, 5752.0, 5759.280089988752, 4700.788464519105, 5032.421271553763, 5082.796688132489, 5099.184130539114, 5209.765560549766, 5214.608904332172, 5232.424433312332, 5234.345706786652, 5247.685138891659, 5278.970600732859, 4159.81445288093, 5163.225516172604, 5171.948280517222, 5225.137852254374, 5284.497972692588, 5086.92369614456, 5157.4068982067165, 5166.3800343958965, 5179.093658609729, 5187.351581052369, 5074.797008119671, 5090.465501122403, 5121.103806833827, 5139.997700448414, 5187.936473023021, 5570.693611851347, 5617.0731829248125, 5663.801766938179, 5664.0, 5691.544676425876, 6029.306629737593, 6163.568550201488, 6189.226346706661, 6192.318844927055, 6195.597286176429, 5283.841484755463, 6122.733612273388, 6195.721192546346, 6200.844978875519, 6215.098810672473, 5721.485066344049, 5725.198472213893, 5728.598998070095, 5742.540596752333, 5758.280214973171, 6205.627662340274, 6269.62382257066, 6274.905876411857, 6288.308286088527, 6294.402031806971, 5908.96593096215, 5975.671338076448, 5985.401459854067, 6077.36187700294, 6081.057436097412, 6094.3367212805715, 6127.100598814382, 6135.049067394561, 6141.788955975845, 6146.938530614726, 6099.922829902115, 6128.228138398591, 6147.801178770186, 6157.476614487729, 6169.56813023084, 6171.352008039181, 6180.103884936659, 6192.040233763683, 6193.473554747807, 6203.96898015507, 4768.880777980509, 4835.984443266953, 4853.927191092066, 4867.367242258484, 4899.632527560376, 4336.9132617348105, 4369.978150109229, 4370.169667763115, 4393.275109606879, 4420.160169567772, 5616.269884914935, 5696.117101849137, 5699.5725320600295, 5710.857228569236, 5716.914246286309, 5169.612279079084, 6248.843778905563, 6272.090546870678, 6276.497880169667, 6282.308946015934, 4924.96575719103, 5580.720963951786, 5614.522765564891, 5637.0, 5651.604387692904, 5374.892502149935, 5380.78476860929, 5383.138697808332, 5384.623076384615, 5439.945600544023, 6254.061890716338, 6264.091706702594, 6272.686365681789, 6283.37166283368, 6286.4342209201395, 6079.574429790049, 6146.692665366803, 6257.9687101564205, 6259.561830671809, 6279.560430769802, 6192.0, 6220.813375598739, 6250.593711408744, 6273.247210334789, 6325.304216536086, 5417.83746487606, 6094.268687757498, 6102.908456373247, 6110.7861224856915, 6112.694365281807, 5992.13114098462, 6161.72272247741, 6186.81439556814, 6214.1921550198185, 6229.906551401823, 4936.506349365035, 5814.30228372598, 5879.323877754113, 5938.049912014057, 6026.337102918587, 6003.459688628044, 6052.334243233153, 6059.787907423219, 6076.848078798065, 6078.513718902565, 5882.764689412546, 5884.852878678067, 5892.558058145742, 5903.0, 5920.467157955804, 5854.677992710442, 5946.762129514944, 5951.464368206709, 5962.2249107615735, 6012.488938440281, 5864.179014937827, 5866.765329386947, 5887.61730487517, 5918.852028699317, 5936.465718085393], "value": 5616.246771163534, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.70989116421305, 497.87055365604954, 499.91751361025433, 503.6700960870631, 528.6114705691316, 29871.850640746794, 33337.33325333492, 33395.99999999997, 39686.40470392943, 43286.7835660822, 41467.37798933018, 41874.0, 41907.790461047654, 41937.37093943593, 41949.79025104878, 9670.85493717593, 9870.555824987892, 9871.111599956002, 9878.0, 9910.900890991106, 9313.068693130701, 9858.605655773761, 9862.852057219148, 9867.851982220272, 9877.950610246975, 9814.263930205227, 9879.901200988006, 9881.011898810133, 9898.703038908809, 9904.207663386911, 9759.316847820655, 9843.310968232227, 9844.458554779449, 9862.161716254128, 9869.950650246703, 8266.256036956642, 9610.855837162448, 9736.610535578568, 9745.415275083518, 9755.756106097333, 39884.40173397402, 40133.0, 40183.7990810047, 40229.597704023174, 40237.0, 3253.7885037472724, 3304.6091386721887, 3333.333333333343, 3343.164208947763, 3413.266147778215, 23498.798244045833, 30530.08409747689, 30624.865643138113, 31153.0, 32262.0, 3148.0, 3288.93422131556, 3318.2699806042633, 3340.6659334066644, 3366.175287054663, 492848.0, 496322.0, 498112.0, 499481.50259248837, 499552.0, 59352.0, 59742.999999999134, 59778.40221597729, 59816.40183598195, 59871.101933471036, 63518.68240658767, 63625.681871591274, 63639.72720545563, 63744.36255637477, 63837.680811595645, 15623.687526249412, 15823.604409889615, 15934.203289835461, 15940.760888586681, 15962.361505539648, 12136.817947730791, 12196.939015304866, 12210.9620682243, 12224.205426647239, 12234.75530489403, 15003.924980375028, 18223.17995690197, 18296.268149274147, 18421.63156736858, 18429.170687318834, 6256.874862502816, 7345.853082938312, 7630.504017238862, 7740.0, 7745.264199901, 820.3601191070974, 914.3782228084833, 977.9168770654574, 1006.4263369879098, 1033.7674023344687, 24800.0, 29080.0, 29143.979960701698, 29777.553336699966, 30034.399312014073, 993.2699465892563, 999.6301368493704, 1039.9116075133697, 1068.556549032143, 1106.280917403678, 15120.790336773032, 15215.391384344724, 15277.847221527643, 15346.0, 15381.568458525795, 632166.0, 669949.0, 670660.0, 670985.0, 672801.0, 55180.724096380065, 55861.720691397095, 55946.720266398406, 55987.0, 56110.0, 1310.7181955879532, 1358.130796290375, 1358.8777010068943, 1365.6858922447693, 1370.0546622830216, 28837.134885953456, 28957.85521072381, 29013.41973160567, 29803.85098074496, 29836.0, 47208.52791472041, 47674.0, 47707.76146119247, 47756.28365574589, 47915.5208447925, 584189.0, 585634.0, 586898.0, 587507.0, 587818.0, 25191.74808251894, 25327.0, 25503.6174457387, 25639.487210256062, 25772.0, 45050.77474612606, 45997.77001114973, 46131.0, 46344.0, 46509.302360463946, 34243.31513369669, 36890.8155459221, 37090.62909370872, 38915.6108438912, 39107.6089239104, 36985.44521832228, 37774.811125944194, 37800.81099594484, 38694.80652596831, 38785.6121438782, 36908.81545592255, 37103.25793484169, 37957.810210948766, 38196.809015955856, 38453.0, 28081.0, 32635.0, 34390.14024649404, 34422.31155376929, 34999.65000350066, 18830.435086947415, 24164.75835241625, 24725.258242252763, 25238.0, 25785.871070644527], "value": 19641.775405948276, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.81585128179495, 344.26842958712734, 362.8349101158973, 364.05709213137965, 383.9385698288274, 15144.772828407582, 15254.847451525482, 16216.837831621695, 18388.356407525738, 21140.788592114113, 19896.10467528961, 20153.496162595944, 20486.692699609517, 20647.69028464574, 20873.582528349423, 5834.883302333961, 6207.565470417073, 6224.968875155618, 6237.937620623804, 6241.968790156043, 4612.492625811158, 6116.877662446771, 6171.506279497629, 6182.443580077792, 6193.597416167957, 5960.135780311853, 6010.609310394811, 6090.634561926298, 6128.57100003, 6164.4143806338325, 5950.107483877409, 6075.605085669439, 6078.0, 6085.786997455112, 6097.756089756404, 5149.382074151088, 5236.7119808410525, 5239.13554263546, 5415.512603865671, 5472.069748142825, 21630.891845540827, 21763.129474820988, 23491.7650823493, 23541.293761187022, 24054.759452405426, 1986.3544348086784, 2061.597988392264, 2161.1571487120036, 2284.977150228493, 2304.481491664379, 9480.762980925532, 10372.376035824223, 10373.014563616445, 11334.206605537616, 15727.921360393124, 1824.4982629776785, 1868.7383766272733, 1869.541962219265, 1888.1125870840683, 1905.6760350740408, 112150.0, 112370.43814780956, 125258.4948301038, 125671.37164314212, 129038.3548082263, 10956.726081848017, 11111.0, 11231.71920701989, 11385.0, 11508.36703981289, 14250.501232456814, 14295.142291462338, 14323.928380358031, 14329.78505322421, 14332.0, 8160.102388737234, 8263.586820658944, 8281.668733250723, 8285.95857020711, 8439.873401898976, 8112.756617301372, 8175.918240817635, 8301.916980830236, 8307.293880020266, 8328.625211865481, 3647.0699971507097, 4423.424954755926, 4428.357888106207, 4460.130274596422, 4527.411436513232, 1643.6794825009008, 1683.5117815833516, 1840.693107893384, 1907.427771668494, 1909.7230901519201, 655.8589903170841, 775.4571799740204, 867.492516877621, 896.6682327538853, 938.1275413865171, 16235.756463653055, 19311.103334499658, 20099.19603215884, 20148.697769533468, 20460.69308960367, 884.6284560484612, 893.6738090596886, 906.2342320738965, 918.2241006349624, 994.955296938212, 7954.522728636184, 10672.78654426907, 10782.946085269681, 10788.784224315626, 10872.565097396173, 565501.0, 575597.0, 578219.0, 585983.0, 586356.0, 74983.0, 113215.43392282986, 125632.37183814023, 145630.0, 147680.0, 953.594722243044, 971.3734641156415, 1021.7241344836852, 1025.6512785652915, 1026.6714651311509, 14248.71502569922, 17109.374609412087, 17337.653246935242, 19291.228350865807, 19318.420447386037, 56728.71635641796, 57901.420985789606, 58207.0, 58593.4140658605, 58636.706816465645, 64813.73151939659, 68956.6552167236, 71977.0, 75890.0, 78828.60585697264, 6810.31896810315, 6863.485238607024, 7181.0, 7199.604021778852, 7450.8882366763455, 9392.765180870532, 12719.364031798557, 12980.675483112998, 13311.86688133145, 13370.0, 10739.248252622241, 10834.449884692292, 11271.436428178722, 11318.717032074264, 11326.150538709795, 10954.780904382027, 11286.379249141377, 11416.372099534605, 11574.421278935852, 11713.0, 9333.25333973294, 11487.966083052564, 11546.48040838181, 11666.88333116658, 11977.221480603734, 7940.523568585903, 8008.399370047153, 8224.62989165477, 8289.0, 8344.874826877724, 10422.322549034288, 11030.669079927615, 11353.659390218307, 11433.314001159957, 11472.598459053894], "value": 9334.36413686812, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.1934179821205, 1527.2821773766332, 1717.9398721044759, 1896.4405500377388, 1932.6231384879954, 8544.530050847201, 9084.954575227122, 9089.500077495735, 9089.727308180765, 9099.99900010999, 7767.68929242831, 7857.607119644023, 8171.019477662689, 8175.95912020439, 8497.872531912011, 2252.763459836718, 2351.412146963259, 2357.7760112789256, 2360.881955902198, 2361.9055237790553, 2344.8358614896965, 2345.870977096259, 2375.6317770745477, 2378.4172877645, 2382.868942208178, 2355.4346956730346, 2361.6693662887124, 2370.9407264818433, 2379.179183181808, 2379.7620237976153, 2327.464683122891, 2352.4471749139025, 2358.5282943411303, 2360.5160942006846, 2365.6451532270125, 2305.827062970284, 2342.121704360865, 2348.377679914824, 2353.3410645019253, 2355.187460326185, 8852.557372131432, 8890.799742034807, 8908.0, 8938.374313798036, 8939.73180804577, 5080.74596270185, 5337.626366154371, 5342.038433082043, 5343.385510666284, 5346.4118946915805, 3568.4825700273313, 3583.623719509466, 3651.050726811022, 3653.8173091345325, 3654.214343916071, 1062.9627963021258, 1078.9244752867303, 1080.616381184676, 1093.2565855218447, 1102.779444111181, 11546.53813847445, 11649.242799218024, 11660.941695291469, 11711.945924866803, 11746.530138794438, 4450.577195166454, 4549.499555048942, 4714.575688188011, 4764.213904705754, 4768.499307572724, 9903.405795652285, 9929.404235745735, 9929.404235745878, 9931.80136397268, 9940.155086817556, 5294.655847369986, 5301.284326615934, 5302.761375738024, 5307.787688492417, 5311.256424100629, 4374.803133858984, 4446.866594002185, 4568.725876447424, 4825.30033145199, 4832.830850920201, 3826.942595861064, 3875.2636998970106, 3957.5053118360206, 3995.2808494471274, 3999.460072890123, 1117.6032508459543, 1213.1386715431966, 1240.7580521798163, 1249.731307768834, 1279.9552015679405, 838.3000194837379, 839.8661806561093, 850.4769566716549, 897.838389089957, 957.6121670723368, 5575.609707320448, 5639.3232812062815, 5903.645781253139, 5983.222181116514, 6030.085738882071, 897.7665806890255, 908.3641450984205, 917.4678686361895, 941.6610020392653, 947.9052094790603, 3803.1633040730994, 3838.3091043612417, 3888.51393575803, 3954.367301231789, 3960.801959902051, 10687.519061642244, 10988.780224395627, 11085.0, 11143.275687080313, 11152.944235278772, 9245.815083698288, 11059.281146725438, 11067.833982490269, 11072.224944253825, 11078.335299882034, 7343.449241306924, 8120.269175774209, 8127.187281271826, 8158.0, 8286.129956354616, 5841.58562985786, 5850.444207800252, 5960.6125601837475, 5994.0, 6056.485198758155, 7597.772066838003, 10123.088921996761, 10150.441725705157, 10174.49127543605, 10179.7455063624, 9732.12410883024, 10100.646477373257, 10162.847557286794, 10337.121344685786, 10359.015893490106, 7182.102237220348, 7514.286142816423, 7571.129320128035, 7613.9238607613215, 7733.845323093619, 7208.9639551804, 8418.116097809641, 8504.702335418231, 8523.105073967392, 8526.275266602408, 7381.704731810667, 7481.7755467336065, 7482.289182527651, 7646.961765191361, 7722.53664780115, 7262.273772622695, 7400.888986665097, 7429.962850185931, 7447.888281675888, 7459.589722565094, 6889.827754306182, 7058.223595404399, 7064.505484616233, 7073.0, 7078.0, 4885.389326334208, 5049.166887463674, 5054.494550544916, 5123.129068058513, 5201.375834899836, 4969.130402179532, 4976.651634385703, 5115.769790359517, 5124.898791495469, 5196.324477818011], "value": 4586.131839959448, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.4966532396939, 293.55819491665045, 304.7470599402495, 308.8532946850245, 311.32597925491314, 4076.653484453818, 4347.413099231603, 5185.766640501177, 5214.374275086991, 5279.8416047518585, 4211.452511173551, 4288.935665965013, 5007.824726134586, 5134.948650513494, 5189.558887494558, 3901.356276214421, 4005.259027079985, 4019.5176578810438, 4024.134811015631, 4036.475258216435, 3532.311199316134, 3817.351050321438, 4058.69559783016, 4064.796760161995, 4082.83668653255, 3948.6643635291034, 3968.6825053995613, 3977.076848516338, 3977.542582602994, 3996.440498330236, 3505.929881402383, 3932.58707835676, 3954.545227298854, 3962.960370396288, 3987.6809855211513, 3015.9547606785914, 3017.8038427502142, 3429.6227414984332, 3454.809985450799, 3469.8785542505893, 4829.372181616367, 4873.829415970424, 5050.419201791804, 5157.613179011551, 5216.973915130438, 4477.664175186841, 4497.977510112429, 4519.276915693506, 4521.638268938477, 4544.409226800528, 4939.728314942677, 5443.503036664909, 5449.482299181572, 5450.974709036484, 5462.371827239839, 3464.532288141119, 3739.7284923125994, 3749.212665340282, 3763.8306276217363, 3770.4155855842387, 5717.9714101429645, 5751.0, 5762.366139724627, 5768.423157684198, 5769.855753606152, 3209.935801283961, 3777.490038844721, 3793.6206379361843, 3793.905152371158, 3793.943090853639, 3851.401668307677, 3860.613938606173, 3874.748141370802, 3892.9221415572097, 3903.941440878389, 4070.5115386153852, 4186.225548273579, 4477.910441791146, 4498.730076195438, 4520.141173177151, 5425.267588875451, 5509.696966666792, 5521.420250873681, 5525.530329921921, 5574.080276754371, 1243.055277988717, 1244.3031902134833, 1268.892144167756, 1294.4692676002794, 1298.8506321772927, 862.6714859117016, 876.7939534209455, 890.7951171230653, 895.4403497813867, 896.8296023755465, 5105.591552675777, 5121.8719532011255, 5128.9484206317775, 5137.974310128426, 5214.584447187099, 4679.3916790816975, 4898.804047838046, 4905.70565766048, 5337.386200586904, 5504.339479262514, 4800.279958006327, 5070.340855688737, 5087.19404611365, 5094.439611642716, 5119.385673719177, 2576.510463012059, 2679.0891097027106, 2930.4725149473297, 2944.8969286074885, 2957.137003687663, 5494.423085576037, 5783.595148339576, 5858.794942177004, 5862.677552734641, 5869.325027621793, 4877.219644856806, 5119.897602047939, 5420.512153906088, 5813.1460230707835, 5891.293044834561, 4961.742407204713, 5868.501177399969, 5874.82631426375, 5901.5868889177345, 5907.025340818716, 4767.6662633615315, 5106.821261255838, 5710.2862142232225, 5742.397048309867, 5760.279965004374, 5200.893638076585, 5581.5813813963305, 5599.832005039855, 5607.971960140337, 5609.691466969356, 4926.655134140576, 5352.785888564414, 5900.9610050823, 5902.61632993854, 5905.704714764331, 3027.9697203027686, 3636.5272514573994, 3643.836027378721, 3775.301569209705, 3793.336166170854, 4680.625549956064, 5632.295963004624, 5640.492355688008, 5652.434756524314, 5667.0, 4586.628827811623, 5298.7350632469, 5310.2831117798605, 5405.405405405481, 5501.9174712380145, 4602.861914142581, 5488.231647569423, 5527.778888844562, 5539.556835453234, 5540.889182216414, 4526.8682829292675, 5022.3219865317715, 5353.0, 5393.568514518907, 5418.729063546885, 4671.360681215103, 5344.793124125494, 5538.6399884007415, 5539.612227144061, 5560.555155587463, 4519.711882113582, 4754.786034628381, 5416.674999500043, 5478.835634930959, 5485.286912701324], "value": 3996.9181125858804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.762783025941, 718.593994393168, 727.8253219227383, 776.8329809091044, 779.9275995506179, 15002.924985375072, 15718.978266412689, 15728.842711572895, 15761.842381576182, 16166.0, 14103.858961410382, 14130.858691413083, 14213.360398782053, 14315.21266330354, 14346.42614295427, 680.1600023970393, 701.0500771454656, 703.5075447186964, 830.6220669595319, 843.6878355008655, 862.3833958719514, 864.6411739128254, 875.0374587953232, 876.0932434929849, 876.8728534362514, 924.4129977464291, 925.4493576322078, 926.4256161180044, 926.6247169896203, 927.0312523413049, 720.9567425954459, 729.3763831923682, 918.393860052369, 922.1930810540777, 923.4090182283344, 663.8506336074392, 678.1421501800227, 679.952403331767, 683.8153698501377, 686.0189928402383, 14903.254837258093, 14941.850581494155, 14964.17697026674, 14973.625659358495, 15042.849571504254, 13716.108452950526, 13948.16311021342, 14226.644333891632, 14259.714805703932, 14279.78580321296, 12762.0, 12906.870931290754, 12961.805572916413, 13024.86975130246, 13038.934805325913, 4478.596926276651, 4571.2457444521615, 4607.746573938432, 4785.880352991203, 4795.256735206049, 116326.83673163244, 118666.22000669996, 118872.0, 119023.0, 121494.0, 11119.0, 11139.498722557504, 11165.66503004895, 11187.160962927814, 11368.94315528417, 22304.442388940082, 22423.0, 22500.549989000454, 22510.774892251196, 22752.0, 7897.763067107881, 7906.446548741533, 7913.287804097658, 7976.322012628992, 8069.241275559653, 3988.880333589939, 4191.706580539394, 4200.012996945716, 4300.80646370914, 4351.325544540601, 3807.809609519513, 4033.2941735196223, 4043.4137050127565, 4047.5547689754103, 4089.427480152781, 718.9568625882464, 731.8170457385654, 731.8536292741367, 734.0933946575974, 735.1876176824638, 580.5993864233666, 594.9553783466171, 601.8736065426222, 602.4156568128908, 657.1457105762489, 7599.392048636207, 8692.869606955901, 9571.061788118164, 9703.417794932186, 9706.417614943126, 472.28920474686066, 566.9886602268014, 584.4593750780512, 654.9017647352936, 660.8281846719888, 4474.351219073216, 4647.976760116246, 4723.055388922229, 4757.976210118927, 4772.856814295576, 97085.0, 121104.0, 130690.34654826856, 150915.2271193207, 168697.0, 132599.0, 140144.0, 140398.0, 141400.0, 143421.9604627666, 110206.0, 112179.5128194863, 113581.0, 114040.0, 117100.02049702745, 6783.4573234142135, 8882.911170888467, 9051.773705657411, 9101.932663662757, 9114.589843456928, 61806.38193618006, 61851.54079721034, 64325.39186520375, 65063.34936650573, 68259.0, 94419.52790236005, 98598.52102218616, 100477.49761251439, 101418.0, 103309.48345258526, 11661.53353865836, 11670.358130302575, 11722.413879306172, 11978.880211198126, 12403.193792403465, 12976.286304253357, 13139.802902956271, 13302.46790128384, 13739.725205495633, 13765.724685506435, 9825.312228143961, 10854.945725271638, 12535.874641253835, 12536.686582835502, 12796.936015319863, 11909.880901190878, 12111.394430278275, 12368.567100151451, 12524.123311368117, 12668.936655316664, 11984.520619175135, 11987.460564274454, 12292.893639572123, 12365.505379784709, 12401.62795116148, 9801.656942006995, 10246.333988290737, 10255.820580633024, 10272.537735801758, 10337.9483102584, 7974.760757177294, 9247.630094796134, 9314.25485961135, 10109.898901010896, 10480.528376222936], "value": 7744.711974653643, "unit": "score"}], "name": "dromaeo_css", "value": 7258.092763615221, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 114.0, 152.0, 111.0, 140.0, 115.0, 114.0, 146.0, 116.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 187.0, 186.0, 192.0, 264.0, 185.0, 181.0, 184.0], "value": 193.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 88.0, 75.0, 59.0, 60.0, 60.0, 83.0, 57.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 94.0, 70.0, 68.0, 69.0, 93.0, 68.0, 68.0, 90.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 131.0, 135.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 98.0, 98.0, 97.0, 98.0, 97.0, 114.0, 137.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 88.0, 88.0, 85.0, 87.0, 87.0, 86.0, 86.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 53.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 75.0, 76.0, 70.0, 70.0, 69.0, 71.0, 70.0, 76.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 89.0, 87.0, 90.0, 91.0, 93.0, 87.0, 90.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 155.0, 156.0, 159.0, 144.0, 145.0, 148.0, 146.0, 232.0, 147.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 187.0, 177.0, 190.0, 178.0, 178.0, 175.0, 173.0, 174.0, 172.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 70.0, 69.0, 70.0, 70.0, 70.0, 68.0, 67.0, 69.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1491.8, "alertThreshold": 2.0}]} 09:37:29 INFO - Return code: 0 09:37:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:37:29 INFO - # TBPL SUCCESS # 09:37:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:29 INFO - 2016-10-05 09:37:29.497 screenresolution[1667:9824] starting screenresolution argv=screenresolution get 09:37:29 INFO - 2016-10-05 09:37:29.500 screenresolution[1667:9824] Display 0: 1600x1200x32@60 09:37:29 INFO - 2016-10-05 09:37:29.506 screenresolution[1668:9827] starting screenresolution argv=screenresolution list 09:37:29 INFO - Available Modes on Display 0 09:37:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:29 INFO - Intel Iris: 09:37:29 INFO - Chipset Model: Intel Iris 09:37:29 INFO - Type: GPU 09:37:29 INFO - Bus: Built-In 09:37:29 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:29 INFO - Vendor: Intel (0x8086) 09:37:29 INFO - Device ID: 0x0a2e 09:37:29 INFO - Revision ID: 0x0009 09:37:29 INFO - Displays: 09:37:29 INFO - Display: 09:37:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:29 INFO - Display Serial Number: 1600x1200 60 09:37:29 INFO - Main Display: Yes 09:37:29 INFO - Mirror: Off 09:37:29 INFO - Online: Yes 09:37:29 INFO - Rotation: Supported 09:37:29 INFO - Return code: 0 09:37:29 INFO - Running post-action listener: _resource_record_post_action 09:37:29 INFO - [mozharness: 2016-10-05 16:37:29.749785Z] Finished run-tests step (success) 09:37:29 INFO - Running post-run listener: _resource_record_post_run 09:37:29 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 38679552; Write bytes: 705355776; Read time: 376; Write time: 2171 09:37:29 INFO - TinderboxPrint: CPU usage
53.8% 09:37:29 INFO - TinderboxPrint: I/O read bytes / time
38,679,552 / 376 09:37:29 INFO - TinderboxPrint: I/O write bytes / time
705,355,776 / 2,171 09:37:29 INFO - TinderboxPrint: CPU idle
2,246.2 (46.2%) 09:37:29 INFO - TinderboxPrint: CPU system
983.5 (20.2%) 09:37:29 INFO - TinderboxPrint: CPU user
1,632.1 (33.6%) 09:37:29 INFO - TinderboxPrint: Swap in / out
446,447,616 / 0 09:37:29 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387609088; Write bytes: 379069952; Read time: 29382; Write time: 688 09:37:29 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37708800; Write bytes: 295655936; Read time: 360; Write time: 1428 09:37:30 INFO - Running post-run listener: _upload_blobber_files 09:37:30 INFO - Blob upload gear active. 09:37:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:30 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 09:37:30 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'] 09:37:30 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 09:37:30 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:37:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:37:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:31 INFO - (blobuploader) - INFO - Done attempting. 09:37:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:37:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:37:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:32 INFO - (blobuploader) - INFO - Done attempting. 09:37:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:34 INFO - (blobuploader) - INFO - Done attempting. 09:37:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:34 INFO - Return code: 0 09:37:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a86721015f81a53580abfd905662341a4395b6d0f7d13a23c88f7a0e22f5d585f44b4bbb8a781c1cbf6f62d6b7d781dbc194405f471ad664dc7610345ad21b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f2eede36735d0d87e03fc9e251991c20676921c2482b62b7d3954e131f2e8d30f35cac7a15bd99e9ffc9838d8c9462a7a42c997f1898b298a4ded2dd0f7c573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a492721297f426dc43ab4427919e10acf059e62d96b3a271e5691cffb0ace03ba98496ee67903a9bd885b453e12a860bb41647dc73a7fb72730a043a84d1933"} 09:37:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:34 INFO - Contents: 09:37:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a86721015f81a53580abfd905662341a4395b6d0f7d13a23c88f7a0e22f5d585f44b4bbb8a781c1cbf6f62d6b7d781dbc194405f471ad664dc7610345ad21b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f2eede36735d0d87e03fc9e251991c20676921c2482b62b7d3954e131f2e8d30f35cac7a15bd99e9ffc9838d8c9462a7a42c997f1898b298a4ded2dd0f7c573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a492721297f426dc43ab4427919e10acf059e62d96b3a271e5691cffb0ace03ba98496ee67903a9bd885b453e12a860bb41647dc73a7fb72730a043a84d1933"} 09:37:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:34 INFO - Copying logs to upload dir... 09:37:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.918995 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-05 09:37:34.756851) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 09:37:34.759886) ========= 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.lj3riFmeqf/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.oPKXAB5dVR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/3a86721015f81a53580abfd905662341a4395b6d0f7d13a23c88f7a0e22f5d585f44b4bbb8a781c1cbf6f62d6b7d781dbc194405f471ad664dc7610345ad21b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f2eede36735d0d87e03fc9e251991c20676921c2482b62b7d3954e131f2e8d30f35cac7a15bd99e9ffc9838d8c9462a7a42c997f1898b298a4ded2dd0f7c573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a492721297f426dc43ab4427919e10acf059e62d96b3a271e5691cffb0ace03ba98496ee67903a9bd885b453e12a860bb41647dc73a7fb72730a043a84d1933"} build_url:https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011117 build_url: 'https://queue.taskcluster.net/v1/task/Gt5B5t1LRFaQmUEtnnxrjA/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/3a86721015f81a53580abfd905662341a4395b6d0f7d13a23c88f7a0e22f5d585f44b4bbb8a781c1cbf6f62d6b7d781dbc194405f471ad664dc7610345ad21b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f2eede36735d0d87e03fc9e251991c20676921c2482b62b7d3954e131f2e8d30f35cac7a15bd99e9ffc9838d8c9462a7a42c997f1898b298a4ded2dd0f7c573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a492721297f426dc43ab4427919e10acf059e62d96b3a271e5691cffb0ace03ba98496ee67903a9bd885b453e12a860bb41647dc73a7fb72730a043a84d1933"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 09:37:34.792458) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:37:34.792767) ========= 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.lj3riFmeqf/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.oPKXAB5dVR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005039 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:37:34.841423) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 09:37:34.841713) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 09:37:34.842010) ========= ========= Total master_lag: 0.72 =========