builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0201 starttime: 1476794235.16 results: success (0) buildid: 20161018030211 builduid: 1dba9a568c854acdbcce636c5aeceee8 revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.162914) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.163958) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.164532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.189720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.190168) ========= 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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-18 05:37:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2016-10-18 05:37:15 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099945 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.312566) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.312904) ========= 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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.026154 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:15.363046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 05:37:15.363531) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --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 dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JmoSe0GZDS/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.S5KAJD6pXC/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-18 05:37:15,442 truncating revision to first 12 chars 2016-10-18 05:37:15,442 Setting DEBUG logging. 2016-10-18 05:37:15,442 attempt 1/10 2016-10-18 05:37:15,442 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dc89484d4b45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 05:37:17,354 unpacking tar archive at: mozilla-central-dc89484d4b45/testing/mozharness/ program finished with exit code 0 elapsedTime=2.161206 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 05:37:17.547050) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:17.547384) ========= script_repo_revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:17.547792) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:17.548114) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 05:37:17.566163) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-18 05:37:17.566819) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JmoSe0GZDS/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.S5KAJD6pXC/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:37:17 INFO - MultiFileLogger online at 20161018 05:37:17 in /builds/slave/test 05:37: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 05:37:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:37:17 INFO - {'append_to_log': False, 05:37:17 INFO - 'base_work_dir': '/builds/slave/test', 05:37:17 INFO - 'blob_upload_branch': 'Firefox', 05:37:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:37:17 INFO - 'branch': 'Firefox', 05:37:17 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:17 INFO - 'config_files': ('talos/mac_config.py',), 05:37:17 INFO - 'default_actions': ('clobber', 05:37:17 INFO - 'read-buildbot-config', 05:37:17 INFO - 'download-and-extract', 05:37:17 INFO - 'populate-webroot', 05:37:17 INFO - 'create-virtualenv', 05:37:17 INFO - 'install', 05:37:17 INFO - 'run-tests'), 05:37:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:17 INFO - 'download_minidump_stackwalk': True, 05:37:17 INFO - 'download_symbols': 'ondemand', 05:37:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:17 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:17 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:17 INFO - 'installer_path': 'installer.exe', 05:37:17 INFO - 'log_level': 'info', 05:37:17 INFO - 'log_name': 'talos', 05:37:17 INFO - 'log_to_console': True, 05:37:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:37:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:37:17 INFO - 'opt_config_files': (), 05:37:17 INFO - 'pip_index': False, 05:37:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:17 INFO - 'cmd': ('bash', 05:37:17 INFO - '-c', 05:37:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:17 INFO - 'enabled': True, 05:37:17 INFO - 'halt_on_failure': False, 05:37:17 INFO - 'name': 'check_screen_resolution'},), 05:37:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:17 INFO - 'cmd': ('bash', 05:37:17 INFO - '-c', 05:37:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:17 INFO - 'enabled': True, 05:37:17 INFO - 'halt_on_failure': False, 05:37:17 INFO - 'name': 'check_screen_resolution'},), 05:37:17 INFO - 'run_cmd_checks_enabled': True, 05:37:17 INFO - 'sps_profile': False, 05:37:17 INFO - 'sps_profile_interval': 0, 05:37:17 INFO - 'suite': 'dromaeojs', 05:37:17 INFO - 'system_bits': '32', 05:37:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:17 INFO - 'title': 't-yosemite-r7-0201', 05:37:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:17 INFO - 'use_talos_json': True, 05:37:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:37:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:17 INFO - 'work_dir': 'build'} 05:37:17 INFO - [mozharness: 2016-10-18 12:37:17.763768Z] Running clobber step. 05:37:17 INFO - Running pre-action listener: _resource_record_pre_action 05:37:17 INFO - Running main action method: clobber 05:37:17 INFO - rmtree: /builds/slave/test/build 05:37:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:37:19 INFO - Running post-action listener: _resource_record_post_action 05:37:19 INFO - [mozharness: 2016-10-18 12:37:19.954431Z] Finished clobber step (success) 05:37:19 INFO - [mozharness: 2016-10-18 12:37:19.954639Z] Running read-buildbot-config step. 05:37:19 INFO - Running pre-action listener: _resource_record_pre_action 05:37:19 INFO - Running main action method: read_buildbot_config 05:37:19 INFO - Using buildbot properties: 05:37:19 INFO - { 05:37:19 INFO - "project": "", 05:37:19 INFO - "product": "firefox", 05:37:19 INFO - "script_repo_revision": "production", 05:37:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:37:19 INFO - "repository": "", 05:37:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:37:19 INFO - "buildid": "20161018030211", 05:37:19 INFO - "stage_platform": "macosx64", 05:37:19 INFO - "basedir": "/builds/slave/test", 05:37:19 INFO - "nightly_build": "True", 05:37:19 INFO - "buildnumber": 13, 05:37:19 INFO - "slavename": "t-yosemite-r7-0201", 05:37:19 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:37:19 INFO - "platform": "yosemite_r7", 05:37:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:37:19 INFO - "branch": "mozilla-central", 05:37:19 INFO - "repo_path": "mozilla-central", 05:37:19 INFO - "revision": "dc89484d4b45abf442162e5ea2dd46f9de40197d", 05:37:19 INFO - "pgo_build": "False", 05:37:19 INFO - "builduid": "1dba9a568c854acdbcce636c5aeceee8", 05:37:19 INFO - "slavebuilddir": "test" 05:37:19 INFO - } 05:37:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:37:19 INFO - Running post-action listener: _resource_record_post_action 05:37:19 INFO - [mozharness: 2016-10-18 12:37:19.960523Z] Finished read-buildbot-config step (success) 05:37:19 INFO - [mozharness: 2016-10-18 12:37:19.960720Z] Running download-and-extract step. 05:37:19 INFO - Running pre-action listener: _resource_record_pre_action 05:37:19 INFO - Running main action method: download_and_extract 05:37:19 INFO - mkdir: /builds/slave/test/build/tests 05:37: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')]} 05:37:19 INFO - trying https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:19 INFO - Downloading https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:37:20 INFO - Downloaded 1199 bytes. 05:37:20 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:20 INFO - Using the following test package requirements: 05:37:20 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:20 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:37:20 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:37:20 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'jsshell-mac.zip'], 05:37:20 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:37:20 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:20 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:37:20 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:37:20 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:37:20 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:20 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:37:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:37:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:20 INFO - Fetch https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:21 INFO - Http code: 200 05:37:21 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 05:37:21 INFO - x-amz-cf-id: Qp7fztY_el9Mo0Sp5hYymhuOfSnIex6USz2oikYoswrVyvINxJBAXA== 05:37:21 INFO - x-amz-version-id: IjNGZkv8gtPWv1fI9myArFSD00VpuBfT 05:37:21 INFO - Expected file size: 16483805 05:37:21 INFO - Obtained file size: 16483805 05:37:28 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:37:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:28 INFO - Fetch https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:28 INFO - Http code: 200 05:37:28 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 05:37:28 INFO - x-amz-cf-id: u_0t2UnyGmyMS926jGOGXhQFfhKExYFcHmNNOWnF43oCEgGg10fUJg== 05:37:28 INFO - x-amz-version-id: IjNGZkv8gtPWv1fI9myArFSD00VpuBfT 05:37:28 INFO - Expected file size: 16483805 05:37:28 INFO - Obtained file size: 16483805 05:37:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:37:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:37:35 INFO - Fetch https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:37:35 INFO - Http code: 200 05:37:35 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 05:37:35 INFO - x-amz-cf-id: UYz-TEGG3EW-8olkCSB_erApJRCRIU-jj-WsLahaHhmvx-YTZEUBxg== 05:37:35 INFO - x-amz-version-id: VbDfXZiz7rB1nc0kqtG.stM3vgoRpANu 05:37:36 INFO - Expected file size: 14409028 05:37:36 INFO - Obtained file size: 14409028 05:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:36 INFO - trying https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:36 INFO - Downloading https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:37:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:37:39 INFO - Downloaded 111767649 bytes. 05:37:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:39 INFO - mkdir: /builds/slave/test/properties 05:37:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:39 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:39 INFO - Contents: 05:37:39 INFO - build_url:https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:39 INFO - Running post-action listener: _resource_record_post_action 05:37:39 INFO - Running post-action listener: set_extra_try_arguments 05:37:39 INFO - [mozharness: 2016-10-18 12:37:39.986947Z] Finished download-and-extract step (success) 05:37:39 INFO - [mozharness: 2016-10-18 12:37:39.987076Z] Running populate-webroot step. 05:37:39 INFO - Running pre-action listener: _resource_record_pre_action 05:37:39 INFO - Running main action method: populate_webroot 05:37:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:39 INFO - '--tppagecycles', 05:37:39 INFO - '7'], 05:37:39 INFO - 'tests': ['tsvgm']}}, 05:37:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:37:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:37:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:37:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:39 INFO - 'talos_options': ['--disable-e10s'], 05:37:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:39 INFO - 'talos_options': ['--disable-e10s'], 05:37:39 INFO - 'tests': ['damp', 'tps']}, 05:37:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:39 INFO - 'tests': ['damp', 'tps']}, 05:37:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:37:39 INFO - 'tests': ['dromaeo_dom']}, 05:37:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:37:39 INFO - 'tests': ['basic_compositor_video']}, 05:37:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:37:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:37:39 INFO - 'tests': ['a11yr', 05:37:39 INFO - 'ts_paint', 05:37:39 INFO - 'tpaint', 05:37:39 INFO - 'sessionrestore', 05:37:39 INFO - 'sessionrestore_no_auto_restore', 05:37:39 INFO - 'tabpaint']}, 05:37:39 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:40 INFO - 'ts_paint', 05:37:40 INFO - 'tpaint', 05:37:40 INFO - 'sessionrestore', 05:37:40 INFO - 'sessionrestore_no_auto_restore', 05:37:40 INFO - 'tabpaint']}, 05:37:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:37:40 INFO - 'ts_paint', 05:37:40 INFO - 'tpaint', 05:37:40 INFO - 'sessionrestore', 05:37:40 INFO - 'sessionrestore_no_auto_restore', 05:37:40 INFO - 'tabpaint']}, 05:37:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:37:40 INFO - 'ts_paint', 05:37:40 INFO - 'tpaint', 05:37:40 INFO - 'sessionrestore', 05:37:40 INFO - 'sessionrestore_no_auto_restore', 05:37:40 INFO - 'tabpaint']}, 05:37:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:37:40 INFO - 'tests': ['a11yr', 05:37:40 INFO - 'ts_paint', 05:37:40 INFO - 'tpaint', 05:37:40 INFO - 'sessionrestore', 05:37:40 INFO - 'sessionrestore_no_auto_restore', 05:37:40 INFO - 'tabpaint']}, 05:37:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:37:40 INFO - 'tests': ['a11yr', 05:37:40 INFO - 'ts_paint', 05:37:40 INFO - 'tpaint', 05:37:40 INFO - 'sessionrestore', 05:37:40 INFO - 'sessionrestore_no_auto_restore', 05:37:40 INFO - 'tabpaint']}, 05:37:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:37:40 INFO - 'tests': ['tsvgx', 05:37:40 INFO - 'tsvgr_opacity', 05:37:40 INFO - 'tart', 05:37:40 INFO - 'tscrollx', 05:37:40 INFO - 'cart']}, 05:37:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:40 INFO - 'tsvgr_opacity', 05:37:40 INFO - 'tart', 05:37:40 INFO - 'tscrollx', 05:37:40 INFO - 'cart']}, 05:37:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:40 INFO - 'talos_options': ['--disable-e10s'], 05:37:40 INFO - 'tests': ['tp5o']}, 05:37:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:40 INFO - 'tests': ['tp5o']}, 05:37:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:40 INFO - 'talos_options': ['--disable-e10s', 05:37:40 INFO - '--xperf_path', 05:37:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:40 INFO - 'tests': ['tp5n']}, 05:37:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:40 INFO - 'talos_options': ['--xperf_path', 05:37:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:40 INFO - 'tests': ['tp5n']}}, 05:37:40 INFO - 'talos.zip': {'path': '', 05:37:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:40 INFO - Running post-action listener: _resource_record_post_action 05:37:40 INFO - [mozharness: 2016-10-18 12:37:40.006457Z] Finished populate-webroot step (success) 05:37:40 INFO - [mozharness: 2016-10-18 12:37:40.006585Z] Running create-virtualenv step. 05:37:40 INFO - Running pre-action listener: _resource_record_pre_action 05:37:40 INFO - Running main action method: create_virtualenv 05:37:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:37:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:40 INFO - Using real prefix '/tools/python27' 05:37:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:41 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:43 INFO - Installing pip.................done. 05:37:43 INFO - Return code: 0 05:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:37:44 INFO - Reading from file tmpfile_stdout 05:37:44 INFO - Output received: 05:37:44 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:37:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:44 INFO - 'HOME': '/Users/cltbld', 05:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:44 INFO - 'LOGNAME': 'cltbld', 05:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:44 INFO - 'MOZ_NO_REMOTE': '1', 05:37:44 INFO - 'NO_EM_RESTART': '1', 05:37:44 INFO - 'PAGER': '/bin/cat', 05:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:44 INFO - 'PWD': '/builds/slave/test', 05:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:44 INFO - 'SHELL': '/bin/bash', 05:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:37:44 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:44 INFO - 'USER': 'cltbld', 05:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:44 INFO - 'XPC_FLAGS': '0x0', 05:37:44 INFO - 'XPC_SERVICE_NAME': '0', 05:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:37:44 INFO - Cleaning up... 05:37:44 INFO - Return code: 0 05:37:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:44 INFO - 'HOME': '/Users/cltbld', 05:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:44 INFO - 'LOGNAME': 'cltbld', 05:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:44 INFO - 'MOZ_NO_REMOTE': '1', 05:37:44 INFO - 'NO_EM_RESTART': '1', 05:37:44 INFO - 'PAGER': '/bin/cat', 05:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:44 INFO - 'PWD': '/builds/slave/test', 05:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:44 INFO - 'SHELL': '/bin/bash', 05:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:37:44 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:44 INFO - 'USER': 'cltbld', 05:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:44 INFO - 'XPC_FLAGS': '0x0', 05:37:44 INFO - 'XPC_SERVICE_NAME': '0', 05:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:44 INFO - Downloading/unpacking psutil>=3.1.1 05:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:48 INFO - Installing collected packages: psutil 05:37:48 INFO - Running setup.py install for psutil 05:37:48 INFO - building 'psutil._psutil_osx' extension 05:37:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:37:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:37:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:37:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:37:49 INFO - building 'psutil._psutil_posix' extension 05:37:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:37:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:37:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:37:49 INFO - ^ 05:37:49 INFO - 1 warning generated. 05:37:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:37:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:49 INFO - Successfully installed psutil 05:37:49 INFO - Cleaning up... 05:37:49 INFO - Return code: 0 05:37:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:49 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:37:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:49 INFO - 'HOME': '/Users/cltbld', 05:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:49 INFO - 'LOGNAME': 'cltbld', 05:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:49 INFO - 'MOZ_NO_REMOTE': '1', 05:37:49 INFO - 'NO_EM_RESTART': '1', 05:37:49 INFO - 'PAGER': '/bin/cat', 05:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:49 INFO - 'PWD': '/builds/slave/test', 05:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:49 INFO - 'SHELL': '/bin/bash', 05:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:37:49 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:49 INFO - 'USER': 'cltbld', 05:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:49 INFO - 'XPC_FLAGS': '0x0', 05:37:49 INFO - 'XPC_SERVICE_NAME': '0', 05:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:37:52 INFO - Installing collected packages: mozsystemmonitor 05:37:52 INFO - Running setup.py install for mozsystemmonitor 05:37:52 INFO - Successfully installed mozsystemmonitor 05:37:52 INFO - Cleaning up... 05:37:52 INFO - Return code: 0 05:37:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:52 INFO - 'HOME': '/Users/cltbld', 05:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:52 INFO - 'LOGNAME': 'cltbld', 05:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:52 INFO - 'MOZ_NO_REMOTE': '1', 05:37:52 INFO - 'NO_EM_RESTART': '1', 05:37:52 INFO - 'PAGER': '/bin/cat', 05:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:52 INFO - 'PWD': '/builds/slave/test', 05:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:52 INFO - 'SHELL': '/bin/bash', 05:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:37:52 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:52 INFO - 'USER': 'cltbld', 05:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:52 INFO - 'XPC_FLAGS': '0x0', 05:37:52 INFO - 'XPC_SERVICE_NAME': '0', 05:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:52 INFO - Downloading/unpacking jsonschema==2.5.1 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:37:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:55 INFO - Downloading functools32-3.2.3-2.tar.gz 05:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:37:55 INFO - warning: no files found matching '*.txt' 05:37:55 INFO - no previously-included directories found matching 'build' 05:37:55 INFO - no previously-included directories found matching 'dist' 05:37:55 INFO - no previously-included directories found matching '.git*' 05:37:55 INFO - Installing collected packages: jsonschema, functools32 05:37:55 INFO - Running setup.py install for functools32 05:37:55 INFO - warning: no files found matching '*.txt' 05:37:55 INFO - no previously-included directories found matching 'build' 05:37:55 INFO - no previously-included directories found matching 'dist' 05:37:55 INFO - no previously-included directories found matching '.git*' 05:37:55 INFO - Successfully installed jsonschema functools32 05:37:55 INFO - Cleaning up... 05:37:55 INFO - Return code: 0 05:37:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:55 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:37:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:37:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:55 INFO - 'HOME': '/Users/cltbld', 05:37:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:55 INFO - 'LOGNAME': 'cltbld', 05:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:55 INFO - 'MOZ_NO_REMOTE': '1', 05:37:55 INFO - 'NO_EM_RESTART': '1', 05:37:55 INFO - 'PAGER': '/bin/cat', 05:37:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:55 INFO - 'PWD': '/builds/slave/test', 05:37:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:55 INFO - 'SHELL': '/bin/bash', 05:37:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:37:55 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:37:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:55 INFO - 'USER': 'cltbld', 05:37:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:55 INFO - 'XPC_FLAGS': '0x0', 05:37:55 INFO - 'XPC_SERVICE_NAME': '0', 05:37:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:37:56 INFO - Cleaning up... 05:37:56 INFO - Return code: 0 05:37:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:56 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:37:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:37:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:56 INFO - 'HOME': '/Users/cltbld', 05:37:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:56 INFO - 'LOGNAME': 'cltbld', 05:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:56 INFO - 'MOZ_NO_REMOTE': '1', 05:37:56 INFO - 'NO_EM_RESTART': '1', 05:37:56 INFO - 'PAGER': '/bin/cat', 05:37:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:56 INFO - 'PWD': '/builds/slave/test', 05:37:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:56 INFO - 'SHELL': '/bin/bash', 05:37:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:37:56 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:37:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:56 INFO - 'USER': 'cltbld', 05:37:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:56 INFO - 'XPC_FLAGS': '0x0', 05:37:56 INFO - 'XPC_SERVICE_NAME': '0', 05:37:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:56 INFO - Downloading/unpacking blobuploader==1.2.4 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:58 INFO - Downloading blobuploader-1.2.4.tar.gz 05:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:37:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:37:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:59 INFO - Downloading docopt-0.6.1.tar.gz 05:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:00 INFO - Running setup.py install for blobuploader 05:38:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:38:00 INFO - Running setup.py install for requests 05:38:00 INFO - Running setup.py install for docopt 05:38:00 INFO - Successfully installed blobuploader requests docopt 05:38:00 INFO - Cleaning up... 05:38:01 INFO - Return code: 0 05:38:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:01 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:38:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:01 INFO - 'HOME': '/Users/cltbld', 05:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:01 INFO - 'LOGNAME': 'cltbld', 05:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:01 INFO - 'MOZ_NO_REMOTE': '1', 05:38:01 INFO - 'NO_EM_RESTART': '1', 05:38:01 INFO - 'PAGER': '/bin/cat', 05:38:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:01 INFO - 'PWD': '/builds/slave/test', 05:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:01 INFO - 'SHELL': '/bin/bash', 05:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:38:01 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:01 INFO - 'USER': 'cltbld', 05:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:01 INFO - 'XPC_FLAGS': '0x0', 05:38:01 INFO - 'XPC_SERVICE_NAME': '0', 05:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-qet86R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-nczvHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-myrLoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-5WMrK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-FVKZQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-dd4a0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-SMmPzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-cnxSkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-g8IEkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-CWwnD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-2ppXaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-HO4CVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Ex49I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-I8vABq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-wmsB45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-EZz42i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:02 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-26OG5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:03 INFO - Running setup.py install for manifestparser 05:38:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Running setup.py install for mozcrash 05:38:03 INFO - Running setup.py install for mozdebug 05:38:03 INFO - Running setup.py install for mozdevice 05:38:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Running setup.py install for mozfile 05:38:03 INFO - Running setup.py install for mozhttpd 05:38:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Running setup.py install for mozinfo 05:38:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for mozInstall 05:38:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for mozleak 05:38:04 INFO - Running setup.py install for mozlog 05:38:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for moznetwork 05:38:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for mozprocess 05:38:04 INFO - Running setup.py install for mozprofile 05:38:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for mozrunner 05:38:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozscreenshot 05:38:05 INFO - Running setup.py install for moztest 05:38:05 INFO - Running setup.py install for mozversion 05:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:38:05 INFO - Cleaning up... 05:38:05 INFO - Return code: 0 05:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:05 INFO - 'HOME': '/Users/cltbld', 05:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:05 INFO - 'LOGNAME': 'cltbld', 05:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:05 INFO - 'MOZ_NO_REMOTE': '1', 05:38:05 INFO - 'NO_EM_RESTART': '1', 05:38:05 INFO - 'PAGER': '/bin/cat', 05:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:05 INFO - 'PWD': '/builds/slave/test', 05:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:05 INFO - 'SHELL': '/bin/bash', 05:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:38:05 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:05 INFO - 'USER': 'cltbld', 05:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:05 INFO - 'XPC_FLAGS': '0x0', 05:38:05 INFO - 'XPC_SERVICE_NAME': '0', 05:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:05 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-JWHodC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-nGpbae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-PfoNne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Mbo2KD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-3pkA3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-CjLpvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-F0v2AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-LJJ78t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-VWYGPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-5vMh8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:06 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-5_Y5zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:07 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-5bzpKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:07 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-8SL_VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:07 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-HtLAob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:07 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-WvgHSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:07 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-CD2GES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:07 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Ot_KuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:10 INFO - Downloading blessings-1.6.tar.gz 05:38:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:10 INFO - Installing collected packages: blessings 05:38:10 INFO - Running setup.py install for blessings 05:38:10 INFO - Successfully installed blessings 05:38:10 INFO - Cleaning up... 05:38:10 INFO - Return code: 0 05:38:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:10 INFO - Reading from file tmpfile_stdout 05:38:10 INFO - Current package versions: 05:38:10 INFO - blessings == 1.6 05:38:10 INFO - blobuploader == 1.2.4 05:38:10 INFO - docopt == 0.6.1 05:38:10 INFO - functools32 == 3.2.3-2 05:38:10 INFO - jsonschema == 2.5.1 05:38:10 INFO - manifestparser == 1.1 05:38:10 INFO - mozInstall == 1.12 05:38:10 INFO - mozcrash == 0.17 05:38:10 INFO - mozdebug == 0.1 05:38:10 INFO - mozdevice == 0.48 05:38:10 INFO - mozfile == 1.2 05:38:10 INFO - mozhttpd == 0.7 05:38:10 INFO - mozinfo == 0.9 05:38:10 INFO - mozleak == 0.1 05:38:10 INFO - mozlog == 3.4 05:38:10 INFO - moznetwork == 0.27 05:38:10 INFO - mozprocess == 0.23 05:38:10 INFO - mozprofile == 0.28 05:38:10 INFO - mozrunner == 6.12 05:38:10 INFO - mozscreenshot == 0.1 05:38:10 INFO - mozsystemmonitor == 0.3 05:38:10 INFO - moztest == 0.7 05:38:10 INFO - mozversion == 1.4 05:38:10 INFO - psutil == 3.1.1 05:38:10 INFO - requests == 1.2.3 05:38:10 INFO - wsgiref == 0.1.2 05:38:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:10 INFO - 'HOME': '/Users/cltbld', 05:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:10 INFO - 'LOGNAME': 'cltbld', 05:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:10 INFO - 'MOZ_NO_REMOTE': '1', 05:38:10 INFO - 'NO_EM_RESTART': '1', 05:38:10 INFO - 'PAGER': '/bin/cat', 05:38:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:10 INFO - 'PWD': '/builds/slave/test', 05:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:10 INFO - 'SHELL': '/bin/bash', 05:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:38:10 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:10 INFO - 'USER': 'cltbld', 05:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:10 INFO - 'XPC_FLAGS': '0x0', 05:38:10 INFO - 'XPC_SERVICE_NAME': '0', 05:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:38:11 INFO - Cleaning up... 05:38:11 INFO - Return code: 0 05:38:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:11 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 0x10301c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f92570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2b157dd20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, '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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:38:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:38:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:38:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:11 INFO - 'HOME': '/Users/cltbld', 05:38:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:11 INFO - 'LOGNAME': 'cltbld', 05:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:11 INFO - 'MOZ_NO_REMOTE': '1', 05:38:11 INFO - 'NO_EM_RESTART': '1', 05:38:11 INFO - 'PAGER': '/bin/cat', 05:38:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:11 INFO - 'PWD': '/builds/slave/test', 05:38:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:11 INFO - 'SHELL': '/bin/bash', 05:38:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:38:11 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:38:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:11 INFO - 'USER': 'cltbld', 05:38:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:11 INFO - 'XPC_FLAGS': '0x0', 05:38:11 INFO - 'XPC_SERVICE_NAME': '0', 05:38:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:38:11 INFO - Cleaning up... 05:38:11 INFO - Return code: 0 05:38:11 INFO - Running post-action listener: _resource_record_post_action 05:38:11 INFO - Running post-action listener: _start_resource_monitoring 05:38:11 INFO - Starting resource monitoring. 05:38:11 INFO - [mozharness: 2016-10-18 12:38:11.346307Z] Finished create-virtualenv step (success) 05:38:11 INFO - [mozharness: 2016-10-18 12:38:11.347291Z] Running install step. 05:38:11 INFO - Running pre-action listener: _resource_record_pre_action 05:38:11 INFO - Running main action method: install 05:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:11 INFO - Reading from file tmpfile_stdout 05:38:11 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:38:11 INFO - Reading from file tmpfile_stdout 05:38:11 INFO - Output received: 05:38:11 INFO - Usage: mozinstall [options] installer 05:38:11 INFO - Options: 05:38:11 INFO - -h, --help show this help message and exit 05:38:11 INFO - -d DEST, --destination=DEST 05:38:11 INFO - Directory to install application into. [default: 05:38:11 INFO - "/builds/slave/test"] 05:38:11 INFO - --app=APP Application being installed. [default: firefox] 05:38:11 INFO - mkdir: /builds/slave/test/build/application 05:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:38:45 INFO - Reading from file tmpfile_stdout 05:38:45 INFO - Output received: 05:38:45 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:38:45 INFO - Running post-action listener: _resource_record_post_action 05:38:45 INFO - [mozharness: 2016-10-18 12:38:45.591189Z] Finished install step (success) 05:38:45 INFO - [mozharness: 2016-10-18 12:38:45.591350Z] Running run-tests step. 05:38:45 INFO - Running pre-action listener: _resource_record_pre_action 05:38:45 INFO - Running main action method: run_tests 05:38:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:45 INFO - 2016-10-18 05:38:45.605 screenresolution[1639:7373] starting screenresolution argv=screenresolution get 05:38:45 INFO - 2016-10-18 05:38:45.608 screenresolution[1639:7373] Display 0: 1600x1200x32@60 05:38:45 INFO - 2016-10-18 05:38:45.614 screenresolution[1640:7375] starting screenresolution argv=screenresolution list 05:38:45 INFO - Available Modes on Display 0 05:38:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:45 INFO - Intel Iris: 05:38:45 INFO - Chipset Model: Intel Iris 05:38:45 INFO - Type: GPU 05:38:45 INFO - Bus: Built-In 05:38:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:45 INFO - Vendor: Intel (0x8086) 05:38:45 INFO - Device ID: 0x0a2e 05:38:45 INFO - Revision ID: 0x0009 05:38:45 INFO - Displays: 05:38:45 INFO - Display: 05:38:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:45 INFO - Display Serial Number: 1600x1200 60 05:38:45 INFO - Main Display: Yes 05:38:45 INFO - Mirror: Off 05:38:45 INFO - Online: Yes 05:38:45 INFO - Rotation: Supported 05:38:45 INFO - Return code: 0 05:38:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:45 INFO - Python 2.7.3 05:38:45 INFO - Return code: 0 05:38:45 INFO - grabbing minidump binary from tooltool 05:38: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')]} 05:38:45 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 0x102e30d30>, '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 0x102c56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e28b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:38:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:38:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:38:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:45 INFO - Return code: 0 05:38:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:38:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:38:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:38:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0201 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:38:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmoSe0GZDS/Render', 05:38:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:45 INFO - 'HOME': '/Users/cltbld', 05:38:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:45 INFO - 'LOGNAME': 'cltbld', 05:38:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:38:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:45 INFO - 'MOZ_NO_REMOTE': '1', 05:38:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:45 INFO - 'NO_EM_RESTART': '1', 05:38:45 INFO - 'PAGER': '/bin/cat', 05:38:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:45 INFO - 'PWD': '/builds/slave/test', 05:38:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:45 INFO - 'SHELL': '/bin/bash', 05:38:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S5KAJD6pXC/Listeners', 05:38:45 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 05:38:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:45 INFO - 'USER': 'cltbld', 05:38:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:45 INFO - 'XPC_FLAGS': '0x0', 05:38:45 INFO - 'XPC_SERVICE_NAME': '0', 05:38:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:38:46 INFO - mozversion application_buildid: 20161018030211 05:38:46 INFO - mozversion application_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 05:38:46 INFO - mozversion application_display_name: Nightly 05:38:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:46 INFO - mozversion application_name: Firefox 05:38:46 INFO - mozversion application_remotingname: firefox 05:38:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:38:46 INFO - mozversion application_vendor: Mozilla 05:38:46 INFO - mozversion application_version: 52.0a1 05:38:46 INFO - mozversion platform_buildid: 20161018030211 05:38:46 INFO - mozversion platform_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 05:38:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:38:46 INFO - mozversion platform_version: 52.0a1 05:38:46 INFO - using testdate: 1476794326 05:38:46 INFO - actual date: 1476794326 05:38:46 INFO - starting webserver on 'localhost:49274' 05:38:46 INFO - SUITE-START | Running 2 tests 05:38:46 INFO - TEST-START | dromaeo_css 05:38:46 INFO - operating with platform_type : mac_ 05:38:46 INFO - Initialising browser for dromaeo_css test... 05:38:46 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpYGxQmd/profile http://localhost:49274/getInfo.html) 05:38:48 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:38:48 INFO - PROCESS | 1650 | colorDepth:24 05:38:48 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:38:48 INFO - PROCESS | 1650 | __metrics 05:38:48 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:38:48 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:38:49 INFO - TEST-INFO | 1650: exit 0 05:38:49 INFO - Browser initialized. 05:38:49 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:49 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpYGxQmd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:01 INFO - PROCESS | 1652 | RSS: Main: 232017920 05:39:01 INFO - PROCESS | 1652 | 05:39:52 INFO - PROCESS | 1652 | 1476794392823 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:42:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 05:42:08 INFO - PROCESS | 1652 | RSS: Main: 406441984 05:42:08 INFO - PROCESS | 1652 | 05:45:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 05:45:11 INFO - PROCESS | 1652 | RSS: Main: 369160192 05:45:11 INFO - PROCESS | 1652 | 05:48:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 05:48:14 INFO - PROCESS | 1652 | RSS: Main: 347140096 05:48:14 INFO - PROCESS | 1652 | 05:51:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 05:51:17 INFO - PROCESS | 1652 | RSS: Main: 355278848 05:51:17 INFO - PROCESS | 1652 | 05:54:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 05:54:21 INFO - PROCESS | 1652 | RSS: Main: 336252928 05:54:21 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 05:57:25 INFO - PROCESS | 1652 | RSS: Main: 464031744 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | __start_tp_report 05:57:25 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:57:25 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:57:25 INFO - PROCESS | 1652 | |i|pagename|runs| 05:57:25 INFO - PROCESS | 1652 | |0;dojo.html;3323.9501407478883;3339.148517128132;3340.0480862954055;3372.5447064646264;3406.080358303258;5626.381098079208;5718.59969802114;5720.256366672332;5768.336641286253;5812.128180772881;5707.286589176353;5747.137929310606;5754.65472071675;5756.3092428908485;5763.049096899017;5176.715280659553;5204.479552044784;5205.973970130144;5243.10867152585;5256.132738098209;5222.5038621331105;5267.841964741045;5305.124654432014;5311.495407936259;5321.068812957737;5310.20346947957;5328.120860058105;5330.546903513206;5348.679079255237;5388.326459192601;4549.908022074711;5297.841064768063;5308.52223299901;5344.225087362311;5350.0904846176245;4055.634992850628;5044.772985215637;5225.895482090375;5228;5291.417944026154;5609.074502707049;5684.573656975744;5708.914366284509;5714.399988001242;5743.310802703702;6276.654783986879;6281.87436251277;6284.214473190851;6291.433770960635;6294.590851594632;6126.609281623656;6190.226221722285;6200.844978875519;6209.379062093754;6287.52843536732;5767.480926716616;5882.411758824083;5884.235049443545;5910.320313164041;5914.497267732291;6292.528060395397;6296.685165741695;6311.27420346666;6316.526260530479;6318.147050148263;6030.517558595302;6089.147519347295;6121.602095863755;6154.243201794671;6228.906566401508;6224.844378890473;6233.937660623426;6257.311695713468;6314.400131987546;6314.778982735582;6200.0079987202735;6213.751449942042;6259.405356491126;6267.843303917439;6305.96847015762;6134.620279381683;6303.842403939938;6317.336679648571;6323.177986861679;6328;4183.560726123774;4882.584980276645;4934.9753251233515;4938.209886418156;4939.333190019373;4481.5294394088805;4505.504394516601;4513.961788788597;4537.319402089713;4543.227651299287;5785.479306862402;5786.826395208151;5789.2763404574425;5789.913151302734;5796.391378905154;6182.814515564541;6236.563440559208;6266.060090986297;6275.96862015687;6276.152719382916;5777.711114444345;5796.362400135981;5804.564657650609;5804.564657650693;5804.912926306109;5426.131818908955;5434.83695489136;5435.673859568438;5443.292371991695;5445.346558412936;6245.5628106032145;6309.621422714652;6322.114903913363;6357.110004599267;6363.5863668861375;6293.685315734287;6312.968435157794;6315.90526142099;6322.715477803602;6331.746730130743;6118.527810132562;6194.721237544411;6223.37766223374;6291.087792270004;6340.1123842661145;5070.797168113234;6183.505319574513;6194.783182588588;6207.131001659862;6227.346128656426;6098.908516372347;6239.188905442264;6269.5611307208055;6290.591111577733;6312.368763123651;5978.073398623221;6022.217111775616;6085.269767627736;6114.969425152845;6117.694115294128;4951.762059485129;5889.028310328919;6124.173236613178;6131.478824299806;6221.377862213742;6105.694715264308;6109.877802444015;6112.083187521907;6115.755369785159;6118.724657390339;6153.353897840662;6157.599756015845;6187.381261873777;6200.224971878515;6209.534284928558;5865.3254875689845;5899.91150132757;5908.822735317947;5979.880402391841;5982.0727787193055 05:57:25 INFO - PROCESS | 1652 | |1;ext.html;511.09791218499356;519.2367220186326;522.4775224775225;525.3118414876512;551.6414330685052;30156;38257.42613860791;39713.80143099284;40802;42834.14331713364;31927;41151;42094;42498;42516.36225456621;9324.533773311341;9505.382150160218;9511.619535218582;9513.429194248332;9523.285753568476;9384.343095983284;9502.524873756285;9502.857457138149;9504.239660827117;9519.571619277114;9434.669786557472;9520.761980950463;9527.857082143739;9534.380265282769;9539.570719317648;9461.385009974365;9511.14399704023;9513.762155946088;9523.095305945924;9536.380135291252;7974.960125199337;9432.009638987945;9451.385659932152;9463.337566370357;9472.14750672436;36924.446133308025;37979.86060418192;37997.240055198745;38229.61770382316;38415.615843841486;3248.8538015789345;3347.815870127142;3364.5364266544134;3977.5624681285035;4035.6771458283265;29985.950491732685;30433.847830760704;30942.071737847902;31053.913113040937;31113.533297000562;3138.3252600690735;3234.544454995238;3239.82180980046;3300.455424854896;3307.074019274583;464178;464921;466691;468409.99999999657;470182;49811.0037799242;56400.71799640975;56666.150007749915;56844.14733778997;56922.861542768915;56792.71603642038;57897.13154302689;58041.41958580445;58079.41920580825;58086.4191358081;15399;15614.531564053097;15638.530844074696;15659.138747369005;15677.05937643745;12115.939420303019;12188.756224875453;12192.878071219175;12193.756124877631;12215.816762748565;13093.214407135602;14086.647833804032;14117.78823317651;14120.293985300694;14247.928760356132;5880.323762767251;5945.524358051345;5947.524198064231;6034.607750496204;6037.9433599119975;917.7292698653913;948.2319321349729;948.6442584030989;1026.1175389165312;1127.520803658442;29620.555691664642;29758.702412975592;30053.248668783457;30251.848740756155;30364.84817575898;779.3531368963746;884.6947803007952;1033.8087453820917;1044.1803184500284;1077.7305673581604;12739.745205095845;14195.290235488184;14564.927175364268;15408.265122779178;15429.845701543065;610340;648490;652035;652343;654862;57501.712491437276;57516;57546;57608.423915761145;57846.421535784946;1220.5789002794022;1274.5347947998914;1325.6155714842587;1373.6085215713274;1373.6359864635679;28384.71615283903;30000.549991749704;30106.24734381658;30414.695853041187;30774.53838192384;52379.73810130925;53044;53201.46798531965;53226.4677353237;53484.73257633687;404924;563784;569331;569418;570279;20249.898750506152;24824.875875620506;24856.130035448674;24902.626460602743;24914;43411;44113.77943110264;44135.33796992983;44746.3288050673;45684.77157614191;36694.44958325574;37182.44226336553;37361.25277494381;38172.427413588266;38289.425658614586;34280.82859585686;36588.902332930054;37047;38106.61893381031;38293.61706383012;31531.684683152875;36200;36737.26525469529;41223.58776412198;42809.785951070044;40013;42791;42855.785721071195;43170.352444713986;43400.78299608482;27608;31785.364292713555;32161.678383216804;32447.188820279684;32715.509267361485 05:57:25 INFO - PROCESS | 1652 | |2;jquery.html;313.2247686974738;355.22206368053975;363.54919899324847;381.37264200390354;384.62113323734417;13141.540046098376;14075.255325211576;14672.633184170401;15547.844521554809;20946;19702.90148549255;19772.517061220395;21181.68227476585;21298.36104916851;21344.8932755336;5499.587530935176;5993.310769261525;6012.068129439944;6044.365341639131;6065.423784740443;4791.472937976837;5864.5894787364905;6596.901046484307;6691.5650482718465;6749.898751518731;5699.943000569982;6700.229473610549;6705.228898676666;6707.5975441473665;6708.792417364873;5971.313298970631;6097.207363042776;6105.297890742532;6105.908411373833;6115.9694201529155;5045.091883460976;5306.09796334624;5393.2179833923865;5923.466887980059;5937.554683398719;13179.406926688227;13451.86548134516;13463.596092117252;16757.41349052777;25120.864726498086;2156.644153714635;2250;2325.68603238564;2361.220797136958;2524.798016158703;9699.175570076552;9817.558209880572;9853.310268281222;10241.590336386536;10277.486125693686;1780.9287628494844;1830.2221555838848;1830.533214030427;1882.8211319924585;1939.8351140153104;101017.484737729;118099;118197.22704159358;121767;124880;11174.217804753589;11560.942195289137;11585.362805045635;11749.706257343634;11801.232919860182;14406.135631861913;14429.92785036068;14475.855241447662;14477.638059048397;14509.637259068608;8145.918540814516;8445.324374050062;8472.364572657076;8606.526641034803;8621.956890215633;8281.29608983243;8322.62548185333;8340.999080110425;8389.95805020971;8527.317814574943;4377.6497880170145;4503.437070366204;4521.61566266871;4574.359589657451;4579.977100114478;1467.530390275123;1520.4982355822551;1788.6241949251073;1854.6847036003912;1924.3745782620704;723.5333210079574;839.9748007559783;873.1268731268731;874.1171416868881;910.0671811393374;19539;19963.700544491843;21136.260230891847;21146.942652867296;21185.78814211838;915.6520522201654;933.2953620016843;945.281585994649;947.7962238118838;978.3493976505545;10908.454577271104;11196.720081998015;11199;11292.943535282271;11358.886411135949;552031;558696;568027;574409;575371;74960.25039749642;75779.62110189414;99950;122982;128784;870.4559650218487;975.6194884481841;976.2043934193609;1000.6497725795913;1004.4124187350336;18890.716639250146;19112.044397780333;19141.330053448062;19194.616107677488;19196.904015479835;52809;52933.7353313231;53245.46754532405;54239;54515.45484545104;58616.483011019474;66192.3451913706;73716.52566948738;75810;81648.59175704083;6994.755183568551;7894.605269736376;8020.558869262247;8142.470739402157;8282.254597086292;9579.329446938647;11531.135164862837;12935;13249.867501324863;13277.800832987707;9502.714918552454;11264;11461.942690286829;11510.884891150981;11633.360165190657;9556.61773529051;11203.271787333797;11319.886801131883;11575.363355015219;11633.47649355764;10378.481075946022;10651.520681569193;11515.827262591236;11656.30062196271;11860.644180674593;8103.311218546254;8227.053888802864;8246.299064579578;8274.29668478186;8281.17188281167;9784.168345690696;10115.565059680981;11147.72130696739;11592.47833847462;11634.243774154653 05:57:25 INFO - PROCESS | 1652 | |3;mootools.html;1509.4641402302182;1536.2011753887978;1751.4570483150223;1909.942701718947;1939.922403103875;7854.528728276293;8140.9592952035155;8361;8865.468071915682;8880.023197448283;7641.732539361124;7975.7208497702595;8427.367947403938;8519.787005324855;8661.653533858655;2341.2976107167824;2362.6574146748712;2373.098222675381;2376.298991797415;2376.572217000939;1727.2745446912263;2158.136745301884;2353.682252895854;2370.573296806574;2399.2802159352213;2018.9697154542694;2133.7866213378616;2280.5210905709737;2368.7749663782;2369.2418426103654;2321.048370168223;2334.941626459335;2346.1788374069024;2348.295511346589;2351.8588884666806;2247.752747197807;2265.9773402266096;2285.1316499729996;2288.9885550572308;2292.1289909834154;8646.697365592174;8769.649214031431;8787.340949428755;8802.603882825288;8810.647574097029;5185.144451165553;5339.279197308352;5345.7594408251725;5363.651362661453;5363.812266570651;3552;3565.625609311037;3631.800250986195;3634.200475895299;3637.8181090945345;1047.6961681112468;1057.9788404231872;1060.7136073260176;1074.5594306334365;1082.7726177502734;11374.31754094757;11473.254238474556;11478.253913495679;11500.942495287554;11549.307041577533;4517.435320584861;4641.74470404131;4757.907360215888;4771.141194584939;4791.377120974251;9756.707298781048;9765;9778.31551791382;9789.412635241908;9789.559469823875;5312.574994000471;5312.73436328182;5319.255304257407;5322.707251101227;5324.41431442541;4691.366665500182;4845.1278769821065;4886.21820508724;4886.975565122151;4894.608431325485;3849.4418309344987;3855.691544676419;3934.9016274593364;3937.684985201234;4038.2327357801923;1197.928124312544;1251.8184863194967;1275.273094336238;1299.2659147581776;1317.5256907513287;686.2417029182781;863.6329559937127;900.3382513852313;929.2380248196549;937.376644531379;5842.298924129132;5928.940710592925;5972.522198224132;6028.095785632103;6146.877062458815;849.4945507423073;940.4592359393376;974.1524873360222;994.472929347447;1065.984010239847;3935.153941902505;3953.150072734318;3955.288048151303;4072.8778136655947;4076.898077548085;9040.276777857756;10298.17614590831;10529.526171322308;10704.625338113285;10717.410542420086;10670.30643008202;10673.786524269626;10680.252382333161;10680.839787403194;10685.679429617125;7570.318671319497;7800.297973182441;7996.0804507481225;8051.718189863327;8115.715949941842;5803.680797556175;5902.7343769531335;6003.5797494174985;6074;6137.324894261538;8168.714095006646;10026.348287361297;10041.698749037541;10045.488627843039;10111.674314907465;8442.240198382175;9497.382670126419;9949.154321882721;9981.60073597048;10088.243381746252;7349.4855360124275;7407.407407407502;7510.737124200627;7527.096748390229;7533.54798712079;8050.235227653364;8060.959695201486;8150.98112735908;8154.38842086834;8162.224588664068;6998.475114366341;7134.8929766052515;7177.533460325062;7193.856122877618;7345.0084238627105;6716.496262780213;7095.432365410651;7123.465740069619;7157.928420715934;7193.136823581203;6748.021536877223;6884.345987131214;6892.414144797548;6896.965515172392;6909.343612356818;4898.804162291476;4984.2025275955675;5045.066662667418;5060.063888180698;5134.691918484903;4952.727599982036;4963.131451995959;5105.566026887607;5143.5627971622835;5256.842294731164 05:57:25 INFO - PROCESS | 1652 | |4;prototype.html;303.47195879170243;303.8799674128718;311.88148503568647;316.3293817107732;319.88644031368875;4421.46942366916;5597.664140151589;5611.663300201991;5619.707467282522;5642.661440313574;4188.664906807456;4388.67084968627;5311.176767601019;5329.920051199235;5535.335759708841;3442.8294379910826;3651.561812582494;4152.003519155396;4191.790410479463;4234.957650423487;4013.4548198943326;4181.916361672765;4206;4213.726107802999;4222.261104306734;3408.931821363571;3818.052793075524;4124.8143833527565;4169.499660040784;4175.749455032693;3384.9323013539592;4108.897277568085;4119.526254480728;4154.335306350985;4259.936100958488;3548.3967725486727;3554.182538016271;3567.5362202913716;3570.732195085353;3579.713622910161;4252.9149417011795;4771.594414474739;5133.537981581676;5248.081585722522;5410.648307860016;3975.8012099394914;4134.979325103385;4180.226658068236;4769.737664428455;4800.639952003614;5790.420957904218;5800.303963524362;5820.9708951455395;5845.970770146122;5853.29760428747;3862.3627101528214;3912.471816304791;3912.4913761211146;3917.412388141773;3942.3639189736227;4806.831760888387;5644.927463781909;5768.3366412862215;6075.270967483886;6141.324454310023;3324.7672662913847;3958;3971.245463361952;3971.384435412516;3991.501062367204;4024.215278020816;4040.4949381327333;4053.6959728020515;4065.4511640928686;4068.532118806316;3668.3309094362003;4717.410323709536;4733.692309999839;4750.857474275777;4795.880102997453;5098.796048158107;5332.546733527616;5681.761396004238;5822.5633077518505;5934.460137994438;1144.8797876223043;1281.135233717237;1373.3133433283358;1386.2929905748142;1387.6947071644222;696.8571442854203;776.5904882637994;823.6087858267275;856.8586183279689;861.7590669435992;4112.691548133902;4198.194776246182;4238.279492486285;4573.794179261875;4597.609203217764;3936.3429684962534;4565.748883811422;4728.4562275338085;4829.420469543677;5044.369453818273;4270.7864606769535;4335.352566024891;4484.551544845489;4614.915494858706;4640.978984623377;2261.8529795563563;2496.8501889886666;2527.748764361653;2600.8699565021675;2662.6804783426114;4810.7113573185725;4837.854864354075;5152.613553983466;5190.532852043334;5214;4522.987270664523;4679.6367834244165;5120.974395128075;5147.194778845538;5184.948150518447;4413.514513403587;4450.933236001462;5183.14478111115;5221.321228240305;5344.225087362389;4425.690201685851;4868.320429451834;4985.5791099536455;5037.345145131109;5103.821366252163;4400.801963911567;4635;5360.097165506345;5458.399576046711;5788.160716695971;4947.579455746302;5559.777608895599;5576.33084029919;6356.364363563606;6393.808185754435;3581.6955558777795;3969.142217603321;4036.7577945323374;4041.9393709094975;4053.6757059434585;5455.745178608863;5608.242887210175;5866.442687944638;6074.3318234994995;6115.287719438565;5209.557187639093;5925.496332811759;5926.911096333644;5934.584579079423;5947.762089516371;5073;5743.6840973745175;5850.5027072699295;5923.289205295392;6031.125486804476;4667.649926255613;5812;5865.266841644794;5866.765329386777;5894.970525147347;5151.484851514819;5358.757048038991;5433.320834895638;5810.82567522975;5811.680357580373;4897.8285759998225;5082.720450375117;5788.363280039108;5798.144234084287;5803.910592186894 05:57:25 INFO - PROCESS | 1652 | |5;yui.html;717.2576383443137;729.5914287998718;781.945263831532;823.3084209264224;827.0488937721619;15212.391504339826;15523.611909702264;15529.844701552982;15895.28471218795;16139.950903191298;14230.288485575706;14346.784798228035;14357.353919073641;14358.425662973495;14374.20941848198;716.7097325583147;738.0294912190479;742.3356096293841;875.92992560595;880.2561835249204;903.9276857851389;904.2088172848757;918.4626993208948;920.6547544670749;920.8204400141975;967.7483854197924;970.8009857979098;971.6550624528297;971.68905950096;972.2027937091588;747.484235877243;768.8961990131372;960.1742501448748;965.8406362950104;966.840471322231;583.2359608912338;585.1223165252122;585.2976428286074;687.2097088348389;712.5510928115255;14742.189179595118;14832.777508337384;14839.332230049673;14844.183569903651;14863.256837158098;13207.537736179187;13539.796903046463;13567.321633918364;13581;13954.232517211549;12178;12374.690632734253;12438;12450.439730212162;12949.352532373437;4470.642348612103;4471.105778844244;4557.544245575449;4668.346431499593;4681.297805329228;78798.60600697018;105297;105602.47198764034;108843.45578272059;108963.46920104131;10650;10664.893351066545;10693.518791654395;10822.026017658449;11112.944435277772;21108.261210857545;21132.260370886946;21153.04811283496;21185.258515951868;21217.257395991066;7552.433567482461;7561.962190189014;7567.356774674215;7719.807004824812;7887.369010479262;3978.3435733104293;4105.651019663362;4174.31123864559;4230.344296634026;4551.158035763395;3411.488276758506;3505.509228707983;3519.9824000880344;3523.1016090896655;3532.1699400640828;626.4049153304379;630.5554584018348;633.8193614819754;652.8172111808701;653.4478365780908;497.7809763703959;518.3158231134865;613.6440864298696;639.216959224955;646.4892734739503;8314.916850831536;8421.789455263668;8515.318774498026;8728.78178045554;8849.69026084097;618.1839971238013;642.6979319719725;652.393274254938;656.967151642416;662.2847324889108;3477.704395126392;3670.596234414212;4056.675465962716;4188.727732697365;4393.846215382447;154876.2256188712;155564.2221788884;156253;157909.05091389106;159062;54350.45649543454;105184.63707540552;123361;124585;132689;112845;113637;115212.42393788145;117471;118224.40887795507;8292.792680182802;8356.122607126164;8367.707130250412;8463.365247606578;8705.085965973482;65724.34275657182;66114.33885661082;66726.3318417024;66819.66590167017;67040;70670;99431;100047;100189.49905250427;100827.49586252021;11216.383098929637;11257.043151331902;11418.371989540656;11579.42102894835;11858;11921;12014.219075760047;12087.75824483523;12252.632421027383;12569.994400448126;11458.369789661316;11496.67955198738;11550;11744.00175985051;11859.52561897549;10212.234082443698;12363.134580579273;12401.292442388665;12741.87478956637;12925.289109098714;13225.393866685221;13325.933370333087;13354.799678004643;13440.798388023992;13630.727385452434;10646.946765266124;10862.619808306672;10930.726731831768;11138.33170009802;11273.887261127284;9953.420683988195;11000.22998390105;11260.155488338243;11337.433128343715;11442.141839361915 05:57:25 INFO - PROCESS | 1652 | __end_tp_report 05:57:25 INFO - PROCESS | 1652 | __start_cc_report 05:57:25 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1723 05:57:25 INFO - PROCESS | 1652 | __end_cc_report 05:57:25 INFO - PROCESS | 1652 | __startTimestamp1476795445289__endTimestamp 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:57:25 INFO - PROCESS | 1652 | Number of tests: 6 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5754.50 Median:5979.88 stddev:663.32 (11.1%) stddev-sans-first:637.36 05:57:25 INFO - PROCESS | 1652 | Values: 3324.0 3339.1 3340.0 3372.5 3406.1 5626.4 5718.6 5720.3 5768.3 5812.1 5707.3 5747.1 5754.7 5756.3 5763.0 5176.7 5204.5 5206.0 5243.1 5256.1 5222.5 5267.8 5305.1 5311.5 5321.1 5310.2 5328.1 5330.5 5348.7 5388.3 4549.9 5297.8 5308.5 5344.2 5350.1 4055.6 5044.8 5225.9 5228.0 5291.4 5609.1 5684.6 5708.9 5714.4 5743.3 6276.7 6281.9 6284.2 6291.4 6294.6 6126.6 6190.2 6200.8 6209.4 6287.5 5767.5 5882.4 5884.2 5910.3 5914.5 6292.5 6296.7 6311.3 6316.5 6318.1 6030.5 6089.1 6121.6 6154.2 6228.9 6224.8 6233.9 6257.3 6314.4 6314.8 6200.0 6213.8 6259.4 6267.8 6306.0 6134.6 6303.8 6317.3 6323.2 6328.0 4183.6 4882.6 4935.0 4938.2 4939.3 4481.5 4505.5 4514.0 4537.3 4543.2 5785.5 5786.8 5789.3 5789.9 5796.4 6182.8 6236.6 6266.1 6276.0 6276.2 5777.7 5796.4 5804.6 5804.6 5804.9 5426.1 5434.8 5435.7 5443.3 5445.3 6245.6 6309.6 6322.1 6357.1 6363.6 6293.7 6313.0 6315.9 6322.7 6331.7 6118.5 6194.7 6223.4 6291.1 6340.1 5070.8 6183.5 6194.8 6207.1 6227.3 6098.9 6239.2 6269.6 6290.6 6312.4 5978.1 6022.2 6085.3 6115.0 6117.7 4951.8 5889.0 6124.2 6131.5 6221.4 6105.7 6109.9 6112.1 6115.8 6118.7 6153.4 6157.6 6187.4 6200.2 6209.5 5865.3 5899.9 5908.8 5979.9 5982.1 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:68082.01 Median:41187.29 stddev:148304.75 (360.1%) stddev-sans-first:148634.01 05:57:25 INFO - PROCESS | 1652 | Values: 511.1 519.2 522.5 525.3 551.6 30156.0 38257.4 39713.8 40802.0 42834.1 31927.0 41151.0 42094.0 42498.0 42516.4 9324.5 9505.4 9511.6 9513.4 9523.3 9384.3 9502.5 9502.9 9504.2 9519.6 9434.7 9520.8 9527.9 9534.4 9539.6 9461.4 9511.1 9513.8 9523.1 9536.4 7975.0 9432.0 9451.4 9463.3 9472.1 36924.4 37979.9 37997.2 38229.6 38415.6 3248.9 3347.8 3364.5 3977.6 4035.7 29986.0 30433.8 30942.1 31053.9 31113.5 3138.3 3234.5 3239.8 3300.5 3307.1 464178.0 464921.0 466691.0 468410.0 470182.0 49811.0 56400.7 56666.2 56844.1 56922.9 56792.7 57897.1 58041.4 58079.4 58086.4 15399.0 15614.5 15638.5 15659.1 15677.1 12115.9 12188.8 12192.9 12193.8 12215.8 13093.2 14086.6 14117.8 14120.3 14247.9 5880.3 5945.5 5947.5 6034.6 6037.9 917.7 948.2 948.6 1026.1 1127.5 29620.6 29758.7 30053.2 30251.8 30364.8 779.4 884.7 1033.8 1044.2 1077.7 12739.7 14195.3 14564.9 15408.3 15429.8 610340.0 648490.0 652035.0 652343.0 654862.0 57501.7 57516.0 57546.0 57608.4 57846.4 1220.6 1274.5 1325.6 1373.6 1373.6 28384.7 30000.5 30106.2 30414.7 30774.5 52379.7 53044.0 53201.5 53226.5 53484.7 404924.0 563784.0 569331.0 569418.0 570279.0 20249.9 24824.9 24856.1 24902.6 24914.0 43411.0 44113.8 44135.3 44746.3 45684.8 36694.4 37182.4 37361.3 38172.4 38289.4 34280.8 36588.9 37047.0 38106.6 38293.6 31531.7 36200.0 36737.3 41223.6 42809.8 40013.0 42791.0 42855.8 43170.4 43400.8 27608.0 31785.4 32161.7 32447.2 32715.5 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:32785.80 Median:383.00 stddev:94127.70 (24576.6%) stddev-sans-first:94360.18 05:57:25 INFO - PROCESS | 1652 | Values: 313.2 355.2 363.5 381.4 384.6 13141.5 14075.3 14672.6 15547.8 20946.0 19702.9 19772.5 21181.7 21298.4 21344.9 5499.6 5993.3 6012.1 6044.4 6065.4 4791.5 5864.6 6596.9 6691.6 6749.9 5699.9 6700.2 6705.2 6707.6 6708.8 5971.3 6097.2 6105.3 6105.9 6116.0 5045.1 5306.1 5393.2 5923.5 5937.6 13179.4 13451.9 13463.6 16757.4 25120.9 2156.6 2250.0 2325.7 2361.2 2524.8 9699.2 9817.6 9853.3 10241.6 10277.5 1780.9 1830.2 1830.5 1882.8 1939.8 101017.5 118099.0 118197.2 121767.0 124880.0 11174.2 11560.9 11585.4 11749.7 11801.2 14406.1 14429.9 14475.9 14477.6 14509.6 8145.9 8445.3 8472.4 8606.5 8622.0 8281.3 8322.6 8341.0 8390.0 8527.3 4377.6 4503.4 4521.6 4574.4 4580.0 1467.5 1520.5 1788.6 1854.7 1924.4 723.5 840.0 873.1 874.1 910.1 19539.0 19963.7 21136.3 21146.9 21185.8 915.7 933.3 945.3 947.8 978.3 10908.5 11196.7 11199.0 11292.9 11358.9 552031.0 558696.0 568027.0 574409.0 575371.0 74960.3 75779.6 99950.0 122982.0 128784.0 870.5 975.6 976.2 1000.6 1004.4 18890.7 19112.0 19141.3 19194.6 19196.9 52809.0 52933.7 53245.5 54239.0 54515.5 58616.5 66192.3 73716.5 75810.0 81648.6 6994.8 7894.6 8020.6 8142.5 8282.3 9579.3 11531.1 12935.0 13249.9 13277.8 9502.7 11264.0 11461.9 11510.9 11633.4 9556.6 11203.3 11319.9 11575.4 11633.5 10378.5 10651.5 11515.8 11656.3 11860.6 8103.3 8227.1 8246.3 8274.3 8281.2 9784.2 10115.6 11147.7 11592.5 11634.2 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5626.54 Median:5082.81 stddev:3105.50 (61.1%) stddev-sans-first:3098.80 05:57:25 INFO - PROCESS | 1652 | Values: 1509.5 1536.2 1751.5 1909.9 1939.9 7854.5 8141.0 8361.0 8865.5 8880.0 7641.7 7975.7 8427.4 8519.8 8661.7 2341.3 2362.7 2373.1 2376.3 2376.6 1727.3 2158.1 2353.7 2370.6 2399.3 2019.0 2133.8 2280.5 2368.8 2369.2 2321.0 2334.9 2346.2 2348.3 2351.9 2247.8 2266.0 2285.1 2289.0 2292.1 8646.7 8769.6 8787.3 8802.6 8810.6 5185.1 5339.3 5345.8 5363.7 5363.8 3552.0 3565.6 3631.8 3634.2 3637.8 1047.7 1058.0 1060.7 1074.6 1082.8 11374.3 11473.3 11478.3 11500.9 11549.3 4517.4 4641.7 4757.9 4771.1 4791.4 9756.7 9765.0 9778.3 9789.4 9789.6 5312.6 5312.7 5319.3 5322.7 5324.4 4691.4 4845.1 4886.2 4887.0 4894.6 3849.4 3855.7 3934.9 3937.7 4038.2 1197.9 1251.8 1275.3 1299.3 1317.5 686.2 863.6 900.3 929.2 937.4 5842.3 5928.9 5972.5 6028.1 6146.9 849.5 940.5 974.2 994.5 1066.0 3935.2 3953.2 3955.3 4072.9 4076.9 9040.3 10298.2 10529.5 10704.6 10717.4 10670.3 10673.8 10680.3 10680.8 10685.7 7570.3 7800.3 7996.1 8051.7 8115.7 5803.7 5902.7 6003.6 6074.0 6137.3 8168.7 10026.3 10041.7 10045.5 10111.7 8442.2 9497.4 9949.2 9981.6 10088.2 7349.5 7407.4 7510.7 7527.1 7533.5 8050.2 8061.0 8151.0 8154.4 8162.2 6998.5 7134.9 7177.5 7193.9 7345.0 6716.5 7095.4 7123.5 7157.9 7193.1 6748.0 6884.3 6892.4 6897.0 6909.3 4898.8 4984.2 5045.1 5060.1 5134.7 4952.7 4963.1 5105.6 5143.6 5256.8 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4434.06 Median:4742.27 stddev:1382.53 (29.2%) stddev-sans-first:1351.19 05:57:25 INFO - PROCESS | 1652 | Values: 303.5 303.9 311.9 316.3 319.9 4421.5 5597.7 5611.7 5619.7 5642.7 4188.7 4388.7 5311.2 5329.9 5535.3 3442.8 3651.6 4152.0 4191.8 4235.0 4013.5 4181.9 4206.0 4213.7 4222.3 3408.9 3818.1 4124.8 4169.5 4175.7 3384.9 4108.9 4119.5 4154.3 4259.9 3548.4 3554.2 3567.5 3570.7 3579.7 4252.9 4771.6 5133.5 5248.1 5410.6 3975.8 4135.0 4180.2 4769.7 4800.6 5790.4 5800.3 5821.0 5846.0 5853.3 3862.4 3912.5 3912.5 3917.4 3942.4 4806.8 5644.9 5768.3 6075.3 6141.3 3324.8 3958.0 3971.2 3971.4 3991.5 4024.2 4040.5 4053.7 4065.5 4068.5 3668.3 4717.4 4733.7 4750.9 4795.9 5098.8 5332.5 5681.8 5822.6 5934.5 1144.9 1281.1 1373.3 1386.3 1387.7 696.9 776.6 823.6 856.9 861.8 4112.7 4198.2 4238.3 4573.8 4597.6 3936.3 4565.7 4728.5 4829.4 5044.4 4270.8 4335.4 4484.6 4614.9 4641.0 2261.9 2496.9 2527.7 2600.9 2662.7 4810.7 4837.9 5152.6 5190.5 5214.0 4523.0 4679.6 5121.0 5147.2 5184.9 4413.5 4450.9 5183.1 5221.3 5344.2 4425.7 4868.3 4985.6 5037.3 5103.8 4400.8 4635.0 5360.1 5458.4 5788.2 4947.6 5559.8 5576.3 6356.4 6393.8 3581.7 3969.1 4036.8 4041.9 4053.7 5455.7 5608.2 5866.4 6074.3 6115.3 5209.6 5925.5 5926.9 5934.6 5947.8 5073.0 5743.7 5850.5 5923.3 6031.1 4667.6 5812.0 5865.3 5866.8 5895.0 5151.5 5358.8 5433.3 5810.8 5811.7 4897.8 5082.7 5788.4 5798.1 5803.9 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:24326.53 Median:3444.60 stddev:39473.74 (1146.0%) stddev-sans-first:39544.67 05:57:25 INFO - PROCESS | 1652 | Values: 717.3 729.6 781.9 823.3 827.0 15212.4 15523.6 15529.8 15895.3 16140.0 14230.3 14346.8 14357.4 14358.4 14374.2 716.7 738.0 742.3 875.9 880.3 903.9 904.2 918.5 920.7 920.8 967.7 970.8 971.7 971.7 972.2 747.5 768.9 960.2 965.8 966.8 583.2 585.1 585.3 687.2 712.6 14742.2 14832.8 14839.3 14844.2 14863.3 13207.5 13539.8 13567.3 13581.0 13954.2 12178.0 12374.7 12438.0 12450.4 12949.4 4470.6 4471.1 4557.5 4668.3 4681.3 78798.6 105297.0 105602.5 108843.5 108963.5 10650.0 10664.9 10693.5 10822.0 11112.9 21108.3 21132.3 21153.0 21185.3 21217.3 7552.4 7562.0 7567.4 7719.8 7887.4 3978.3 4105.7 4174.3 4230.3 4551.2 3411.5 3505.5 3520.0 3523.1 3532.2 626.4 630.6 633.8 652.8 653.4 497.8 518.3 613.6 639.2 646.5 8314.9 8421.8 8515.3 8728.8 8849.7 618.2 642.7 652.4 657.0 662.3 3477.7 3670.6 4056.7 4188.7 4393.8 154876.2 155564.2 156253.0 157909.1 159062.0 54350.5 105184.6 123361.0 124585.0 132689.0 112845.0 113637.0 115212.4 117471.0 118224.4 8292.8 8356.1 8367.7 8463.4 8705.1 65724.3 66114.3 66726.3 66819.7 67040.0 70670.0 99431.0 100047.0 100189.5 100827.5 11216.4 11257.0 11418.4 11579.4 11858.0 11921.0 12014.2 12087.8 12252.6 12570.0 11458.4 11496.7 11550.0 11744.0 11859.5 10212.2 12363.1 12401.3 12741.9 12925.3 13225.4 13325.9 13354.8 13440.8 13630.7 10646.9 10862.6 10930.7 11138.3 11273.9 9953.4 11000.2 11260.2 11337.4 11442.1 05:57:25 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:57:25 INFO - PROCESS | 1652 | 05:57:25 INFO - TEST-INFO | 1652: exit 0 05:57:25 INFO - TEST-OK | dromaeo_css | took 1119756ms 05:57:25 INFO - TEST-START | kraken 05:57:25 INFO - operating with platform_type : mac_ 05:57:25 INFO - Initialising browser for kraken test... 05:57:25 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpbmHOaJ/profile http://localhost:49274/getInfo.html) 05:57:28 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 05:57:28 INFO - PROCESS | 1664 | colorDepth:24 05:57:28 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 05:57:28 INFO - PROCESS | 1664 | __metrics 05:57:28 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:57:28 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:29 INFO - TEST-INFO | 1664: exit 0 05:57:29 INFO - Browser initialized. 05:57:29 INFO - Running cycle 1/1 for kraken test... 05:57:29 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpbmHOaJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:41 INFO - PROCESS | 1666 | RSS: Main: 231567360 05:57:41 INFO - PROCESS | 1666 | 05:57:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:44 INFO - PROCESS | 1666 | RSS: Main: 290037760 05:57:44 INFO - PROCESS | 1666 | 05:57:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 05:57:48 INFO - PROCESS | 1666 | RSS: Main: 291340288 05:57:48 INFO - PROCESS | 1666 | 05:57:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 05:57:52 INFO - PROCESS | 1666 | RSS: Main: 383238144 05:57:52 INFO - PROCESS | 1666 | 05:57:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 05:57:54 INFO - PROCESS | 1666 | RSS: Main: 284893184 05:57:54 INFO - PROCESS | 1666 | 05:57:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:57:57 INFO - PROCESS | 1666 | RSS: Main: 289288192 05:57:57 INFO - PROCESS | 1666 | 05:58:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 05:58:00 INFO - PROCESS | 1666 | RSS: Main: 328298496 05:58:00 INFO - PROCESS | 1666 | 05:58:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 05:58:04 INFO - PROCESS | 1666 | RSS: Main: 329105408 05:58:04 INFO - PROCESS | 1666 | 05:58:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 05:58:07 INFO - PROCESS | 1666 | RSS: Main: 325861376 05:58:07 INFO - PROCESS | 1666 | 05:58:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:58:09 INFO - PROCESS | 1666 | RSS: Main: 302575616 05:58:09 INFO - PROCESS | 1666 | 05:58:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:58:12 INFO - PROCESS | 1666 | RSS: Main: 306147328 05:58:12 INFO - PROCESS | 1666 | 05:58:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:58:15 INFO - PROCESS | 1666 | RSS: Main: 308531200 05:58:15 INFO - PROCESS | 1666 | 05:58:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:58:18 INFO - PROCESS | 1666 | RSS: Main: 318234624 05:58:18 INFO - PROCESS | 1666 | 05:58:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:22 INFO - PROCESS | 1666 | RSS: Main: 317841408 05:58:22 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 05:58:24 INFO - PROCESS | 1666 | RSS: Main: 362217472 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | __start_tp_report 05:58:24 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:58:24 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:58:24 INFO - PROCESS | 1666 | |i|pagename|runs| 05:58:24 INFO - PROCESS | 1666 | |0;ai-astar;103;113;113;113;113;114;113;113;113;112 05:58:24 INFO - PROCESS | 1666 | |1;audio-beat-detection;149;113;120;182;143;106;146;142;180;116 05:58:24 INFO - PROCESS | 1666 | |2;audio-dft;182;182;184;183;185;183;184;184;180;181 05:58:24 INFO - PROCESS | 1666 | |3;audio-fft;80;60;58;83;61;58;56;57;83;58 05:58:24 INFO - PROCESS | 1666 | |4;audio-oscillator;89;72;90;69;68;69;93;68;68;88 05:58:24 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;85;88;90;87;84;89;85;88;85 05:58:24 INFO - PROCESS | 1666 | |6;imaging-darkroom;95;95;95;96;96;96;96;95;96;94 05:58:24 INFO - PROCESS | 1666 | |7;imaging-desaturate;83;85;79;81;84;79;86;84;84;83 05:58:24 INFO - PROCESS | 1666 | |8;json-parse-financial;56;52;54;52;53;56;56;53;53;54 05:58:24 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;67;70;73;75;71;69;70;69;70;70 05:58:24 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;87;83;84;89;84;87;88;89;87;88 05:58:24 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;148;146;148;144;144;143;149;145;262;149 05:58:24 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;171;167;168;170;170;172;171;169;170;170 05:58:24 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;68;70;66;67;68;70;68;69;67;68 05:58:24 INFO - PROCESS | 1666 | __end_tp_report 05:58:24 INFO - PROCESS | 1666 | __start_cc_report 05:58:24 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2002 05:58:24 INFO - PROCESS | 1666 | __end_cc_report 05:58:24 INFO - PROCESS | 1666 | __startTimestamp1476795504332__endTimestamp 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:58:24 INFO - PROCESS | 1666 | Number of tests: 14 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:3.20 (2.8%) stddev-sans-first:0.50 05:58:24 INFO - PROCESS | 1666 | Values: 103.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 112.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:144.50 stddev:26.57 (18.4%) stddev-sans-first:27.97 05:58:24 INFO - PROCESS | 1666 | Values: 149.0 113.0 120.0 182.0 143.0 106.0 146.0 142.0 180.0 116.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:1.55 (0.8%) stddev-sans-first:1.62 05:58:24 INFO - PROCESS | 1666 | Values: 182.0 182.0 184.0 183.0 185.0 183.0 184.0 184.0 180.0 181.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.50 stddev:11.57 (19.1%) stddev-sans-first:11.00 05:58:24 INFO - PROCESS | 1666 | Values: 80.0 60.0 58.0 83.0 61.0 58.0 56.0 57.0 83.0 58.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:80.00 stddev:10.98 (13.7%) stddev-sans-first:10.81 05:58:24 INFO - PROCESS | 1666 | Values: 89.0 72.0 90.0 69.0 68.0 69.0 93.0 68.0 68.0 88.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:2.02 (2.3%) stddev-sans-first:2.11 05:58:24 INFO - PROCESS | 1666 | Values: 88.0 85.0 88.0 90.0 87.0 84.0 89.0 85.0 88.0 85.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 05:58:24 INFO - PROCESS | 1666 | Values: 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 96.0 94.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:84.00 stddev:2.39 (2.9%) stddev-sans-first:2.54 05:58:24 INFO - PROCESS | 1666 | Values: 83.0 85.0 79.0 81.0 84.0 79.0 86.0 84.0 84.0 83.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.60 (3.0%) stddev-sans-first:1.50 05:58:24 INFO - PROCESS | 1666 | Values: 56.0 52.0 54.0 52.0 53.0 56.0 56.0 53.0 53.0 54.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.00 stddev:2.22 (3.2%) stddev-sans-first:1.99 05:58:24 INFO - PROCESS | 1666 | Values: 67.0 70.0 73.0 75.0 71.0 69.0 70.0 69.0 70.0 70.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.50 stddev:2.17 (2.5%) stddev-sans-first:2.30 05:58:24 INFO - PROCESS | 1666 | Values: 87.0 83.0 84.0 89.0 84.0 87.0 88.0 89.0 87.0 88.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:157.80 Median:148.00 stddev:36.68 (24.8%) stddev-sans-first:38.73 05:58:24 INFO - PROCESS | 1666 | Values: 148.0 146.0 148.0 144.0 144.0 143.0 149.0 145.0 262.0 149.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.00 stddev:1.48 (0.9%) stddev-sans-first:1.50 05:58:24 INFO - PROCESS | 1666 | Values: 171.0 167.0 168.0 170.0 170.0 172.0 171.0 169.0 170.0 170.0 05:58:24 INFO - PROCESS | 1666 | 05:58:24 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.29 (1.9%) stddev-sans-first:1.36 05:58:24 INFO - PROCESS | 1666 | Values: 68.0 70.0 66.0 67.0 68.0 70.0 68.0 69.0 67.0 68.0 05:58:24 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:58:24 INFO - PROCESS | 1666 | 05:58:25 INFO - TEST-INFO | 1666: exit 0 05:58:25 INFO - TEST-OK | kraken | took 59232ms 05:58:25 INFO - SUITE-END | took 1178s 05:58:25 INFO - Completed test suite (00:19:39) 05:58:25 INFO - javascript benchmark 05:58:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.9501407478883, 3339.148517128132, 3340.0480862954055, 3372.5447064646264, 3406.080358303258, 5626.381098079208, 5718.59969802114, 5720.256366672332, 5768.336641286253, 5812.128180772881, 5707.286589176353, 5747.137929310606, 5754.65472071675, 5756.3092428908485, 5763.049096899017, 5176.715280659553, 5204.479552044784, 5205.973970130144, 5243.10867152585, 5256.132738098209, 5222.5038621331105, 5267.841964741045, 5305.124654432014, 5311.495407936259, 5321.068812957737, 5310.20346947957, 5328.120860058105, 5330.546903513206, 5348.679079255237, 5388.326459192601, 4549.908022074711, 5297.841064768063, 5308.52223299901, 5344.225087362311, 5350.0904846176245, 4055.634992850628, 5044.772985215637, 5225.895482090375, 5228.0, 5291.417944026154, 5609.074502707049, 5684.573656975744, 5708.914366284509, 5714.399988001242, 5743.310802703702, 6276.654783986879, 6281.87436251277, 6284.214473190851, 6291.433770960635, 6294.590851594632, 6126.609281623656, 6190.226221722285, 6200.844978875519, 6209.379062093754, 6287.52843536732, 5767.480926716616, 5882.411758824083, 5884.235049443545, 5910.320313164041, 5914.497267732291, 6292.528060395397, 6296.685165741695, 6311.27420346666, 6316.526260530479, 6318.147050148263, 6030.517558595302, 6089.147519347295, 6121.602095863755, 6154.243201794671, 6228.906566401508, 6224.844378890473, 6233.937660623426, 6257.311695713468, 6314.400131987546, 6314.778982735582, 6200.0079987202735, 6213.751449942042, 6259.405356491126, 6267.843303917439, 6305.96847015762, 6134.620279381683, 6303.842403939938, 6317.336679648571, 6323.177986861679, 6328.0, 4183.560726123774, 4882.584980276645, 4934.9753251233515, 4938.209886418156, 4939.333190019373, 4481.5294394088805, 4505.504394516601, 4513.961788788597, 4537.319402089713, 4543.227651299287, 5785.479306862402, 5786.826395208151, 5789.2763404574425, 5789.913151302734, 5796.391378905154, 6182.814515564541, 6236.563440559208, 6266.060090986297, 6275.96862015687, 6276.152719382916, 5777.711114444345, 5796.362400135981, 5804.564657650609, 5804.564657650693, 5804.912926306109, 5426.131818908955, 5434.83695489136, 5435.673859568438, 5443.292371991695, 5445.346558412936, 6245.5628106032145, 6309.621422714652, 6322.114903913363, 6357.110004599267, 6363.5863668861375, 6293.685315734287, 6312.968435157794, 6315.90526142099, 6322.715477803602, 6331.746730130743, 6118.527810132562, 6194.721237544411, 6223.37766223374, 6291.087792270004, 6340.1123842661145, 5070.797168113234, 6183.505319574513, 6194.783182588588, 6207.131001659862, 6227.346128656426, 6098.908516372347, 6239.188905442264, 6269.5611307208055, 6290.591111577733, 6312.368763123651, 5978.073398623221, 6022.217111775616, 6085.269767627736, 6114.969425152845, 6117.694115294128, 4951.762059485129, 5889.028310328919, 6124.173236613178, 6131.478824299806, 6221.377862213742, 6105.694715264308, 6109.877802444015, 6112.083187521907, 6115.755369785159, 6118.724657390339, 6153.353897840662, 6157.599756015845, 6187.381261873777, 6200.224971878515, 6209.534284928558, 5865.3254875689845, 5899.91150132757, 5908.822735317947, 5979.880402391841, 5982.0727787193055], "value": 5711.90276565394, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.09791218499356, 519.2367220186326, 522.4775224775225, 525.3118414876512, 551.6414330685052, 30156.0, 38257.42613860791, 39713.80143099284, 40802.0, 42834.14331713364, 31927.0, 41151.0, 42094.0, 42498.0, 42516.36225456621, 9324.533773311341, 9505.382150160218, 9511.619535218582, 9513.429194248332, 9523.285753568476, 9384.343095983284, 9502.524873756285, 9502.857457138149, 9504.239660827117, 9519.571619277114, 9434.669786557472, 9520.761980950463, 9527.857082143739, 9534.380265282769, 9539.570719317648, 9461.385009974365, 9511.14399704023, 9513.762155946088, 9523.095305945924, 9536.380135291252, 7974.960125199337, 9432.009638987945, 9451.385659932152, 9463.337566370357, 9472.14750672436, 36924.446133308025, 37979.86060418192, 37997.240055198745, 38229.61770382316, 38415.615843841486, 3248.8538015789345, 3347.815870127142, 3364.5364266544134, 3977.5624681285035, 4035.6771458283265, 29985.950491732685, 30433.847830760704, 30942.071737847902, 31053.913113040937, 31113.533297000562, 3138.3252600690735, 3234.544454995238, 3239.82180980046, 3300.455424854896, 3307.074019274583, 464178.0, 464921.0, 466691.0, 468409.99999999657, 470182.0, 49811.0037799242, 56400.71799640975, 56666.150007749915, 56844.14733778997, 56922.861542768915, 56792.71603642038, 57897.13154302689, 58041.41958580445, 58079.41920580825, 58086.4191358081, 15399.0, 15614.531564053097, 15638.530844074696, 15659.138747369005, 15677.05937643745, 12115.939420303019, 12188.756224875453, 12192.878071219175, 12193.756124877631, 12215.816762748565, 13093.214407135602, 14086.647833804032, 14117.78823317651, 14120.293985300694, 14247.928760356132, 5880.323762767251, 5945.524358051345, 5947.524198064231, 6034.607750496204, 6037.9433599119975, 917.7292698653913, 948.2319321349729, 948.6442584030989, 1026.1175389165312, 1127.520803658442, 29620.555691664642, 29758.702412975592, 30053.248668783457, 30251.848740756155, 30364.84817575898, 779.3531368963746, 884.6947803007952, 1033.8087453820917, 1044.1803184500284, 1077.7305673581604, 12739.745205095845, 14195.290235488184, 14564.927175364268, 15408.265122779178, 15429.845701543065, 610340.0, 648490.0, 652035.0, 652343.0, 654862.0, 57501.712491437276, 57516.0, 57546.0, 57608.423915761145, 57846.421535784946, 1220.5789002794022, 1274.5347947998914, 1325.6155714842587, 1373.6085215713274, 1373.6359864635679, 28384.71615283903, 30000.549991749704, 30106.24734381658, 30414.695853041187, 30774.53838192384, 52379.73810130925, 53044.0, 53201.46798531965, 53226.4677353237, 53484.73257633687, 404924.0, 563784.0, 569331.0, 569418.0, 570279.0, 20249.898750506152, 24824.875875620506, 24856.130035448674, 24902.626460602743, 24914.0, 43411.0, 44113.77943110264, 44135.33796992983, 44746.3288050673, 45684.77157614191, 36694.44958325574, 37182.44226336553, 37361.25277494381, 38172.427413588266, 38289.425658614586, 34280.82859585686, 36588.902332930054, 37047.0, 38106.61893381031, 38293.61706383012, 31531.684683152875, 36200.0, 36737.26525469529, 41223.58776412198, 42809.785951070044, 40013.0, 42791.0, 42855.785721071195, 43170.352444713986, 43400.78299608482, 27608.0, 31785.364292713555, 32161.678383216804, 32447.188820279684, 32715.509267361485], "value": 19437.519985014977, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.2247686974738, 355.22206368053975, 363.54919899324847, 381.37264200390354, 384.62113323734417, 13141.540046098376, 14075.255325211576, 14672.633184170401, 15547.844521554809, 20946.0, 19702.90148549255, 19772.517061220395, 21181.68227476585, 21298.36104916851, 21344.8932755336, 5499.587530935176, 5993.310769261525, 6012.068129439944, 6044.365341639131, 6065.423784740443, 4791.472937976837, 5864.5894787364905, 6596.901046484307, 6691.5650482718465, 6749.898751518731, 5699.943000569982, 6700.229473610549, 6705.228898676666, 6707.5975441473665, 6708.792417364873, 5971.313298970631, 6097.207363042776, 6105.297890742532, 6105.908411373833, 6115.9694201529155, 5045.091883460976, 5306.09796334624, 5393.2179833923865, 5923.466887980059, 5937.554683398719, 13179.406926688227, 13451.86548134516, 13463.596092117252, 16757.41349052777, 25120.864726498086, 2156.644153714635, 2250.0, 2325.68603238564, 2361.220797136958, 2524.798016158703, 9699.175570076552, 9817.558209880572, 9853.310268281222, 10241.590336386536, 10277.486125693686, 1780.9287628494844, 1830.2221555838848, 1830.533214030427, 1882.8211319924585, 1939.8351140153104, 101017.484737729, 118099.0, 118197.22704159358, 121767.0, 124880.0, 11174.217804753589, 11560.942195289137, 11585.362805045635, 11749.706257343634, 11801.232919860182, 14406.135631861913, 14429.92785036068, 14475.855241447662, 14477.638059048397, 14509.637259068608, 8145.918540814516, 8445.324374050062, 8472.364572657076, 8606.526641034803, 8621.956890215633, 8281.29608983243, 8322.62548185333, 8340.999080110425, 8389.95805020971, 8527.317814574943, 4377.6497880170145, 4503.437070366204, 4521.61566266871, 4574.359589657451, 4579.977100114478, 1467.530390275123, 1520.4982355822551, 1788.6241949251073, 1854.6847036003912, 1924.3745782620704, 723.5333210079574, 839.9748007559783, 873.1268731268731, 874.1171416868881, 910.0671811393374, 19539.0, 19963.700544491843, 21136.260230891847, 21146.942652867296, 21185.78814211838, 915.6520522201654, 933.2953620016843, 945.281585994649, 947.7962238118838, 978.3493976505545, 10908.454577271104, 11196.720081998015, 11199.0, 11292.943535282271, 11358.886411135949, 552031.0, 558696.0, 568027.0, 574409.0, 575371.0, 74960.25039749642, 75779.62110189414, 99950.0, 122982.0, 128784.0, 870.4559650218487, 975.6194884481841, 976.2043934193609, 1000.6497725795913, 1004.4124187350336, 18890.716639250146, 19112.044397780333, 19141.330053448062, 19194.616107677488, 19196.904015479835, 52809.0, 52933.7353313231, 53245.46754532405, 54239.0, 54515.45484545104, 58616.483011019474, 66192.3451913706, 73716.52566948738, 75810.0, 81648.59175704083, 6994.755183568551, 7894.605269736376, 8020.558869262247, 8142.470739402157, 8282.254597086292, 9579.329446938647, 11531.135164862837, 12935.0, 13249.867501324863, 13277.800832987707, 9502.714918552454, 11264.0, 11461.942690286829, 11510.884891150981, 11633.360165190657, 9556.61773529051, 11203.271787333797, 11319.886801131883, 11575.363355015219, 11633.47649355764, 10378.481075946022, 10651.520681569193, 11515.827262591236, 11656.30062196271, 11860.644180674593, 8103.311218546254, 8227.053888802864, 8246.299064579578, 8274.29668478186, 8281.17188281167, 9784.168345690696, 10115.565059680981, 11147.72130696739, 11592.47833847462, 11634.243774154653], "value": 9248.638892486139, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1509.4641402302182, 1536.2011753887978, 1751.4570483150223, 1909.942701718947, 1939.922403103875, 7854.528728276293, 8140.9592952035155, 8361.0, 8865.468071915682, 8880.023197448283, 7641.732539361124, 7975.7208497702595, 8427.367947403938, 8519.787005324855, 8661.653533858655, 2341.2976107167824, 2362.6574146748712, 2373.098222675381, 2376.298991797415, 2376.572217000939, 1727.2745446912263, 2158.136745301884, 2353.682252895854, 2370.573296806574, 2399.2802159352213, 2018.9697154542694, 2133.7866213378616, 2280.5210905709737, 2368.7749663782, 2369.2418426103654, 2321.048370168223, 2334.941626459335, 2346.1788374069024, 2348.295511346589, 2351.8588884666806, 2247.752747197807, 2265.9773402266096, 2285.1316499729996, 2288.9885550572308, 2292.1289909834154, 8646.697365592174, 8769.649214031431, 8787.340949428755, 8802.603882825288, 8810.647574097029, 5185.144451165553, 5339.279197308352, 5345.7594408251725, 5363.651362661453, 5363.812266570651, 3552.0, 3565.625609311037, 3631.800250986195, 3634.200475895299, 3637.8181090945345, 1047.6961681112468, 1057.9788404231872, 1060.7136073260176, 1074.5594306334365, 1082.7726177502734, 11374.31754094757, 11473.254238474556, 11478.253913495679, 11500.942495287554, 11549.307041577533, 4517.435320584861, 4641.74470404131, 4757.907360215888, 4771.141194584939, 4791.377120974251, 9756.707298781048, 9765.0, 9778.31551791382, 9789.412635241908, 9789.559469823875, 5312.574994000471, 5312.73436328182, 5319.255304257407, 5322.707251101227, 5324.41431442541, 4691.366665500182, 4845.1278769821065, 4886.21820508724, 4886.975565122151, 4894.608431325485, 3849.4418309344987, 3855.691544676419, 3934.9016274593364, 3937.684985201234, 4038.2327357801923, 1197.928124312544, 1251.8184863194967, 1275.273094336238, 1299.2659147581776, 1317.5256907513287, 686.2417029182781, 863.6329559937127, 900.3382513852313, 929.2380248196549, 937.376644531379, 5842.298924129132, 5928.940710592925, 5972.522198224132, 6028.095785632103, 6146.877062458815, 849.4945507423073, 940.4592359393376, 974.1524873360222, 994.472929347447, 1065.984010239847, 3935.153941902505, 3953.150072734318, 3955.288048151303, 4072.8778136655947, 4076.898077548085, 9040.276777857756, 10298.17614590831, 10529.526171322308, 10704.625338113285, 10717.410542420086, 10670.30643008202, 10673.786524269626, 10680.252382333161, 10680.839787403194, 10685.679429617125, 7570.318671319497, 7800.297973182441, 7996.0804507481225, 8051.718189863327, 8115.715949941842, 5803.680797556175, 5902.7343769531335, 6003.5797494174985, 6074.0, 6137.324894261538, 8168.714095006646, 10026.348287361297, 10041.698749037541, 10045.488627843039, 10111.674314907465, 8442.240198382175, 9497.382670126419, 9949.154321882721, 9981.60073597048, 10088.243381746252, 7349.4855360124275, 7407.407407407502, 7510.737124200627, 7527.096748390229, 7533.54798712079, 8050.235227653364, 8060.959695201486, 8150.98112735908, 8154.38842086834, 8162.224588664068, 6998.475114366341, 7134.8929766052515, 7177.533460325062, 7193.856122877618, 7345.0084238627105, 6716.496262780213, 7095.432365410651, 7123.465740069619, 7157.928420715934, 7193.136823581203, 6748.021536877223, 6884.345987131214, 6892.414144797548, 6896.965515172392, 6909.343612356818, 4898.804162291476, 4984.2025275955675, 5045.066662667418, 5060.063888180698, 5134.691918484903, 4952.727599982036, 4963.131451995959, 5105.566026887607, 5143.5627971622835, 5256.842294731164], "value": 4543.873809483051, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.47195879170243, 303.8799674128718, 311.88148503568647, 316.3293817107732, 319.88644031368875, 4421.46942366916, 5597.664140151589, 5611.663300201991, 5619.707467282522, 5642.661440313574, 4188.664906807456, 4388.67084968627, 5311.176767601019, 5329.920051199235, 5535.335759708841, 3442.8294379910826, 3651.561812582494, 4152.003519155396, 4191.790410479463, 4234.957650423487, 4013.4548198943326, 4181.916361672765, 4206.0, 4213.726107802999, 4222.261104306734, 3408.931821363571, 3818.052793075524, 4124.8143833527565, 4169.499660040784, 4175.749455032693, 3384.9323013539592, 4108.897277568085, 4119.526254480728, 4154.335306350985, 4259.936100958488, 3548.3967725486727, 3554.182538016271, 3567.5362202913716, 3570.732195085353, 3579.713622910161, 4252.9149417011795, 4771.594414474739, 5133.537981581676, 5248.081585722522, 5410.648307860016, 3975.8012099394914, 4134.979325103385, 4180.226658068236, 4769.737664428455, 4800.639952003614, 5790.420957904218, 5800.303963524362, 5820.9708951455395, 5845.970770146122, 5853.29760428747, 3862.3627101528214, 3912.471816304791, 3912.4913761211146, 3917.412388141773, 3942.3639189736227, 4806.831760888387, 5644.927463781909, 5768.3366412862215, 6075.270967483886, 6141.324454310023, 3324.7672662913847, 3958.0, 3971.245463361952, 3971.384435412516, 3991.501062367204, 4024.215278020816, 4040.4949381327333, 4053.6959728020515, 4065.4511640928686, 4068.532118806316, 3668.3309094362003, 4717.410323709536, 4733.692309999839, 4750.857474275777, 4795.880102997453, 5098.796048158107, 5332.546733527616, 5681.761396004238, 5822.5633077518505, 5934.460137994438, 1144.8797876223043, 1281.135233717237, 1373.3133433283358, 1386.2929905748142, 1387.6947071644222, 696.8571442854203, 776.5904882637994, 823.6087858267275, 856.8586183279689, 861.7590669435992, 4112.691548133902, 4198.194776246182, 4238.279492486285, 4573.794179261875, 4597.609203217764, 3936.3429684962534, 4565.748883811422, 4728.4562275338085, 4829.420469543677, 5044.369453818273, 4270.7864606769535, 4335.352566024891, 4484.551544845489, 4614.915494858706, 4640.978984623377, 2261.8529795563563, 2496.8501889886666, 2527.748764361653, 2600.8699565021675, 2662.6804783426114, 4810.7113573185725, 4837.854864354075, 5152.613553983466, 5190.532852043334, 5214.0, 4522.987270664523, 4679.6367834244165, 5120.974395128075, 5147.194778845538, 5184.948150518447, 4413.514513403587, 4450.933236001462, 5183.14478111115, 5221.321228240305, 5344.225087362389, 4425.690201685851, 4868.320429451834, 4985.5791099536455, 5037.345145131109, 5103.821366252163, 4400.801963911567, 4635.0, 5360.097165506345, 5458.399576046711, 5788.160716695971, 4947.579455746302, 5559.777608895599, 5576.33084029919, 6356.364363563606, 6393.808185754435, 3581.6955558777795, 3969.142217603321, 4036.7577945323374, 4041.9393709094975, 4053.6757059434585, 5455.745178608863, 5608.242887210175, 5866.442687944638, 6074.3318234994995, 6115.287719438565, 5209.557187639093, 5925.496332811759, 5926.911096333644, 5934.584579079423, 5947.762089516371, 5073.0, 5743.6840973745175, 5850.5027072699295, 5923.289205295392, 6031.125486804476, 4667.649926255613, 5812.0, 5865.266841644794, 5866.765329386777, 5894.970525147347, 5151.484851514819, 5358.757048038991, 5433.320834895638, 5810.82567522975, 5811.680357580373, 4897.8285759998225, 5082.720450375117, 5788.363280039108, 5798.144234084287, 5803.910592186894], "value": 3996.802965343758, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.2576383443137, 729.5914287998718, 781.945263831532, 823.3084209264224, 827.0488937721619, 15212.391504339826, 15523.611909702264, 15529.844701552982, 15895.28471218795, 16139.950903191298, 14230.288485575706, 14346.784798228035, 14357.353919073641, 14358.425662973495, 14374.20941848198, 716.7097325583147, 738.0294912190479, 742.3356096293841, 875.92992560595, 880.2561835249204, 903.9276857851389, 904.2088172848757, 918.4626993208948, 920.6547544670749, 920.8204400141975, 967.7483854197924, 970.8009857979098, 971.6550624528297, 971.68905950096, 972.2027937091588, 747.484235877243, 768.8961990131372, 960.1742501448748, 965.8406362950104, 966.840471322231, 583.2359608912338, 585.1223165252122, 585.2976428286074, 687.2097088348389, 712.5510928115255, 14742.189179595118, 14832.777508337384, 14839.332230049673, 14844.183569903651, 14863.256837158098, 13207.537736179187, 13539.796903046463, 13567.321633918364, 13581.0, 13954.232517211549, 12178.0, 12374.690632734253, 12438.0, 12450.439730212162, 12949.352532373437, 4470.642348612103, 4471.105778844244, 4557.544245575449, 4668.346431499593, 4681.297805329228, 78798.60600697018, 105297.0, 105602.47198764034, 108843.45578272059, 108963.46920104131, 10650.0, 10664.893351066545, 10693.518791654395, 10822.026017658449, 11112.944435277772, 21108.261210857545, 21132.260370886946, 21153.04811283496, 21185.258515951868, 21217.257395991066, 7552.433567482461, 7561.962190189014, 7567.356774674215, 7719.807004824812, 7887.369010479262, 3978.3435733104293, 4105.651019663362, 4174.31123864559, 4230.344296634026, 4551.158035763395, 3411.488276758506, 3505.509228707983, 3519.9824000880344, 3523.1016090896655, 3532.1699400640828, 626.4049153304379, 630.5554584018348, 633.8193614819754, 652.8172111808701, 653.4478365780908, 497.7809763703959, 518.3158231134865, 613.6440864298696, 639.216959224955, 646.4892734739503, 8314.916850831536, 8421.789455263668, 8515.318774498026, 8728.78178045554, 8849.69026084097, 618.1839971238013, 642.6979319719725, 652.393274254938, 656.967151642416, 662.2847324889108, 3477.704395126392, 3670.596234414212, 4056.675465962716, 4188.727732697365, 4393.846215382447, 154876.2256188712, 155564.2221788884, 156253.0, 157909.05091389106, 159062.0, 54350.45649543454, 105184.63707540552, 123361.0, 124585.0, 132689.0, 112845.0, 113637.0, 115212.42393788145, 117471.0, 118224.40887795507, 8292.792680182802, 8356.122607126164, 8367.707130250412, 8463.365247606578, 8705.085965973482, 65724.34275657182, 66114.33885661082, 66726.3318417024, 66819.66590167017, 67040.0, 70670.0, 99431.0, 100047.0, 100189.49905250427, 100827.49586252021, 11216.383098929637, 11257.043151331902, 11418.371989540656, 11579.42102894835, 11858.0, 11921.0, 12014.219075760047, 12087.75824483523, 12252.632421027383, 12569.994400448126, 11458.369789661316, 11496.67955198738, 11550.0, 11744.00175985051, 11859.52561897549, 10212.234082443698, 12363.134580579273, 12401.292442388665, 12741.87478956637, 12925.289109098714, 13225.393866685221, 13325.933370333087, 13354.799678004643, 13440.798388023992, 13630.727385452434, 10646.946765266124, 10862.619808306672, 10930.726731831768, 11138.33170009802, 11273.887261127284, 9953.420683988195, 11000.22998390105, 11260.155488338243, 11337.433128343715, 11442.141839361915], "value": 7615.83983279593, "unit": "score"}], "name": "dromaeo_css", "value": 7223.267312862438, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 120.0, 182.0, 143.0, 106.0, 146.0, 142.0, 180.0, 116.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 184.0, 183.0, 185.0, 183.0, 184.0, 184.0, 180.0, 181.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 58.0, 83.0, 61.0, 58.0, 56.0, 57.0, 83.0, 58.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 90.0, 69.0, 68.0, 69.0, 93.0, 68.0, 68.0, 88.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 90.0, 87.0, 84.0, 89.0, 85.0, 88.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 79.0, 81.0, 84.0, 79.0, 86.0, 84.0, 84.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 54.0, 52.0, 53.0, 56.0, 56.0, 53.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 73.0, 75.0, 71.0, 69.0, 70.0, 69.0, 70.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 84.0, 89.0, 84.0, 87.0, 88.0, 89.0, 87.0, 88.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 148.0, 144.0, 144.0, 143.0, 149.0, 145.0, 262.0, 149.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 168.0, 170.0, 170.0, 172.0, 171.0, 169.0, 170.0, 170.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 66.0, 67.0, 68.0, 70.0, 68.0, 69.0, 67.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1448.9999999999995, "alertThreshold": 2.0}]} 05:58:25 INFO - Return code: 0 05:58:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:25 INFO - # TBPL SUCCESS # 05:58:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:25 INFO - 2016-10-18 05:58:25.636 screenresolution[1670:9837] starting screenresolution argv=screenresolution get 05:58:25 INFO - 2016-10-18 05:58:25.638 screenresolution[1670:9837] Display 0: 1600x1200x32@60 05:58:25 INFO - 2016-10-18 05:58:25.645 screenresolution[1671:9840] starting screenresolution argv=screenresolution list 05:58:25 INFO - Available Modes on Display 0 05:58:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:25 INFO - Intel Iris: 05:58:25 INFO - Chipset Model: Intel Iris 05:58:25 INFO - Type: GPU 05:58:25 INFO - Bus: Built-In 05:58:25 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:25 INFO - Vendor: Intel (0x8086) 05:58:25 INFO - Device ID: 0x0a2e 05:58:25 INFO - Revision ID: 0x0009 05:58:25 INFO - Displays: 05:58:25 INFO - Display: 05:58:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:25 INFO - Display Serial Number: 1600x1200 60 05:58:25 INFO - Main Display: Yes 05:58:25 INFO - Mirror: Off 05:58:25 INFO - Online: Yes 05:58:25 INFO - Rotation: Supported 05:58:25 INFO - Return code: 0 05:58:25 INFO - Running post-action listener: _resource_record_post_action 05:58:25 INFO - [mozharness: 2016-10-18 12:58:25.880141Z] Finished run-tests step (success) 05:58:25 INFO - Running post-run listener: _resource_record_post_run 05:58:25 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 36832256; Write bytes: 706616832; Read time: 240; Write time: 1998 05:58:25 INFO - TinderboxPrint: CPU usage
53.8% 05:58:25 INFO - TinderboxPrint: I/O read bytes / time
36,832,256 / 240 05:58:25 INFO - TinderboxPrint: I/O write bytes / time
706,616,832 / 1,998 05:58:25 INFO - TinderboxPrint: CPU idle
2,244.2 (46.2%) 05:58:25 INFO - TinderboxPrint: CPU system
977.5 (20.1%) 05:58:25 INFO - TinderboxPrint: CPU user
1,636.1 (33.7%) 05:58:25 INFO - TinderboxPrint: Swap in / out
443,109,376 / 0 05:58:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403833344; Write bytes: 397658112; Read time: 29706; Write time: 825 05:58:25 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34702336; Write bytes: 296818176; Read time: 220; Write time: 1134 05:58:26 INFO - Running post-run listener: _upload_blobber_files 05:58:26 INFO - Blob upload gear active. 05:58:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:58:26 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:58:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:58:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:28 INFO - (blobuploader) - INFO - Done attempting. 05:58:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:58:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:58:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:29 INFO - (blobuploader) - INFO - Done attempting. 05:58:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:31 INFO - (blobuploader) - INFO - Done attempting. 05:58:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:31 INFO - Return code: 0 05:58:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a374a36dd93b6130ebc9ccc5eb9c8a1d4430e4eaa2eb9a5366cf5b596c9863869dfb7d9f6ee8235b0dac1376722afba094088cef8f7d9f5f49aaea10f58af40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4faec7fc57edd351ffc55eab8e97b5b5d4a3ef5fff5784a502cafa4baacc3be3192510f41db128bdea0b2f6d7f0921e4e9c8fd3502f001c90c578db612fabcbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b388c6c23bd515de0a54d726c8d594b66552d7910472033e39d3e9d70d200cc312ed3ca6887db145a5109e6c10de88e0a5a218499a85ce924799ed3f4c93562a"} 05:58:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:31 INFO - Contents: 05:58:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a374a36dd93b6130ebc9ccc5eb9c8a1d4430e4eaa2eb9a5366cf5b596c9863869dfb7d9f6ee8235b0dac1376722afba094088cef8f7d9f5f49aaea10f58af40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4faec7fc57edd351ffc55eab8e97b5b5d4a3ef5fff5784a502cafa4baacc3be3192510f41db128bdea0b2f6d7f0921e4e9c8fd3502f001c90c578db612fabcbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b388c6c23bd515de0a54d726c8d594b66552d7910472033e39d3e9d70d200cc312ed3ca6887db145a5109e6c10de88e0a5a218499a85ce924799ed3f4c93562a"} 05:58:31 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:31 INFO - Copying logs to upload dir... 05:58:31 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.402321 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-18 05:58:32.008473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 05:58:32.012293) ========= 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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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/9a374a36dd93b6130ebc9ccc5eb9c8a1d4430e4eaa2eb9a5366cf5b596c9863869dfb7d9f6ee8235b0dac1376722afba094088cef8f7d9f5f49aaea10f58af40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4faec7fc57edd351ffc55eab8e97b5b5d4a3ef5fff5784a502cafa4baacc3be3192510f41db128bdea0b2f6d7f0921e4e9c8fd3502f001c90c578db612fabcbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b388c6c23bd515de0a54d726c8d594b66552d7910472033e39d3e9d70d200cc312ed3ca6887db145a5109e6c10de88e0a5a218499a85ce924799ed3f4c93562a"} build_url:https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/d8ptItqhQ5Wg1filSjhFOw/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/9a374a36dd93b6130ebc9ccc5eb9c8a1d4430e4eaa2eb9a5366cf5b596c9863869dfb7d9f6ee8235b0dac1376722afba094088cef8f7d9f5f49aaea10f58af40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4faec7fc57edd351ffc55eab8e97b5b5d4a3ef5fff5784a502cafa4baacc3be3192510f41db128bdea0b2f6d7f0921e4e9c8fd3502f001c90c578db612fabcbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b388c6c23bd515de0a54d726c8d594b66552d7910472033e39d3e9d70d200cc312ed3ca6887db145a5109e6c10de88e0a5a218499a85ce924799ed3f4c93562a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 05:58:32.045123) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:58:32.045446) ========= 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.JmoSe0GZDS/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.S5KAJD6pXC/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.005073 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:58:32.093784) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 05:58:32.094107) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 05:58:32.094416) ========= ========= Total master_lag: 0.17 =========