builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0151 starttime: 1475313036.37 results: success (0) buildid: 20160930232556 builduid: 06250708edb248db926db7f4505e316d revision: fcc62bbf09eecabf27c8d198d5d3719e16b296ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:36.366103) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:36.366457) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:36.366722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:36.469357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:36.469603) ========= 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.C2kYhSdIf5/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.ERET0j5yjb/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-01 02:10:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 35.3M=0s 2016-10-01 02:10:37 (35.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.510814 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.032560) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.032846) ========= 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.C2kYhSdIf5/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.ERET0j5yjb/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.028882 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.081534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.081830) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fcc62bbf09eecabf27c8d198d5d3719e16b296ea --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 fcc62bbf09eecabf27c8d198d5d3719e16b296ea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.C2kYhSdIf5/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.ERET0j5yjb/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-01 02:10:37,154 truncating revision to first 12 chars 2016-10-01 02:10:37,155 Setting DEBUG logging. 2016-10-01 02:10:37,155 attempt 1/10 2016-10-01 02:10:37,155 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fcc62bbf09ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-01 02:10:37,484 unpacking tar archive at: mozilla-central-fcc62bbf09ee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.612432 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.715602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.715921) ========= script_repo_revision: fcc62bbf09eecabf27c8d198d5d3719e16b296ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.716327) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.716597) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 02:10:37.734426) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-01 02:10:37.735075) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.C2kYhSdIf5/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.ERET0j5yjb/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:10:37 INFO - MultiFileLogger online at 20161001 02:10:37 in /builds/slave/test 02:10:37 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 02:10:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:10:37 INFO - {'append_to_log': False, 02:10:37 INFO - 'base_work_dir': '/builds/slave/test', 02:10:37 INFO - 'blob_upload_branch': 'Firefox', 02:10:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:10:37 INFO - 'branch': 'Firefox', 02:10:37 INFO - 'buildbot_json_path': 'buildprops.json', 02:10:37 INFO - 'config_files': ('talos/mac_config.py',), 02:10:37 INFO - 'default_actions': ('clobber', 02:10:37 INFO - 'read-buildbot-config', 02:10:37 INFO - 'download-and-extract', 02:10:37 INFO - 'populate-webroot', 02:10:37 INFO - 'create-virtualenv', 02:10:37 INFO - 'install', 02:10:37 INFO - 'run-tests'), 02:10:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:10:37 INFO - 'download_minidump_stackwalk': True, 02:10:37 INFO - 'download_symbols': 'ondemand', 02:10:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:10:37 INFO - 'tooltool.py': '/tools/tooltool.py', 02:10:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:10:37 INFO - '/tools/misc-python/virtualenv.py')}, 02:10:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:10:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:10:37 INFO - 'installer_path': 'installer.exe', 02:10:37 INFO - 'log_level': 'info', 02:10:37 INFO - 'log_name': 'talos', 02:10:37 INFO - 'log_to_console': True, 02:10:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:10:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:10:37 INFO - 'opt_config_files': (), 02:10:37 INFO - 'pip_index': False, 02:10:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:10:37 INFO - 'cmd': ('bash', 02:10:37 INFO - '-c', 02:10:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:10:37 INFO - 'enabled': True, 02:10:37 INFO - 'halt_on_failure': False, 02:10:37 INFO - 'name': 'check_screen_resolution'},), 02:10:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:10:37 INFO - 'cmd': ('bash', 02:10:37 INFO - '-c', 02:10:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:10:37 INFO - 'enabled': True, 02:10:37 INFO - 'halt_on_failure': False, 02:10:37 INFO - 'name': 'check_screen_resolution'},), 02:10:37 INFO - 'run_cmd_checks_enabled': True, 02:10:37 INFO - 'sps_profile': False, 02:10:37 INFO - 'sps_profile_interval': 0, 02:10:37 INFO - 'suite': 'dromaeojs', 02:10:37 INFO - 'system_bits': '32', 02:10:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:10:37 INFO - 'title': 't-yosemite-r7-0151', 02:10:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:10:37 INFO - 'use_talos_json': True, 02:10:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:10:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:10:37 INFO - 'work_dir': 'build'} 02:10:37 INFO - [mozharness: 2016-10-01 09:10:37.942272Z] Running clobber step. 02:10:37 INFO - Running pre-action listener: _resource_record_pre_action 02:10:37 INFO - Running main action method: clobber 02:10:37 INFO - rmtree: /builds/slave/test/build 02:10:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:10:40 INFO - Running post-action listener: _resource_record_post_action 02:10:40 INFO - [mozharness: 2016-10-01 09:10:40.141144Z] Finished clobber step (success) 02:10:40 INFO - [mozharness: 2016-10-01 09:10:40.141454Z] Running read-buildbot-config step. 02:10:40 INFO - Running pre-action listener: _resource_record_pre_action 02:10:40 INFO - Running main action method: read_buildbot_config 02:10:40 INFO - Using buildbot properties: 02:10:40 INFO - { 02:10:40 INFO - "project": "", 02:10:40 INFO - "product": "firefox", 02:10:40 INFO - "script_repo_revision": "production", 02:10:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:10:40 INFO - "repository": "", 02:10:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:10:40 INFO - "buildid": "20160930232556", 02:10:40 INFO - "pgo_build": "False", 02:10:40 INFO - "basedir": "/builds/slave/test", 02:10:40 INFO - "buildnumber": 26, 02:10:40 INFO - "slavename": "t-yosemite-r7-0151", 02:10:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:10:40 INFO - "platform": "yosemite_r7", 02:10:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:10:40 INFO - "branch": "mozilla-central", 02:10:40 INFO - "repo_path": "mozilla-central", 02:10:40 INFO - "revision": "fcc62bbf09eecabf27c8d198d5d3719e16b296ea", 02:10:40 INFO - "stage_platform": "macosx64", 02:10:40 INFO - "builduid": "06250708edb248db926db7f4505e316d", 02:10:40 INFO - "slavebuilddir": "test" 02:10:40 INFO - } 02:10:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 02:10:40 INFO - Running post-action listener: _resource_record_post_action 02:10:40 INFO - [mozharness: 2016-10-01 09:10:40.147332Z] Finished read-buildbot-config step (success) 02:10:40 INFO - [mozharness: 2016-10-01 09:10:40.147555Z] Running download-and-extract step. 02:10:40 INFO - Running pre-action listener: _resource_record_pre_action 02:10:40 INFO - Running main action method: download_and_extract 02:10:40 INFO - mkdir: /builds/slave/test/build/tests 02:10:40 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')]} 02:10:40 INFO - trying https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 02:10:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/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 02:10:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/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 02:10:41 INFO - Downloaded 1199 bytes. 02:10:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 02:10:41 INFO - Using the following test package requirements: 02:10:41 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:10:41 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 02:10:41 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 02:10:41 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'jsshell-mac.zip'], 02:10:41 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 02:10:41 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:10:41 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 02:10:41 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 02:10:41 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 02:10:41 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:10:41 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 02:10:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:10:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:10:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:10:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:10:42 INFO - Http code: 200 02:10:42 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 02:10:42 INFO - x-amz-cf-id: pC3CEt3T3gI865yPi7z60EJmK2MO9b4FxPU-vcgJZ-08YmLggG-YDQ== 02:10:42 INFO - x-amz-version-id: C6L0_yAISQyr3Vcpu9GUtsBn5TPuRGl1 02:10:42 INFO - Expected file size: 16475339 02:10:42 INFO - Obtained file size: 16475339 02:10:49 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 02:10:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:10:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:10:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:10:50 INFO - Http code: 200 02:10:50 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 02:10:50 INFO - x-amz-cf-id: -rEGteVRf-T1pPwc55G7wSHnar8P44B9hgwEVZPUpGwYZXI-GoO1GQ== 02:10:50 INFO - x-amz-version-id: C6L0_yAISQyr3Vcpu9GUtsBn5TPuRGl1 02:10:50 INFO - Expected file size: 16475339 02:10:50 INFO - Obtained file size: 16475339 02:10:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 02:10:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:10:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 02:10:57 INFO - Http code: 200 02:10:57 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 02:10:57 INFO - x-amz-cf-id: fNN320Rjg3bbFu0v0qs5CgKNJoqn-tUYk6Vr2V0gaMDAxlodtXY0LQ== 02:10:57 INFO - x-amz-version-id: .N1w5I9nOwPLgXrcH2q3397lWlybi3mJ 02:10:58 INFO - Expected file size: 14408522 02:10:58 INFO - Obtained file size: 14408522 02:10:58 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')]} 02:10:58 INFO - trying https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:10:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 02:10:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/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 02:11:01 INFO - Downloaded 111786306 bytes. 02:11:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:11:01 INFO - mkdir: /builds/slave/test/properties 02:11:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:11:01 INFO - Writing to file /builds/slave/test/properties/build_url 02:11:01 INFO - Contents: 02:11:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:11:02 INFO - Running post-action listener: _resource_record_post_action 02:11:02 INFO - Running post-action listener: set_extra_try_arguments 02:11:02 INFO - [mozharness: 2016-10-01 09:11:02.642972Z] Finished download-and-extract step (success) 02:11:02 INFO - [mozharness: 2016-10-01 09:11:02.643115Z] Running populate-webroot step. 02:11:02 INFO - Running pre-action listener: _resource_record_pre_action 02:11:02 INFO - Running main action method: populate_webroot 02:11:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:11:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:11:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:11:02 INFO - '--tppagecycles', 02:11:02 INFO - '7'], 02:11:02 INFO - 'tests': ['tsvgm']}}, 02:11:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:11:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:11:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:11:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:11:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:11:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:11:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['damp', 'tps']}, 02:11:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'tests': ['damp', 'tps']}, 02:11:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['dromaeo_dom']}, 02:11:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:11:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['basic_compositor_video']}, 02:11:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:11:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['a11yr', 02:11:02 INFO - 'ts_paint', 02:11:02 INFO - 'tpaint', 02:11:02 INFO - 'sessionrestore', 02:11:02 INFO - 'sessionrestore_no_auto_restore', 02:11:02 INFO - 'tabpaint']}, 02:11:02 INFO - 'other-e10s': {'tests': ['a11yr', 02:11:02 INFO - 'ts_paint', 02:11:02 INFO - 'tpaint', 02:11:02 INFO - 'sessionrestore', 02:11:02 INFO - 'sessionrestore_no_auto_restore', 02:11:02 INFO - 'tabpaint']}, 02:11:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:11:02 INFO - 'ts_paint', 02:11:02 INFO - 'tpaint', 02:11:02 INFO - 'sessionrestore', 02:11:02 INFO - 'sessionrestore_no_auto_restore', 02:11:02 INFO - 'tabpaint']}, 02:11:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:11:02 INFO - 'ts_paint', 02:11:02 INFO - 'tpaint', 02:11:02 INFO - 'sessionrestore', 02:11:02 INFO - 'sessionrestore_no_auto_restore', 02:11:02 INFO - 'tabpaint']}, 02:11:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['a11yr', 02:11:02 INFO - 'ts_paint', 02:11:02 INFO - 'tpaint', 02:11:02 INFO - 'sessionrestore', 02:11:02 INFO - 'sessionrestore_no_auto_restore', 02:11:02 INFO - 'tabpaint']}, 02:11:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['a11yr', 02:11:02 INFO - 'ts_paint', 02:11:02 INFO - 'tpaint', 02:11:02 INFO - 'sessionrestore', 02:11:02 INFO - 'sessionrestore_no_auto_restore', 02:11:02 INFO - 'tabpaint']}, 02:11:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['tsvgx', 02:11:02 INFO - 'tsvgr_opacity', 02:11:02 INFO - 'tart', 02:11:02 INFO - 'tscrollx', 02:11:02 INFO - 'cart']}, 02:11:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:11:02 INFO - 'tsvgr_opacity', 02:11:02 INFO - 'tart', 02:11:02 INFO - 'tscrollx', 02:11:02 INFO - 'cart']}, 02:11:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'talos_options': ['--disable-e10s'], 02:11:02 INFO - 'tests': ['tp5o']}, 02:11:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'tests': ['tp5o']}, 02:11:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'talos_options': ['--disable-e10s', 02:11:02 INFO - '--xperf_path', 02:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:11:02 INFO - 'tests': ['tp5n']}, 02:11:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:11:02 INFO - 'talos_options': ['--xperf_path', 02:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:11:02 INFO - 'tests': ['tp5n']}}, 02:11:02 INFO - 'talos.zip': {'path': '', 02:11:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:11:02 INFO - Running post-action listener: _resource_record_post_action 02:11:02 INFO - [mozharness: 2016-10-01 09:11:02.662839Z] Finished populate-webroot step (success) 02:11:02 INFO - [mozharness: 2016-10-01 09:11:02.662979Z] Running create-virtualenv step. 02:11:02 INFO - Running pre-action listener: _resource_record_pre_action 02:11:02 INFO - Running main action method: create_virtualenv 02:11:02 INFO - Creating virtualenv /builds/slave/test/build/venv 02:11:02 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 02:11:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:11:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:11:02 INFO - Using real prefix '/tools/python27' 02:11:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:11:03 INFO - Installing distribute.............................................................................................................................................................................................done. 02:11:06 INFO - Installing pip.................done. 02:11:06 INFO - Return code: 0 02:11:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:11:06 INFO - Reading from file tmpfile_stdout 02:11:06 INFO - Output received: 02:11:06 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) 02:11:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:06 INFO - 'HOME': '/Users/cltbld', 02:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:06 INFO - 'LOGNAME': 'cltbld', 02:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:06 INFO - 'MOZ_NO_REMOTE': '1', 02:11:06 INFO - 'NO_EM_RESTART': '1', 02:11:06 INFO - 'PAGER': '/bin/cat', 02:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:06 INFO - 'PWD': '/builds/slave/test', 02:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:06 INFO - 'SHELL': '/bin/bash', 02:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:06 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:06 INFO - 'USER': 'cltbld', 02:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:06 INFO - 'XPC_FLAGS': '0x0', 02:11:06 INFO - 'XPC_SERVICE_NAME': '0', 02:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:06 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 02:11:06 INFO - Cleaning up... 02:11:06 INFO - Return code: 0 02:11:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:06 INFO - 'HOME': '/Users/cltbld', 02:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:06 INFO - 'LOGNAME': 'cltbld', 02:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:06 INFO - 'MOZ_NO_REMOTE': '1', 02:11:06 INFO - 'NO_EM_RESTART': '1', 02:11:06 INFO - 'PAGER': '/bin/cat', 02:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:06 INFO - 'PWD': '/builds/slave/test', 02:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:06 INFO - 'SHELL': '/bin/bash', 02:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:06 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:06 INFO - 'USER': 'cltbld', 02:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:06 INFO - 'XPC_FLAGS': '0x0', 02:11:06 INFO - 'XPC_SERVICE_NAME': '0', 02:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:07 INFO - Downloading/unpacking psutil>=3.1.1 02:11: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 02:11: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 02:11: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 02:11: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 02:11: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 02:11: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 02:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:11:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:11:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:11:11 INFO - Installing collected packages: psutil 02:11:11 INFO - Running setup.py install for psutil 02:11:11 INFO - building 'psutil._psutil_osx' extension 02:11:11 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 02:11:11 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 02:11:11 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 02:11:11 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 02:11:11 INFO - building 'psutil._psutil_posix' extension 02:11:11 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 02:11:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:11:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:11:12 INFO - ^ 02:11:12 INFO - 1 warning generated. 02:11:12 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 02:11:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:11:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:11:12 INFO - Successfully installed psutil 02:11:12 INFO - Cleaning up... 02:11:12 INFO - Return code: 0 02:11:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:12 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:12 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 02:11:12 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 02:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:12 INFO - 'HOME': '/Users/cltbld', 02:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:12 INFO - 'LOGNAME': 'cltbld', 02:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:12 INFO - 'MOZ_NO_REMOTE': '1', 02:11:12 INFO - 'NO_EM_RESTART': '1', 02:11:12 INFO - 'PAGER': '/bin/cat', 02:11:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:12 INFO - 'PWD': '/builds/slave/test', 02:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:12 INFO - 'SHELL': '/bin/bash', 02:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:12 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:12 INFO - 'USER': 'cltbld', 02:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:12 INFO - 'XPC_FLAGS': '0x0', 02:11:12 INFO - 'XPC_SERVICE_NAME': '0', 02:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:11:12 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 02:11:12 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 02:11:12 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 02:11:12 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 02:11:12 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 02:11:12 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 02:11:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:11:15 INFO - Installing collected packages: mozsystemmonitor 02:11:15 INFO - Running setup.py install for mozsystemmonitor 02:11:15 INFO - Successfully installed mozsystemmonitor 02:11:15 INFO - Cleaning up... 02:11:15 INFO - Return code: 0 02:11:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:11:15 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')]} 02:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:15 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')]} 02:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:15 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:15 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 02:11:15 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 02:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:15 INFO - 'HOME': '/Users/cltbld', 02:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:15 INFO - 'LOGNAME': 'cltbld', 02:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:15 INFO - 'MOZ_NO_REMOTE': '1', 02:11:15 INFO - 'NO_EM_RESTART': '1', 02:11:15 INFO - 'PAGER': '/bin/cat', 02:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:15 INFO - 'PWD': '/builds/slave/test', 02:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:15 INFO - 'SHELL': '/bin/bash', 02:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:15 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:15 INFO - 'USER': 'cltbld', 02:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:15 INFO - 'XPC_FLAGS': '0x0', 02:11:15 INFO - 'XPC_SERVICE_NAME': '0', 02:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:15 INFO - Downloading/unpacking jsonschema==2.5.1 02:11:15 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 02:11:15 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 02:11:15 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 02:11:15 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 02:11:15 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 02:11:15 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 02:11:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:11:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:18 INFO - Downloading functools32-3.2.3-2.tar.gz 02:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:11:18 INFO - warning: no files found matching '*.txt' 02:11:18 INFO - no previously-included directories found matching 'build' 02:11:18 INFO - no previously-included directories found matching 'dist' 02:11:18 INFO - no previously-included directories found matching '.git*' 02:11:18 INFO - Installing collected packages: jsonschema, functools32 02:11:18 INFO - Running setup.py install for functools32 02:11:18 INFO - warning: no files found matching '*.txt' 02:11:18 INFO - no previously-included directories found matching 'build' 02:11:18 INFO - no previously-included directories found matching 'dist' 02:11:18 INFO - no previously-included directories found matching '.git*' 02:11:18 INFO - Successfully installed jsonschema functools32 02:11:18 INFO - Cleaning up... 02:11:18 INFO - Return code: 0 02:11:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:11:18 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')]} 02:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:18 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')]} 02:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:18 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:18 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 02:11:18 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 02:11:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:18 INFO - 'HOME': '/Users/cltbld', 02:11:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:18 INFO - 'LOGNAME': 'cltbld', 02:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:18 INFO - 'MOZ_NO_REMOTE': '1', 02:11:18 INFO - 'NO_EM_RESTART': '1', 02:11:18 INFO - 'PAGER': '/bin/cat', 02:11:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:18 INFO - 'PWD': '/builds/slave/test', 02:11:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:18 INFO - 'SHELL': '/bin/bash', 02:11:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:18 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:18 INFO - 'USER': 'cltbld', 02:11:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:18 INFO - 'XPC_FLAGS': '0x0', 02:11:18 INFO - 'XPC_SERVICE_NAME': '0', 02:11:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:11:19 INFO - Cleaning up... 02:11:19 INFO - Return code: 0 02:11:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:11: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')]} 02:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11: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')]} 02:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:19 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:19 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 02:11:19 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 02:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:19 INFO - 'HOME': '/Users/cltbld', 02:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:19 INFO - 'LOGNAME': 'cltbld', 02:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:19 INFO - 'MOZ_NO_REMOTE': '1', 02:11:19 INFO - 'NO_EM_RESTART': '1', 02:11:19 INFO - 'PAGER': '/bin/cat', 02:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:19 INFO - 'PWD': '/builds/slave/test', 02:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:19 INFO - 'SHELL': '/bin/bash', 02:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:19 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:19 INFO - 'USER': 'cltbld', 02:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:19 INFO - 'XPC_FLAGS': '0x0', 02:11:19 INFO - 'XPC_SERVICE_NAME': '0', 02:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:19 INFO - Downloading/unpacking blobuploader==1.2.4 02:11:19 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 02:11:19 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 02:11:19 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 02:11:19 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 02:11:19 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 02:11:19 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 02:11:21 INFO - Downloading blobuploader-1.2.4.tar.gz 02:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:11:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:11:21 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 02:11:21 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 02:11:21 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 02:11:21 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 02:11:21 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 02:11:21 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 02:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:11:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:11:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:22 INFO - Downloading docopt-0.6.1.tar.gz 02:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:11:23 INFO - Installing collected packages: blobuploader, requests, docopt 02:11:23 INFO - Running setup.py install for blobuploader 02:11:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:11:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:11:23 INFO - Running setup.py install for requests 02:11:23 INFO - Running setup.py install for docopt 02:11:23 INFO - Successfully installed blobuploader requests docopt 02:11:23 INFO - Cleaning up... 02:11:24 INFO - Return code: 0 02:11:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:11:24 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')]} 02:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:24 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')]} 02:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:24 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:24 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 02:11:24 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 02:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:24 INFO - 'HOME': '/Users/cltbld', 02:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:24 INFO - 'LOGNAME': 'cltbld', 02:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:24 INFO - 'MOZ_NO_REMOTE': '1', 02:11:24 INFO - 'NO_EM_RESTART': '1', 02:11:24 INFO - 'PAGER': '/bin/cat', 02:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:24 INFO - 'PWD': '/builds/slave/test', 02:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:24 INFO - 'SHELL': '/bin/bash', 02:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:24 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:24 INFO - 'USER': 'cltbld', 02:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:24 INFO - 'XPC_FLAGS': '0x0', 02:11:24 INFO - 'XPC_SERVICE_NAME': '0', 02:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:11:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ggzWCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:11:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-E2krx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:11:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-SidjJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:11:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-mIrk2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:11:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-sOZn5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:11:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-4YkOAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:11:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-htWwm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-7FMIA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-lfweTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-wjB_sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-f2bxup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dvBLGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yad5Bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-5ftLCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-I7etVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-YPDOS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:11:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-VVCa7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:11:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:11:26 INFO - Running setup.py install for manifestparser 02:11:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:11:26 INFO - Running setup.py install for mozcrash 02:11:26 INFO - Running setup.py install for mozdebug 02:11:26 INFO - Running setup.py install for mozdevice 02:11:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:11:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:11:26 INFO - Running setup.py install for mozfile 02:11:26 INFO - Running setup.py install for mozhttpd 02:11:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:11:26 INFO - Running setup.py install for mozinfo 02:11:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:11:27 INFO - Running setup.py install for mozInstall 02:11:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:11:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:11:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:11:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:11:27 INFO - Running setup.py install for mozleak 02:11:27 INFO - Running setup.py install for mozlog 02:11:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:11:27 INFO - Running setup.py install for moznetwork 02:11:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:11:27 INFO - Running setup.py install for mozprocess 02:11:27 INFO - Running setup.py install for mozprofile 02:11:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:11:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:11:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:11:28 INFO - Running setup.py install for mozrunner 02:11:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:11:28 INFO - Running setup.py install for mozscreenshot 02:11:28 INFO - Running setup.py install for moztest 02:11:28 INFO - Running setup.py install for mozversion 02:11:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:11:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:11:28 INFO - Cleaning up... 02:11:28 INFO - Return code: 0 02:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:11:28 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')]} 02:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:28 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')]} 02:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:28 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:28 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 02:11:28 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 02:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:28 INFO - 'HOME': '/Users/cltbld', 02:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:28 INFO - 'LOGNAME': 'cltbld', 02:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:28 INFO - 'MOZ_NO_REMOTE': '1', 02:11:28 INFO - 'NO_EM_RESTART': '1', 02:11:28 INFO - 'PAGER': '/bin/cat', 02:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:28 INFO - 'PWD': '/builds/slave/test', 02:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:28 INFO - 'SHELL': '/bin/bash', 02:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:28 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:28 INFO - 'USER': 'cltbld', 02:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:28 INFO - 'XPC_FLAGS': '0x0', 02:11:28 INFO - 'XPC_SERVICE_NAME': '0', 02:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:11:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-lnvDJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-VsQLBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-RcRGl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-8g_Kh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-8xi645-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-QCmVYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-pS4TFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-3vOGcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-CTnAnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:11:29 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)) 02:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:11:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-2Vytpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:11:30 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)) 02:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:11:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-fFPFv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:11:30 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)) 02:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:11:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-52Uq34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:11:30 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)) 02:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:11:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-jO1vIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:11:30 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)) 02:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:11:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-lNcMNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:11:30 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)) 02:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-q0ZwUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:30 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)) 02:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:11:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-hdwmwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:11:30 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)) 02:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:11:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-SnTa5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:11:30 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)) 02:11:30 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)) 02:11:30 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)) 02:11:30 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)) 02:11:30 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)) 02:11:30 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)) 02:11:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:11:30 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 02:11:30 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 02:11:30 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 02:11:30 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 02:11:30 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 02:11:30 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 02:11:33 INFO - Downloading blessings-1.6.tar.gz 02:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:11:33 INFO - Installing collected packages: blessings 02:11:33 INFO - Running setup.py install for blessings 02:11:33 INFO - Successfully installed blessings 02:11:33 INFO - Cleaning up... 02:11:33 INFO - Return code: 0 02:11:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:11:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:11:33 INFO - Reading from file tmpfile_stdout 02:11:33 INFO - Current package versions: 02:11:33 INFO - blessings == 1.6 02:11:33 INFO - blobuploader == 1.2.4 02:11:33 INFO - docopt == 0.6.1 02:11:33 INFO - functools32 == 3.2.3-2 02:11:33 INFO - jsonschema == 2.5.1 02:11:33 INFO - manifestparser == 1.1 02:11:33 INFO - mozInstall == 1.12 02:11:33 INFO - mozcrash == 0.17 02:11:33 INFO - mozdebug == 0.1 02:11:33 INFO - mozdevice == 0.48 02:11:33 INFO - mozfile == 1.2 02:11:33 INFO - mozhttpd == 0.7 02:11:33 INFO - mozinfo == 0.9 02:11:33 INFO - mozleak == 0.1 02:11:33 INFO - mozlog == 3.4 02:11:33 INFO - moznetwork == 0.27 02:11:33 INFO - mozprocess == 0.23 02:11:33 INFO - mozprofile == 0.28 02:11:33 INFO - mozrunner == 6.12 02:11:33 INFO - mozscreenshot == 0.1 02:11:33 INFO - mozsystemmonitor == 0.3 02:11:33 INFO - moztest == 0.7 02:11:33 INFO - mozversion == 1.4 02:11:33 INFO - psutil == 3.1.1 02:11:33 INFO - requests == 1.2.3 02:11:33 INFO - wsgiref == 0.1.2 02:11:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:33 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:33 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 02:11:33 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 02:11:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:33 INFO - 'HOME': '/Users/cltbld', 02:11:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:33 INFO - 'LOGNAME': 'cltbld', 02:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:33 INFO - 'MOZ_NO_REMOTE': '1', 02:11:33 INFO - 'NO_EM_RESTART': '1', 02:11:33 INFO - 'PAGER': '/bin/cat', 02:11:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:33 INFO - 'PWD': '/builds/slave/test', 02:11:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:33 INFO - 'SHELL': '/bin/bash', 02:11:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:33 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:33 INFO - 'USER': 'cltbld', 02:11:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:33 INFO - 'XPC_FLAGS': '0x0', 02:11:33 INFO - 'XPC_SERVICE_NAME': '0', 02:11:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 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)) 02:11:34 INFO - Cleaning up... 02:11:34 INFO - Return code: 0 02:11:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:11:34 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 0x10b85e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2f2d90690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, '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.C2kYhSdIf5/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.ERET0j5yjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 02:11:34 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 02:11:34 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 02:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:11:34 INFO - 'HOME': '/Users/cltbld', 02:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:11:34 INFO - 'LOGNAME': 'cltbld', 02:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:34 INFO - 'MOZ_NO_REMOTE': '1', 02:11:34 INFO - 'NO_EM_RESTART': '1', 02:11:34 INFO - 'PAGER': '/bin/cat', 02:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:34 INFO - 'PWD': '/builds/slave/test', 02:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:11:34 INFO - 'SHELL': '/bin/bash', 02:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:11:34 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:11:34 INFO - 'USER': 'cltbld', 02:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:34 INFO - 'XPC_FLAGS': '0x0', 02:11:34 INFO - 'XPC_SERVICE_NAME': '0', 02:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:11:34 INFO - Cleaning up... 02:11:34 INFO - Return code: 0 02:11:34 INFO - Running post-action listener: _resource_record_post_action 02:11:34 INFO - Running post-action listener: _start_resource_monitoring 02:11:34 INFO - Starting resource monitoring. 02:11:34 INFO - [mozharness: 2016-10-01 09:11:34.435913Z] Finished create-virtualenv step (success) 02:11:34 INFO - [mozharness: 2016-10-01 09:11:34.436936Z] Running install step. 02:11:34 INFO - Running pre-action listener: _resource_record_pre_action 02:11:34 INFO - Running main action method: install 02:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:11:34 INFO - Reading from file tmpfile_stdout 02:11:34 INFO - Detecting whether we're running mozinstall >=1.0... 02:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:11:34 INFO - Reading from file tmpfile_stdout 02:11:34 INFO - Output received: 02:11:34 INFO - Usage: mozinstall [options] installer 02:11:34 INFO - Options: 02:11:34 INFO - -h, --help show this help message and exit 02:11:34 INFO - -d DEST, --destination=DEST 02:11:34 INFO - Directory to install application into. [default: 02:11:34 INFO - "/builds/slave/test"] 02:11:34 INFO - --app=APP Application being installed. [default: firefox] 02:11:34 INFO - mkdir: /builds/slave/test/build/application 02:11:34 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'] 02:11:34 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 02:12:10 INFO - Reading from file tmpfile_stdout 02:12:10 INFO - Output received: 02:12:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:12:10 INFO - Running post-action listener: _resource_record_post_action 02:12:10 INFO - [mozharness: 2016-10-01 09:12:10.527672Z] Finished install step (success) 02:12:10 INFO - [mozharness: 2016-10-01 09:12:10.527813Z] Running run-tests step. 02:12:10 INFO - Running pre-action listener: _resource_record_pre_action 02:12:10 INFO - Running main action method: run_tests 02:12:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:12:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:12:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:12:10 INFO - 2016-10-01 02:12:10.542 screenresolution[1648:8407] starting screenresolution argv=screenresolution get 02:12:10 INFO - 2016-10-01 02:12:10.545 screenresolution[1648:8407] Display 0: 1600x1200x32@60 02:12:10 INFO - 2016-10-01 02:12:10.551 screenresolution[1649:8409] starting screenresolution argv=screenresolution list 02:12:10 INFO - Available Modes on Display 0 02:12:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:12:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:12:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:12:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:12:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:12:10 INFO - Intel Iris: 02:12:10 INFO - Chipset Model: Intel Iris 02:12:10 INFO - Type: GPU 02:12:10 INFO - Bus: Built-In 02:12:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:12:10 INFO - Vendor: Intel (0x8086) 02:12:10 INFO - Device ID: 0x0a2e 02:12:10 INFO - Revision ID: 0x0009 02:12:10 INFO - Displays: 02:12:10 INFO - Display: 02:12:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:12:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:12:10 INFO - Display Serial Number: 1600x1200 60 02:12:10 INFO - Main Display: Yes 02:12:10 INFO - Mirror: Off 02:12:10 INFO - Online: Yes 02:12:10 INFO - Rotation: Supported 02:12:10 INFO - Return code: 0 02:12:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:12:10 INFO - Python 2.7.3 02:12:10 INFO - Return code: 0 02:12:10 INFO - grabbing minidump binary from tooltool 02:12: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')]} 02:12:10 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 0x10b672d30>, '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 0x10b498db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b66ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:12:10 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 02:12:10 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 02:12:10 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 02:12:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:12:10 INFO - Return code: 0 02:12:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:12:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:12:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:12:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:12:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:12:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 02:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --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 02:12:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C2kYhSdIf5/Render', 02:12:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:10 INFO - 'HOME': '/Users/cltbld', 02:12:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:10 INFO - 'LOGNAME': 'cltbld', 02:12:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:12:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:10 INFO - 'MOZ_NO_REMOTE': '1', 02:12:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:12:10 INFO - 'NO_EM_RESTART': '1', 02:12:10 INFO - 'PAGER': '/bin/cat', 02:12:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:10 INFO - 'PWD': '/builds/slave/test', 02:12:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:12:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:10 INFO - 'SHELL': '/bin/bash', 02:12:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ERET0j5yjb/Listeners', 02:12:10 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 02:12:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:10 INFO - 'USER': 'cltbld', 02:12:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:10 INFO - 'XPC_FLAGS': '0x0', 02:12:10 INFO - 'XPC_SERVICE_NAME': '0', 02:12:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 02:12:11 INFO - mozversion application_buildid: 20160930232556 02:12:11 INFO - mozversion application_changeset: fcc62bbf09eecabf27c8d198d5d3719e16b296ea 02:12:11 INFO - mozversion application_display_name: Nightly 02:12:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:12:11 INFO - mozversion application_name: Firefox 02:12:11 INFO - mozversion application_remotingname: firefox 02:12:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:12:11 INFO - mozversion application_vendor: Mozilla 02:12:11 INFO - mozversion application_version: 52.0a1 02:12:11 INFO - mozversion platform_buildid: 20160930232556 02:12:11 INFO - mozversion platform_changeset: fcc62bbf09eecabf27c8d198d5d3719e16b296ea 02:12:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:12:11 INFO - mozversion platform_version: 52.0a1 02:12:11 INFO - using testdate: 1475313131 02:12:11 INFO - actual date: 1475313131 02:12:11 INFO - starting webserver on 'localhost:49274' 02:12:11 INFO - SUITE-START | Running 2 tests 02:12:11 INFO - TEST-START | dromaeo_css 02:12:11 INFO - operating with platform_type : mac_ 02:12:11 INFO - Initialising browser for dromaeo_css test... 02:12:11 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpmGk4SQ/profile http://localhost:49274/getInfo.html) 02:12:13 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 02:12:13 INFO - PROCESS | 1659 | colorDepth:24 02:12:13 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 02:12:13 INFO - PROCESS | 1659 | __metrics 02:12:13 INFO - PROCESS | 1659 | 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" 02:12:13 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:12:14 INFO - TEST-INFO | 1659: exit 0 02:12:14 INFO - Browser initialized. 02:12:14 INFO - Running cycle 1/1 for dromaeo_css test... 02:12:14 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpmGk4SQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:12:26 INFO - PROCESS | 1662 | RSS: Main: 244396032 02:12:26 INFO - PROCESS | 1662 | 02:13:18 INFO - PROCESS | 1662 | 1475313198097 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] 02:15:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 02:15:33 INFO - PROCESS | 1662 | RSS: Main: 373170176 02:15:33 INFO - PROCESS | 1662 | 02:18:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 02:18:36 INFO - PROCESS | 1662 | RSS: Main: 371687424 02:18:36 INFO - PROCESS | 1662 | 02:21:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 02:21:39 INFO - PROCESS | 1662 | RSS: Main: 361603072 02:21:40 INFO - PROCESS | 1662 | 02:24:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 02:24:42 INFO - PROCESS | 1662 | RSS: Main: 347713536 02:24:42 INFO - PROCESS | 1662 | 02:27:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 02:27:47 INFO - PROCESS | 1662 | RSS: Main: 332013568 02:27:47 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 02:30:50 INFO - PROCESS | 1662 | RSS: Main: 378679296 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | __start_tp_report 02:30:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 02:30:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 02:30:50 INFO - PROCESS | 1662 | |i|pagename|runs| 02:30:50 INFO - PROCESS | 1662 | |0;dojo.html;3253.7234335081525;3271.5910511186103;3279.212988882669;3316.3367326534667;3317.5023746438033;5380.811671591495;5434.565234781218;5485.890282194354;5503.229547863302;5520.668759874406;5437.265969094171;5479.315085614298;5515.09001014833;5534.280543529336;5571.359293681217;4974.129527332702;4983.975080124595;5014.674046186986;5045.318881950945;5052.9747351263195;5064.3163172971545;5130.820421285256;5144.691318520882;5149.871253218681;5192.935448233122;4992.800287988476;5112.207607820794;5113.948860511385;5114.974425127869;5197.584193264549;5023.874403139914;5071.670341427795;5079.23811428285;5083.79664813407;5090.516400941905;4940.728259945701;4977.552020318189;5009.599232061426;5009.849704508871;5030.0694371541385;5434.157705555645;5478.315210598676;5505.6696598204235;5511.173324001392;5514.834554963358;5918.881622367571;5943.673097979609;5945.31628862682;5955.315138759055;5966.224390829208;5862.296524417055;5865.0615901455985;5889.587728858982;5922.31893332268;5979.402059794029;5545.112781954908;5548.195511650788;5551.084071128299;5558.722063896789;5558.777648893999;6186.938130618726;6188.504919606421;6193.473554747807;6208.317085120633;6210.472109870711;5672.8865422691315;5710.029295019856;5732.426757324318;5787.681677507778;5865.442782935615;5843.0651095825315;5891.911621325684;5916.970415147896;5937.554683398763;5938.910916336258;5835.299764028344;5839.416058394211;5843.766249349978;5852.4440178183;5853.209816674779;5787.884242315215;5872.324682661549;5872.471477567039;5900.911486327708;5938.940610593839;4762.904741905142;4769.093872164278;4770.499097594703;4771.570558649738;4818.518148185153;4222.303319952235;4268.743875367488;4294;4335.913281734348;4341.153475072394;5487.039768040657;5513.531349835309;5513.889722205614;5526.336839579276;5571.1086226203615;6054.212952316258;6068.514518838482;6082.787102451482;6103.450689437972;6124.265088189446;5555.77776888928;5558.499735023867;5562.221289019541;5563.360213575491;5572.665640061609;5119.206522988943;5179.507946745053;5184.015037142931;5209.947900521022;5219;6160.599561028519;6182.196314479089;6233.2208473940755;6292.779752708633;6305.243370795534;6108.664023478574;6109.175261339662;6113.969430152821;6121.112438696453;6137.508999280136;5864.413558644102;6238.968805155945;6292.1505596743855;6308.400701933309;6312.684365781784;5649.350324712546;5714.171445140514;5868.3251426086545;5895.115732639967;5898.970505147447;6096.254562767794;6104.125697713593;6107.188815080102;6114.388610881663;6126.387361263838;5751.079827227623;5833.358330583547;5847.38602446737;5858.209141765978;5879.559033072451;5670.631408958405;5732.5413966881715;5737.770489180386;5819.592628515963;5822.417758224144;5520.889582208413;5606.103023516218;5654.434556544313;5662.8301150965535;5664.178694089416;5731.799387021434;5732.598718089694;5736.741846616996;5745.597808153556;5752.856178595402;5562.860928476659;5608.803691870765;5631.605787594829;5644.85887852807;5706.400827913009 02:30:50 INFO - PROCESS | 1662 | |1;ext.html;455.28747510146616;517.7204309672776;533.2960492150363;552.0229194326046;559.8208573256557;25665.61501577478;32350.19124521888;32447.351052978927;32488.512672309906;32505;42412;43255;43337;45324.320135197915;45410.54589454104;9839.50802459876;9987.350822196535;9995.500224988722;10008.649697260596;10015;9756;9846.950765246163;9856.655017074369;9864.556094975744;9867.555959981783;9815.116639502443;9856.162226210745;9872.308938374317;9882.505874706272;9929.553170107327;9746.366486178411;9816.361936474106;9824.705258842174;9831.754206144868;9832.262580306506;9802.656907008291;9869.457179855106;9874.901250987541;9878.308518403715;9881.25890558211;39969;40167.7991610043;40244.597554024374;40282.79858600718;40398.798006010074;3198.3603279344225;3251.7886337387995;3339.1819004343847;3408.829558522064;3634.309481198564;29865.95469158569;30632.54051189234;31381.8430907844;31495.527567086512;31503.054908352788;3014.8643311051055;3126.9212121817936;3171.6035495563056;3246.9188270293193;3308.553345298378;465943;466881;468834;468859.6557017227;472737;57981.71009145011;58131;58225.70887145622;58708.11937820936;58723.70638146782;60656;60699.69650151721;60756.48108797227;60844;60908.086378704356;15727.292271847795;15929.681406371808;15969.121698306471;15983.961042532199;15997.360105595879;10405.739856503647;11826.467808948446;12333.444994975247;12375.69060773488;12407.689807754878;15493.922530387275;16125.419932810693;16147.596310092342;16196.514104576881;16281;5971.850703732354;7005.649717514104;7033.191183013917;7082.394040278909;7159.355657990805;879.4239772948729;975.22956864078;985.8951139317466;988.663854289545;1020.6529779874878;26209;29259.56110658342;29409;29680.257993550335;30076.699233007388;818.7134502924048;972.2562239886493;1003.2174903575223;1019.9898001020043;1064.6486659402378;14621.853781462261;15080.54758357251;15238.238088095552;15404.92297538505;15530.689386212212;600238;602121;605156;605497;608524;56260.71869640626;56644;56680.433195667516;56689.716551416976;56850.715746421;1206.4269472000906;1245.5453759377763;1313.5271302330962;1352.932353382327;1367.43934986655;23938.042478300675;28668.139955801358;30139.69860301369;30141.095767127023;31363.529547057267;48793.02413951772;49452.258216126065;49469.257961131334;49506.75246623888;49522.25716614182;423888;426631;427046;427191;427421;25639;26228.213153605422;26270.73729262683;27119.72880271172;39066.14007898838;45831.77084114558;46211.84470388267;46434.30348544842;47055.823604410165;47420.34028808975;38528.80735596304;38609.613903861726;38843.80578097091;40089.59910400859;40705.79647101864;36687.449688255234;38177;38383.84848454551;39003.609963899995;39190.60809391984;36721.44917826181;36834.63165368312;37580.436293456165;37826.43260351042;38131.23737525178;32467.512987305683;34395.4840677385;34405;34420;34443.800115979364;22519.66220506661;23649.52700945937;24822.510649361095;25299.367515812253;25313.746862531138 02:30:50 INFO - PROCESS | 1662 | |2;jquery.html;344.85343728915205;368.08530800959613;369.8132443116226;375.8966284271826;396.0731887383522;15177.089374637515;15237.085774853502;16733.414330498435;21135.788642113614;21174.89412552935;19011.524711882208;20070.799292007076;20680.37958861233;20746.377608671726;20944.685829712565;5950.107483877431;5969;6275.309715931244;6282.905756413657;6290;5996;6174.043023331368;6182.505399568024;6184.443400093991;6195.876082478348;4209.137126888981;4426.62373698236;6478.153726188038;6823.863522729543;6882.621455819928;5802.4197580241635;6618.93381066188;6794.45644348451;6885.414739747102;6887.690053947559;5793.8841223175305;5956.225690660186;5957.553183511254;5961.850953726148;5964.910526342108;24586.016559337604;26123.608145877828;26126.86936565324;26154.422975979127;26509.93960241588;2266.048259730917;2372.205311220743;2417.4319035026756;2506.8370555913807;2898.0581311073774;12411.441485133191;15428.845711542965;15478.380864765508;16083.437079702271;16159.353625854952;1810.3482746211353;1827.2051657529018;1851.9443916967334;1907.0655378864396;1950.619629172312;104105;118195.4090229552;118690;119270;119371;8881.866771998426;10522;10933.453327333602;10962.671119866416;11092.389918554396;13563;13576.86423135756;13616.114952528253;13673.247971361472;13677.110987785762;8341.83316333682;8375.32493501302;8448.408611397266;8516.233538981522;8518.531480768617;8433.325333973267;8437.240648341678;8461.661533538712;8591.140885911358;8665.696700615448;6515.543911926215;6634.635095069721;6637.701303441357;6639.302873198341;6686.431653309523;1791.4446521578352;1828.168183476515;1831.899245541508;1832.3037245846492;1837.4303965770655;372.045702772388;832.488019867777;973.1630797514132;992.9602815887283;1024.9795004099876;11179.273347232403;11664.591739289084;12016.63950081499;12053;12057.939710301393;422.71044334630875;425.28552032585554;427.1265262538117;428.4430240687094;440.36587314267354;5904.7342869570975;5968.4926781224085;6168.228971378578;6179.3820617937845;6183.938160618336;533624;592155;603377;613882;614514;121326;121946.3902680481;125140.37429812975;127852;134129.3293533526;815.5595978171721;989.7723523589615;1009.9091081802526;1015.5024038221219;1025.348903446302;15068.321925513597;17977.820221798138;19729.40811775649;19992.90003550031;20069.29757458482;37999.43000854934;48260;52647;52722.94554108973;53164.73417632887;67893.98159027717;71645;72010.91983620146;72140;74363;7901.328387086932;7915.248051435105;8109;8203.917960820316;8324.209200125979;7445.664945077377;12754.744905102032;12849;13221.206727596375;13355.53255636048;11067;11350.035247003767;11474.70505898807;11499.425028748361;11538.423078846192;11021.393823339793;11128.38793866345;11412.942935285271;11425.828612570984;11429.657110286706;11229.4385280734;11379.943100284445;11453.370064646524;11659.901217780882;11774.646760597196;7982.521048737094;8028.671685211457;8072.152423995396;8191.426600137933;8244.54654993981;10398.376097434179;10813.459327033459;11096.935609576942;11190.83213751778;11695.005924496512 02:30:50 INFO - PROCESS | 1662 | |3;mootools.html;1516.5981015031016;1532.3257766582703;1734.9132543372837;1836.1186630417392;1903.1626084522818;8277.420580559356;8771.780705482359;8772.429792063527;8778.385513014091;8796.428232164904;7751.457397982143;7945.841083178348;7953.483023603461;8445.831083378329;8565.357598180146;2308.9884550577267;2343.16817529777;2344.2615576093563;2344.3201471573307;2344.753800850912;2302.873341966191;2332.568474832153;2339.6256598944174;2340.8361414700976;2341.9648705269433;2273.068042102738;2283.54329134173;2288.7253529576474;2294.2773026496684;2305.3775480620225;2311.0640190722784;2313.595120853844;2317.40906068953;2319.2230602748014;2320.512692334612;2189.5073608438133;2214.8006679398773;2216.312942987679;2219.2676416782583;2223;8709.172628600336;8775.956120219358;8823.602937867812;8828.073052329477;8831.77920551985;5095.210242412432;5249.4750524947585;5252.080885844962;5257.973710131463;5270.841874743725;2878.352370716572;3476.9130771730656;3479.4954731564035;3622.6377362263825;3631.509746184258;1074.355386767941;1075.8977897099762;1078.2775540387959;1097.040089921319;1098.9725256868562;11162.944185279022;11333.489992950337;11353;11361;11425.31448113116;4486.798094085774;4510.436195475566;4676.223035246585;4681.602063824613;4720.787564559603;9002.86495702565;9111.954440227757;9741.610335586638;9754.0733630305;9774.609015639437;5227.738613069331;5249.7112658804135;5250.816221432232;5251.186066159751;5253.212018197301;4287.142571485715;4289.163613095417;4575.611073058761;4791.496892826272;4807.831725889577;3782.111203867089;3786.848526058927;3920.4707364505425;3922.686185105185;3938.468306778548;1079.8704155501232;1209.8729633388543;1236.7217376090487;1237.9814302785464;1256.5790460195753;824.4947365287884;845.0197770586213;898.766320756595;899.4198741811495;931.59475628102;5407.351117865803;5723.427657234327;5779.393163717833;6011.699415029231;6045.395460453919;927.9443233406017;933.8926023507248;943.9480828554496;948.9857652135223;990.6087095597198;3905.4183055862422;3982.067772686177;4047.8785636430953;4055.047063939972;4110.7328023678365;9488.715338539854;9959.850602240973;10107.989201079834;10111.241656875616;10133.594656213669;9844.114029737359;10054.044865737744;10067.194624430029;10068.44623545712;10072.446015469219;7899.21007899217;8072.233137851895;8146.75559733209;8157.673693052211;8168.264856162974;5666.320041595035;5832.795852145325;5918.911216331845;5972.343042265435;6011.879762404815;7680.884786728092;9792.412455252708;9905.306628535933;9906.653267135616;9946.403215807075;9527.38072025316;9940.900590994286;9943.055409736064;10022.34854734469;10087.495625218857;6602.075709400784;6798.93201067983;7369.115706115301;7544.660490277841;7557.4331925106935;7446.664900079401;8000.079990801132;8141.51150930946;8311.085780564012;8389.412741108064;7292.34368906801;7409.740659076906;7435.442341824277;7448.441366897613;7565.356944659766;7273.418126549757;7286.562806231643;7304.19653838088;7312.5246858954;7353.485256032026;6893.586384816927;6893.965530172317;6995.125609298838;7000.159980802337;7003.54476958996;4269.295566231537;4937.333459983;4984.925226121539;5020.949790502194;5102.591792656504;3884.864029758945;4930.383702037246;5040.521150490697;5091.643584949166;5094.923576146287 02:30:50 INFO - PROCESS | 1662 | |4;prototype.html;291.7082917082917;294.4155850636487;303.9103464477978;306.2756580686674;307.1859572133845;4090.1001779608437;4406.625436837869;4483.887902802431;4955.182394904841;5098;4098.877033688987;4883.853484395474;5001.6998980061135;5016.598672106232;5021.849344519661;3886.261610294049;3933.547642021161;3942.5466071401725;3959.6436320731127;3965.8017099145072;3698.9458004468734;3931.174453364797;3955.8417663293576;3956.0703234739813;3970.2059588082357;3017;3251.658575849537;3777.622237776228;3892.5912779158207;3926.411038344257;3219.259570298833;3352.379809735207;3783.451399547078;3882.262370149677;3893.9415908761393;3375.476801095834;3406.352792969353;3408.744344174197;3414.146463384154;3417.4361230396953;3835.8082095895375;4011;4947.342640215532;4977.013791724936;4996.225585034289;3735.906602334936;4386.5962891874615;4443.666724995606;4447.332900064984;4467.039586488921;4839.782209800533;5305.549028332596;5306.469353064701;5326.334208223972;5366.195070739382;3606.765560238576;3736.1967177057063;3744.5319335083113;3753.549574051104;3765.079809297251;5545.528630066489;5552.944470555283;5574.602920744119;5580.19087232349;5581.888362232733;3810.218905124498;3813.427985802152;3816.217675376541;3818.4845045918446;3826.1773718650625;3277.573915390984;3319.9502007469905;3844.36567966288;3854.4603755474254;3856.652901238846;4207.789610519461;4393.209222339946;4402.713823601455;4405.493368262627;4425.269830477935;4643.698159619614;5335.3864305604575;5338.115624203888;5341.893162136736;5345.518903298722;1133.7562424078699;1231.4704676988981;1277.182603133995;1300.876416740408;1320.8216890719823;829.6515463505222;844.273924424994;873.0745409865436;880.1330689271063;899.7615631857562;4266.488021437447;4552.908941821145;5009.323741294951;5014.89970200594;5028.64799464034;4068.4710987571425;4994.925076123861;5336.893262134736;5382.461753824587;5390.568754499631;4237.788110594458;4390.780460976938;4790.616750660009;4830.95169048305;4954.331165292711;2719.551274039761;2819.4924913515356;2822.788290878192;2855.728705772948;2856.571913873191;5217.895642087137;5576.187738984846;5596.3284405871555;5603.10350343943;5638.407967163471;4593.47175074864;4875.68308059975;5176.767045482962;5268.710220937885;5563.777448902079;4520.2089634314525;4763.30932014849;5342.252084708066;5575.972120139373;5610.186522954149;4567.680262381601;4764.8332308369045;5028.622853285945;5375.381831089475;5383.5423988961375;4733.455652599995;5315.810513691754;5324.920126198032;5343.572514198777;5357.464253574612;5200.61959726181;5205.557527610195;5594.049011668108;5619.606627536034;5655.632383895034;3316.7346612270476;3318.303156337196;3318.937939859222;3702.9444558331106;3761.5298087739034;5461.5357694596405;5471.23402723627;5476.342838859362;5488.286522752017;5494.313210848644;4641.468315455824;5327.706976116351;5334.973325133349;5335.973320133375;5355.143177091647;4783.545563171493;5300.6024548158275;5319.38827034896;5334.75993580282;5346.331708536433;4986.301917731448;5203.297554830049;5218.817341393033;5241.659292145999;5247.685138891678;4830.372051633265;5212.739363031757;5388.811391601275;5397.78408863641;5409.94422119592;4817.964137710409;5283.39240987276;5297.198952356217;5307.256984022163;5334.106392230858 02:30:50 INFO - PROCESS | 1662 | |5;yui.html;704.4610872682396;706.6007705646309;729.6096588325247;763.9083310002798;812.9471584347015;15659.451919182817;15900.522984310475;15979.520614381558;15980.92009539952;16003.759943600855;14306.856931430682;14416.279186040681;14427.062240954358;14436.206008669518;14448.855511444883;650.8015055408099;677.9423748981343;810.1857633078747;821.9219174178459;844.6325848255998;840.4327079221532;849.5072857742495;850.5364576305928;851.7529916324258;855.4012191465968;906.3429013964889;906.4108329585756;907.1790030022839;907.3603109807569;908.8773015642903;901.4636291406603;901.7745563609097;902.255639097747;902.5171533229757;903.3766700976371;510.76249543961865;530.2708775433779;631.3370960491511;652.1652285075112;661.0843981086182;14816;14848.62878428048;14851.702965940729;14855.405783768636;14861.92569037148;13224.933875330658;13892.722145557032;13952.720945581032;13974;14360.066595671247;12231.877681223252;12610.243385396905;12642.367881605884;12733.808992865115;13060.477580896753;4484.461864576239;4512.458504979423;4526.207913615104;4578.35902973584;4612.515685852971;95739;110161.44919275433;112131.43934280277;112240.4387978063;112440;10963;11187.272827266203;11253.324800511995;11257.380844053656;11503.942480287546;21968.121275149133;22014.229501967355;22016.77983220147;22028.374041451156;22058.669119963215;7859.331956783737;8080.030396352476;8248.010238771385;8254.958725206336;8258.256756891788;4187.769672668032;4259;4380.102079073782;4468.061707041493;4469.7318160910445;3535.487354333607;3611.331903597843;3673.081729567608;3674.2835147146584;3675.5773086094905;668.3450218785617;676.1683129750435;680.9216940051957;681.7307163670323;682.4983637026781;533.3280067115385;603.2158194347419;656.4540507105793;657.3262406033758;713.5682911838367;9079.863802042975;9323.25413966881;9468.763280917894;9520.23838092951;9689.563969621384;557.7462254674114;591.8549955260988;670.7082419147687;675.5541342713785;682.8600136971909;4462.776861156929;4575.405197324327;4608.5161058088415;4646.95353046472;4712.151812673752;81268.18731812724;83370.58314708387;83464.74802877962;93735.06264937263;147827.26086369716;85911.5704421474;119569.8043019576;122419.38790305992;123338;125899;97326.51336743271;102499.48750256201;103035.48482257691;104989;107954;8526.488410695378;9632.90367096348;9783.168430683472;9811.509424528602;9875.55559999816;43290.13419731651;49219.76950576264;55253.44746552483;57737.71131144317;58306;46072.308915365626;53481;73350.63324683343;74245.62877185796;90176;12430.067744919348;12864.549740759028;12920.353982301036;13041.217526948414;13617.591472255848;14485.565433037025;14945.476908308157;14992.175430351435;15020.09879407239;15214.771778423554;13313.201207927556;13640.317984100557;13702.520411785938;13777.931110344383;13847.584572462842;13242;13608.251546165056;13723.45106195741;14054.086484378482;14204.715905681622;11579.652610421701;13379.567990779286;13395.12931659439;13571.86428135706;13841.446342146604;10922;11080.722981925193;11261.831072533754;11585.942070289932;11711.590094346657;11061.557537698402;11098.621956594026;11215.383153926286;11373.943130284295;11419.37193454368 02:30:50 INFO - PROCESS | 1662 | __end_tp_report 02:30:50 INFO - PROCESS | 1662 | __start_cc_report 02:30:50 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1414 02:30:50 INFO - PROCESS | 1662 | __end_cc_report 02:30:50 INFO - PROCESS | 1662 | __startTimestamp1475314250621__endTimestamp 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | ------- Summary: start ------- 02:30:50 INFO - PROCESS | 1662 | Number of tests: 6 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5531.11 Median:5654.43 stddev:600.53 (10.6%) stddev-sans-first:575.18 02:30:50 INFO - PROCESS | 1662 | Values: 3253.7 3271.6 3279.2 3316.3 3317.5 5380.8 5434.6 5485.9 5503.2 5520.7 5437.3 5479.3 5515.1 5534.3 5571.4 4974.1 4984.0 5014.7 5045.3 5053.0 5064.3 5130.8 5144.7 5149.9 5192.9 4992.8 5112.2 5113.9 5115.0 5197.6 5023.9 5071.7 5079.2 5083.8 5090.5 4940.7 4977.6 5009.6 5009.8 5030.1 5434.2 5478.3 5505.7 5511.2 5514.8 5918.9 5943.7 5945.3 5955.3 5966.2 5862.3 5865.1 5889.6 5922.3 5979.4 5545.1 5548.2 5551.1 5558.7 5558.8 6186.9 6188.5 6193.5 6208.3 6210.5 5672.9 5710.0 5732.4 5787.7 5865.4 5843.1 5891.9 5917.0 5937.6 5938.9 5835.3 5839.4 5843.8 5852.4 5853.2 5787.9 5872.3 5872.5 5900.9 5938.9 4762.9 4769.1 4770.5 4771.6 4818.5 4222.3 4268.7 4294.0 4335.9 4341.2 5487.0 5513.5 5513.9 5526.3 5571.1 6054.2 6068.5 6082.8 6103.5 6124.3 5555.8 5558.5 5562.2 5563.4 5572.7 5119.2 5179.5 5184.0 5209.9 5219.0 6160.6 6182.2 6233.2 6292.8 6305.2 6108.7 6109.2 6114.0 6121.1 6137.5 5864.4 6239.0 6292.2 6308.4 6312.7 5649.4 5714.2 5868.3 5895.1 5899.0 6096.3 6104.1 6107.2 6114.4 6126.4 5751.1 5833.4 5847.4 5858.2 5879.6 5670.6 5732.5 5737.8 5819.6 5822.4 5520.9 5606.1 5654.4 5662.8 5664.2 5731.8 5732.6 5736.7 5745.6 5752.9 5562.9 5608.8 5631.6 5644.9 5706.4 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:63969.10 Median:40128.70 stddev:134736.75 (335.8%) stddev-sans-first:135030.33 02:30:50 INFO - PROCESS | 1662 | Values: 455.3 517.7 533.3 552.0 559.8 25665.6 32350.2 32447.4 32488.5 32505.0 42412.0 43255.0 43337.0 45324.3 45410.5 9839.5 9987.4 9995.5 10008.6 10015.0 9756.0 9847.0 9856.7 9864.6 9867.6 9815.1 9856.2 9872.3 9882.5 9929.6 9746.4 9816.4 9824.7 9831.8 9832.3 9802.7 9869.5 9874.9 9878.3 9881.3 39969.0 40167.8 40244.6 40282.8 40398.8 3198.4 3251.8 3339.2 3408.8 3634.3 29866.0 30632.5 31381.8 31495.5 31503.1 3014.9 3126.9 3171.6 3246.9 3308.6 465943.0 466881.0 468834.0 468859.7 472737.0 57981.7 58131.0 58225.7 58708.1 58723.7 60656.0 60699.7 60756.5 60844.0 60908.1 15727.3 15929.7 15969.1 15984.0 15997.4 10405.7 11826.5 12333.4 12375.7 12407.7 15493.9 16125.4 16147.6 16196.5 16281.0 5971.9 7005.6 7033.2 7082.4 7159.4 879.4 975.2 985.9 988.7 1020.7 26209.0 29259.6 29409.0 29680.3 30076.7 818.7 972.3 1003.2 1020.0 1064.6 14621.9 15080.5 15238.2 15404.9 15530.7 600238.0 602121.0 605156.0 605497.0 608524.0 56260.7 56644.0 56680.4 56689.7 56850.7 1206.4 1245.5 1313.5 1352.9 1367.4 23938.0 28668.1 30139.7 30141.1 31363.5 48793.0 49452.3 49469.3 49506.8 49522.3 423888.0 426631.0 427046.0 427191.0 427421.0 25639.0 26228.2 26270.7 27119.7 39066.1 45831.8 46211.8 46434.3 47055.8 47420.3 38528.8 38609.6 38843.8 40089.6 40705.8 36687.4 38177.0 38383.8 39003.6 39190.6 36721.4 36834.6 37580.4 37826.4 38131.2 32467.5 34395.5 34405.0 34420.0 34443.8 22519.7 23649.5 24822.5 25299.4 25313.7 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34169.87 Median:14704.00 stddev:98785.06 (671.8%) stddev-sans-first:99029.53 02:30:50 INFO - PROCESS | 1662 | Values: 344.9 368.1 369.8 375.9 396.1 15177.1 15237.1 16733.4 21135.8 21174.9 19011.5 20070.8 20680.4 20746.4 20944.7 5950.1 5969.0 6275.3 6282.9 6290.0 5996.0 6174.0 6182.5 6184.4 6195.9 4209.1 4426.6 6478.2 6823.9 6882.6 5802.4 6618.9 6794.5 6885.4 6887.7 5793.9 5956.2 5957.6 5961.9 5964.9 24586.0 26123.6 26126.9 26154.4 26509.9 2266.0 2372.2 2417.4 2506.8 2898.1 12411.4 15428.8 15478.4 16083.4 16159.4 1810.3 1827.2 1851.9 1907.1 1950.6 104105.0 118195.4 118690.0 119270.0 119371.0 8881.9 10522.0 10933.5 10962.7 11092.4 13563.0 13576.9 13616.1 13673.2 13677.1 8341.8 8375.3 8448.4 8516.2 8518.5 8433.3 8437.2 8461.7 8591.1 8665.7 6515.5 6634.6 6637.7 6639.3 6686.4 1791.4 1828.2 1831.9 1832.3 1837.4 372.0 832.5 973.2 993.0 1025.0 11179.3 11664.6 12016.6 12053.0 12057.9 422.7 425.3 427.1 428.4 440.4 5904.7 5968.5 6168.2 6179.4 6183.9 533624.0 592155.0 603377.0 613882.0 614514.0 121326.0 121946.4 125140.4 127852.0 134129.3 815.6 989.8 1009.9 1015.5 1025.3 15068.3 17977.8 19729.4 19992.9 20069.3 37999.4 48260.0 52647.0 52722.9 53164.7 67894.0 71645.0 72010.9 72140.0 74363.0 7901.3 7915.2 8109.0 8203.9 8324.2 7445.7 12754.7 12849.0 13221.2 13355.5 11067.0 11350.0 11474.7 11499.4 11538.4 11021.4 11128.4 11412.9 11425.8 11429.7 11229.4 11379.9 11453.4 11659.9 11774.6 7982.5 8028.7 8072.2 8191.4 8244.5 10398.4 10813.5 11096.9 11190.8 11695.0 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5565.45 Median:5238.61 stddev:3071.78 (58.6%) stddev-sans-first:3065.33 02:30:50 INFO - PROCESS | 1662 | Values: 1516.6 1532.3 1734.9 1836.1 1903.2 8277.4 8771.8 8772.4 8778.4 8796.4 7751.5 7945.8 7953.5 8445.8 8565.4 2309.0 2343.2 2344.3 2344.3 2344.8 2302.9 2332.6 2339.6 2340.8 2342.0 2273.1 2283.5 2288.7 2294.3 2305.4 2311.1 2313.6 2317.4 2319.2 2320.5 2189.5 2214.8 2216.3 2219.3 2223.0 8709.2 8776.0 8823.6 8828.1 8831.8 5095.2 5249.5 5252.1 5258.0 5270.8 2878.4 3476.9 3479.5 3622.6 3631.5 1074.4 1075.9 1078.3 1097.0 1099.0 11162.9 11333.5 11353.0 11361.0 11425.3 4486.8 4510.4 4676.2 4681.6 4720.8 9002.9 9112.0 9741.6 9754.1 9774.6 5227.7 5249.7 5250.8 5251.2 5253.2 4287.1 4289.2 4575.6 4791.5 4807.8 3782.1 3786.8 3920.5 3922.7 3938.5 1079.9 1209.9 1236.7 1238.0 1256.6 824.5 845.0 898.8 899.4 931.6 5407.4 5723.4 5779.4 6011.7 6045.4 927.9 933.9 943.9 949.0 990.6 3905.4 3982.1 4047.9 4055.0 4110.7 9488.7 9959.9 10108.0 10111.2 10133.6 9844.1 10054.0 10067.2 10068.4 10072.4 7899.2 8072.2 8146.8 8157.7 8168.3 5666.3 5832.8 5918.9 5972.3 6011.9 7680.9 9792.4 9905.3 9906.7 9946.4 9527.4 9940.9 9943.1 10022.3 10087.5 6602.1 6798.9 7369.1 7544.7 7557.4 7446.7 8000.1 8141.5 8311.1 8389.4 7292.3 7409.7 7435.4 7448.4 7565.4 7273.4 7286.6 7304.2 7312.5 7353.5 6893.6 6894.0 6995.1 7000.2 7003.5 4269.3 4937.3 4984.9 5020.9 5102.6 3884.9 4930.4 5040.5 5091.6 5094.9 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4289.25 Median:4804.29 stddev:1305.33 (27.2%) stddev-sans-first:1274.04 02:30:50 INFO - PROCESS | 1662 | Values: 291.7 294.4 303.9 306.3 307.2 4090.1 4406.6 4483.9 4955.2 5098.0 4098.9 4883.9 5001.7 5016.6 5021.8 3886.3 3933.5 3942.5 3959.6 3965.8 3698.9 3931.2 3955.8 3956.1 3970.2 3017.0 3251.7 3777.6 3892.6 3926.4 3219.3 3352.4 3783.5 3882.3 3893.9 3375.5 3406.4 3408.7 3414.1 3417.4 3835.8 4011.0 4947.3 4977.0 4996.2 3735.9 4386.6 4443.7 4447.3 4467.0 4839.8 5305.5 5306.5 5326.3 5366.2 3606.8 3736.2 3744.5 3753.5 3765.1 5545.5 5552.9 5574.6 5580.2 5581.9 3810.2 3813.4 3816.2 3818.5 3826.2 3277.6 3320.0 3844.4 3854.5 3856.7 4207.8 4393.2 4402.7 4405.5 4425.3 4643.7 5335.4 5338.1 5341.9 5345.5 1133.8 1231.5 1277.2 1300.9 1320.8 829.7 844.3 873.1 880.1 899.8 4266.5 4552.9 5009.3 5014.9 5028.6 4068.5 4994.9 5336.9 5382.5 5390.6 4237.8 4390.8 4790.6 4831.0 4954.3 2719.6 2819.5 2822.8 2855.7 2856.6 5217.9 5576.2 5596.3 5603.1 5638.4 4593.5 4875.7 5176.8 5268.7 5563.8 4520.2 4763.3 5342.3 5576.0 5610.2 4567.7 4764.8 5028.6 5375.4 5383.5 4733.5 5315.8 5324.9 5343.6 5357.5 5200.6 5205.6 5594.0 5619.6 5655.6 3316.7 3318.3 3318.9 3702.9 3761.5 5461.5 5471.2 5476.3 5488.3 5494.3 4641.5 5327.7 5335.0 5336.0 5355.1 4783.5 5300.6 5319.4 5334.8 5346.3 4986.3 5203.3 5218.8 5241.7 5247.7 4830.4 5212.7 5388.8 5397.8 5409.9 4818.0 5283.4 5297.2 5307.3 5334.1 02:30:50 INFO - PROCESS | 1662 | 02:30:50 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:22005.15 Median:23835.12 stddev:33261.52 (139.5%) stddev-sans-first:33316.38 02:30:50 INFO - PROCESS | 1662 | Values: 704.5 706.6 729.6 763.9 812.9 15659.5 15900.5 15979.5 15980.9 16003.8 14306.9 14416.3 14427.1 14436.2 14448.9 650.8 677.9 810.2 821.9 844.6 840.4 849.5 850.5 851.8 855.4 906.3 906.4 907.2 907.4 908.9 901.5 901.8 902.3 902.5 903.4 510.8 530.3 631.3 652.2 661.1 14816.0 14848.6 14851.7 14855.4 14861.9 13224.9 13892.7 13952.7 13974.0 14360.1 12231.9 12610.2 12642.4 12733.8 13060.5 4484.5 4512.5 4526.2 4578.4 4612.5 95739.0 110161.4 112131.4 112240.4 112440.0 10963.0 11187.3 11253.3 11257.4 11503.9 21968.1 22014.2 22016.8 22028.4 22058.7 7859.3 8080.0 8248.0 8255.0 8258.3 4187.8 4259.0 4380.1 4468.1 4469.7 3535.5 3611.3 3673.1 3674.3 3675.6 668.3 676.2 680.9 681.7 682.5 533.3 603.2 656.5 657.3 713.6 9079.9 9323.3 9468.8 9520.2 9689.6 557.7 591.9 670.7 675.6 682.9 4462.8 4575.4 4608.5 4647.0 4712.2 81268.2 83370.6 83464.7 93735.1 147827.3 85911.6 119569.8 122419.4 123338.0 125899.0 97326.5 102499.5 103035.5 104989.0 107954.0 8526.5 9632.9 9783.2 9811.5 9875.6 43290.1 49219.8 55253.4 57737.7 58306.0 46072.3 53481.0 73350.6 74245.6 90176.0 12430.1 12864.5 12920.4 13041.2 13617.6 14485.6 14945.5 14992.2 15020.1 15214.8 13313.2 13640.3 13702.5 13777.9 13847.6 13242.0 13608.3 13723.5 14054.1 14204.7 11579.7 13379.6 13395.1 13571.9 13841.4 10922.0 11080.7 11261.8 11585.9 11711.6 11061.6 11098.6 11215.4 11373.9 11419.4 02:30:50 INFO - PROCESS | 1662 | -------- Summary: end -------- 02:30:50 INFO - PROCESS | 1662 | 02:30:51 INFO - TEST-INFO | 1662: exit 0 02:30:51 INFO - TEST-OK | dromaeo_css | took 1120147ms 02:30:51 INFO - TEST-START | kraken 02:30:51 INFO - operating with platform_type : mac_ 02:30:51 INFO - Initialising browser for kraken test... 02:30:51 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpU5S_N6/profile http://localhost:49274/getInfo.html) 02:30:53 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 02:30:53 INFO - PROCESS | 1669 | colorDepth:24 02:30:53 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 02:30:53 INFO - PROCESS | 1669 | __metrics 02:30:53 INFO - PROCESS | 1669 | 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" 02:30:53 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:30:54 INFO - TEST-INFO | 1669: exit 0 02:30:54 INFO - Browser initialized. 02:30:54 INFO - Running cycle 1/1 for kraken test... 02:30:54 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpU5S_N6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:31:06 INFO - PROCESS | 1670 | RSS: Main: 236249088 02:31:06 INFO - PROCESS | 1670 | 02:31:09 INFO - PROCESS | 1670 | 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) 02:31:10 INFO - PROCESS | 1670 | RSS: Main: 294846464 02:31:10 INFO - PROCESS | 1670 | 02:31:13 INFO - PROCESS | 1670 | 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) 02:31:13 INFO - PROCESS | 1670 | RSS: Main: 293814272 02:31:13 INFO - PROCESS | 1670 | 02:31:17 INFO - PROCESS | 1670 | 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) 02:31:17 INFO - PROCESS | 1670 | RSS: Main: 384602112 02:31:17 INFO - PROCESS | 1670 | 02:31:19 INFO - PROCESS | 1670 | 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) 02:31:20 INFO - PROCESS | 1670 | RSS: Main: 285491200 02:31:20 INFO - PROCESS | 1670 | 02:31:22 INFO - PROCESS | 1670 | 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) 02:31:22 INFO - PROCESS | 1670 | RSS: Main: 290660352 02:31:22 INFO - PROCESS | 1670 | 02:31:25 INFO - PROCESS | 1670 | 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) 02:31:26 INFO - PROCESS | 1670 | RSS: Main: 327327744 02:31:26 INFO - PROCESS | 1670 | 02:31:29 INFO - PROCESS | 1670 | 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) 02:31:29 INFO - PROCESS | 1670 | RSS: Main: 330665984 02:31:29 INFO - PROCESS | 1670 | 02:31:32 INFO - PROCESS | 1670 | 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) 02:31:33 INFO - PROCESS | 1670 | RSS: Main: 326119424 02:31:33 INFO - PROCESS | 1670 | 02:31:35 INFO - PROCESS | 1670 | 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) 02:31:35 INFO - PROCESS | 1670 | RSS: Main: 304234496 02:31:35 INFO - PROCESS | 1670 | 02:31:37 INFO - PROCESS | 1670 | 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) 02:31:38 INFO - PROCESS | 1670 | RSS: Main: 307359744 02:31:38 INFO - PROCESS | 1670 | 02:31:40 INFO - PROCESS | 1670 | 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) 02:31:41 INFO - PROCESS | 1670 | RSS: Main: 313696256 02:31:41 INFO - PROCESS | 1670 | 02:31:44 INFO - PROCESS | 1670 | 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) 02:31:44 INFO - PROCESS | 1670 | RSS: Main: 317788160 02:31:44 INFO - PROCESS | 1670 | 02:31:47 INFO - PROCESS | 1670 | 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) 02:31:48 INFO - PROCESS | 1670 | RSS: Main: 319303680 02:31:48 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | 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) 02:31:50 INFO - PROCESS | 1670 | RSS: Main: 363020288 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | __start_tp_report 02:31:50 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 02:31:50 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 02:31:50 INFO - PROCESS | 1670 | |i|pagename|runs| 02:31:50 INFO - PROCESS | 1670 | |0;ai-astar;113;114;114;113;112;115;124;166;137;114 02:31:50 INFO - PROCESS | 1670 | |1;audio-beat-detection;150;112;117;152;119;145;145;114;164;142 02:31:50 INFO - PROCESS | 1670 | |2;audio-dft;186;181;184;186;182;183;183;184;184;181 02:31:50 INFO - PROCESS | 1670 | |3;audio-fft;77;59;60;90;61;60;59;60;82;58 02:31:50 INFO - PROCESS | 1670 | |4;audio-oscillator;83;70;91;70;69;69;92;67;69;90 02:31:50 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;91;88;92;92;91;87;92;89;92;87 02:31:50 INFO - PROCESS | 1670 | |6;imaging-darkroom;96;95;96;96;95;95;97;105;148;150 02:31:50 INFO - PROCESS | 1670 | |7;imaging-desaturate;86;85;84;85;86;86;86;87;84;85 02:31:50 INFO - PROCESS | 1670 | |8;json-parse-financial;43;44;78;88;86;54;55;56;56;54 02:31:50 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;65;74;67;76;72;68;79;104;103;89 02:31:50 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;87;90;89;89;87;89;90;85;91;112 02:31:50 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;148;148;156;160;146;143;156;176;156;153 02:31:50 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;170;171;173;172;174;173;173;173;173;174 02:31:50 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;69;70;69;70;69;69;68;69;70;67 02:31:50 INFO - PROCESS | 1670 | __end_tp_report 02:31:50 INFO - PROCESS | 1670 | __start_cc_report 02:31:50 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1973 02:31:50 INFO - PROCESS | 1670 | __end_cc_report 02:31:50 INFO - PROCESS | 1670 | __startTimestamp1475314310402__endTimestamp 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | ------- Summary: start ------- 02:31:50 INFO - PROCESS | 1670 | Number of tests: 14 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:122.20 Median:114.50 stddev:17.22 (15.0%) stddev-sans-first:17.94 02:31:50 INFO - PROCESS | 1670 | Values: 113.0 114.0 114.0 113.0 112.0 115.0 124.0 166.0 137.0 114.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:145.00 stddev:18.69 (12.9%) stddev-sans-first:19.13 02:31:50 INFO - PROCESS | 1670 | Values: 150.0 112.0 117.0 152.0 119.0 145.0 145.0 114.0 164.0 142.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.78 (1.0%) stddev-sans-first:1.62 02:31:50 INFO - PROCESS | 1670 | Values: 186.0 181.0 184.0 186.0 182.0 183.0 183.0 184.0 184.0 181.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:11.76 (19.4%) stddev-sans-first:11.85 02:31:50 INFO - PROCESS | 1670 | Values: 77.0 59.0 60.0 90.0 61.0 60.0 59.0 60.0 82.0 58.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:76.50 stddev:10.62 (13.9%) stddev-sans-first:11.05 02:31:50 INFO - PROCESS | 1670 | Values: 83.0 70.0 91.0 70.0 69.0 69.0 92.0 67.0 69.0 90.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:91.50 stddev:2.13 (2.3%) stddev-sans-first:2.24 02:31:50 INFO - PROCESS | 1670 | Values: 91.0 88.0 92.0 92.0 91.0 87.0 92.0 89.0 92.0 87.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:95.50 stddev:22.18 (23.2%) stddev-sans-first:23.15 02:31:50 INFO - PROCESS | 1670 | Values: 96.0 95.0 96.0 96.0 95.0 95.0 97.0 105.0 148.0 150.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 02:31:50 INFO - PROCESS | 1670 | Values: 86.0 85.0 84.0 85.0 86.0 86.0 86.0 87.0 84.0 85.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:56.00 stddev:16.46 (29.4%) stddev-sans-first:16.06 02:31:50 INFO - PROCESS | 1670 | Values: 43.0 44.0 78.0 88.0 86.0 54.0 55.0 56.0 56.0 54.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:79.70 Median:73.00 stddev:14.30 (19.6%) stddev-sans-first:14.14 02:31:50 INFO - PROCESS | 1670 | Values: 65.0 74.0 67.0 76.0 72.0 68.0 79.0 104.0 103.0 89.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:89.00 stddev:7.62 (8.6%) stddev-sans-first:7.95 02:31:50 INFO - PROCESS | 1670 | Values: 87.0 90.0 89.0 89.0 87.0 89.0 90.0 85.0 91.0 112.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:156.00 stddev:9.37 (6.0%) stddev-sans-first:9.66 02:31:50 INFO - PROCESS | 1670 | Values: 148.0 148.0 156.0 160.0 146.0 143.0 156.0 176.0 156.0 153.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.00 stddev:1.26 (0.7%) stddev-sans-first:0.93 02:31:50 INFO - PROCESS | 1670 | Values: 170.0 171.0 173.0 172.0 174.0 173.0 173.0 173.0 173.0 174.0 02:31:50 INFO - PROCESS | 1670 | 02:31:50 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 02:31:50 INFO - PROCESS | 1670 | Values: 69.0 70.0 69.0 70.0 69.0 69.0 68.0 69.0 70.0 67.0 02:31:50 INFO - PROCESS | 1670 | -------- Summary: end -------- 02:31:50 INFO - PROCESS | 1670 | 02:31:51 INFO - TEST-INFO | 1670: exit 0 02:31:51 INFO - TEST-OK | kraken | took 59969ms 02:31:51 INFO - SUITE-END | took 1180s 02:31:51 INFO - Completed test suite (00:19:40) 02:31:51 INFO - javascript benchmark 02:31:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3253.7234335081525, 3271.5910511186103, 3279.212988882669, 3316.3367326534667, 3317.5023746438033, 5380.811671591495, 5434.565234781218, 5485.890282194354, 5503.229547863302, 5520.668759874406, 5437.265969094171, 5479.315085614298, 5515.09001014833, 5534.280543529336, 5571.359293681217, 4974.129527332702, 4983.975080124595, 5014.674046186986, 5045.318881950945, 5052.9747351263195, 5064.3163172971545, 5130.820421285256, 5144.691318520882, 5149.871253218681, 5192.935448233122, 4992.800287988476, 5112.207607820794, 5113.948860511385, 5114.974425127869, 5197.584193264549, 5023.874403139914, 5071.670341427795, 5079.23811428285, 5083.79664813407, 5090.516400941905, 4940.728259945701, 4977.552020318189, 5009.599232061426, 5009.849704508871, 5030.0694371541385, 5434.157705555645, 5478.315210598676, 5505.6696598204235, 5511.173324001392, 5514.834554963358, 5918.881622367571, 5943.673097979609, 5945.31628862682, 5955.315138759055, 5966.224390829208, 5862.296524417055, 5865.0615901455985, 5889.587728858982, 5922.31893332268, 5979.402059794029, 5545.112781954908, 5548.195511650788, 5551.084071128299, 5558.722063896789, 5558.777648893999, 6186.938130618726, 6188.504919606421, 6193.473554747807, 6208.317085120633, 6210.472109870711, 5672.8865422691315, 5710.029295019856, 5732.426757324318, 5787.681677507778, 5865.442782935615, 5843.0651095825315, 5891.911621325684, 5916.970415147896, 5937.554683398763, 5938.910916336258, 5835.299764028344, 5839.416058394211, 5843.766249349978, 5852.4440178183, 5853.209816674779, 5787.884242315215, 5872.324682661549, 5872.471477567039, 5900.911486327708, 5938.940610593839, 4762.904741905142, 4769.093872164278, 4770.499097594703, 4771.570558649738, 4818.518148185153, 4222.303319952235, 4268.743875367488, 4294.0, 4335.913281734348, 4341.153475072394, 5487.039768040657, 5513.531349835309, 5513.889722205614, 5526.336839579276, 5571.1086226203615, 6054.212952316258, 6068.514518838482, 6082.787102451482, 6103.450689437972, 6124.265088189446, 5555.77776888928, 5558.499735023867, 5562.221289019541, 5563.360213575491, 5572.665640061609, 5119.206522988943, 5179.507946745053, 5184.015037142931, 5209.947900521022, 5219.0, 6160.599561028519, 6182.196314479089, 6233.2208473940755, 6292.779752708633, 6305.243370795534, 6108.664023478574, 6109.175261339662, 6113.969430152821, 6121.112438696453, 6137.508999280136, 5864.413558644102, 6238.968805155945, 6292.1505596743855, 6308.400701933309, 6312.684365781784, 5649.350324712546, 5714.171445140514, 5868.3251426086545, 5895.115732639967, 5898.970505147447, 6096.254562767794, 6104.125697713593, 6107.188815080102, 6114.388610881663, 6126.387361263838, 5751.079827227623, 5833.358330583547, 5847.38602446737, 5858.209141765978, 5879.559033072451, 5670.631408958405, 5732.5413966881715, 5737.770489180386, 5819.592628515963, 5822.417758224144, 5520.889582208413, 5606.103023516218, 5654.434556544313, 5662.8301150965535, 5664.178694089416, 5731.799387021434, 5732.598718089694, 5736.741846616996, 5745.597808153556, 5752.856178595402, 5562.860928476659, 5608.803691870765, 5631.605787594829, 5644.85887852807, 5706.400827913009], "value": 5493.103665392359, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.28747510146616, 517.7204309672776, 533.2960492150363, 552.0229194326046, 559.8208573256557, 25665.61501577478, 32350.19124521888, 32447.351052978927, 32488.512672309906, 32505.0, 42412.0, 43255.0, 43337.0, 45324.320135197915, 45410.54589454104, 9839.50802459876, 9987.350822196535, 9995.500224988722, 10008.649697260596, 10015.0, 9756.0, 9846.950765246163, 9856.655017074369, 9864.556094975744, 9867.555959981783, 9815.116639502443, 9856.162226210745, 9872.308938374317, 9882.505874706272, 9929.553170107327, 9746.366486178411, 9816.361936474106, 9824.705258842174, 9831.754206144868, 9832.262580306506, 9802.656907008291, 9869.457179855106, 9874.901250987541, 9878.308518403715, 9881.25890558211, 39969.0, 40167.7991610043, 40244.597554024374, 40282.79858600718, 40398.798006010074, 3198.3603279344225, 3251.7886337387995, 3339.1819004343847, 3408.829558522064, 3634.309481198564, 29865.95469158569, 30632.54051189234, 31381.8430907844, 31495.527567086512, 31503.054908352788, 3014.8643311051055, 3126.9212121817936, 3171.6035495563056, 3246.9188270293193, 3308.553345298378, 465943.0, 466881.0, 468834.0, 468859.6557017227, 472737.0, 57981.71009145011, 58131.0, 58225.70887145622, 58708.11937820936, 58723.70638146782, 60656.0, 60699.69650151721, 60756.48108797227, 60844.0, 60908.086378704356, 15727.292271847795, 15929.681406371808, 15969.121698306471, 15983.961042532199, 15997.360105595879, 10405.739856503647, 11826.467808948446, 12333.444994975247, 12375.69060773488, 12407.689807754878, 15493.922530387275, 16125.419932810693, 16147.596310092342, 16196.514104576881, 16281.0, 5971.850703732354, 7005.649717514104, 7033.191183013917, 7082.394040278909, 7159.355657990805, 879.4239772948729, 975.22956864078, 985.8951139317466, 988.663854289545, 1020.6529779874878, 26209.0, 29259.56110658342, 29409.0, 29680.257993550335, 30076.699233007388, 818.7134502924048, 972.2562239886493, 1003.2174903575223, 1019.9898001020043, 1064.6486659402378, 14621.853781462261, 15080.54758357251, 15238.238088095552, 15404.92297538505, 15530.689386212212, 600238.0, 602121.0, 605156.0, 605497.0, 608524.0, 56260.71869640626, 56644.0, 56680.433195667516, 56689.716551416976, 56850.715746421, 1206.4269472000906, 1245.5453759377763, 1313.5271302330962, 1352.932353382327, 1367.43934986655, 23938.042478300675, 28668.139955801358, 30139.69860301369, 30141.095767127023, 31363.529547057267, 48793.02413951772, 49452.258216126065, 49469.257961131334, 49506.75246623888, 49522.25716614182, 423888.0, 426631.0, 427046.0, 427191.0, 427421.0, 25639.0, 26228.213153605422, 26270.73729262683, 27119.72880271172, 39066.14007898838, 45831.77084114558, 46211.84470388267, 46434.30348544842, 47055.823604410165, 47420.34028808975, 38528.80735596304, 38609.613903861726, 38843.80578097091, 40089.59910400859, 40705.79647101864, 36687.449688255234, 38177.0, 38383.84848454551, 39003.609963899995, 39190.60809391984, 36721.44917826181, 36834.63165368312, 37580.436293456165, 37826.43260351042, 38131.23737525178, 32467.512987305683, 34395.4840677385, 34405.0, 34420.0, 34443.800115979364, 22519.66220506661, 23649.52700945937, 24822.510649361095, 25299.367515812253, 25313.746862531138], "value": 19370.859715259958, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.85343728915205, 368.08530800959613, 369.8132443116226, 375.8966284271826, 396.0731887383522, 15177.089374637515, 15237.085774853502, 16733.414330498435, 21135.788642113614, 21174.89412552935, 19011.524711882208, 20070.799292007076, 20680.37958861233, 20746.377608671726, 20944.685829712565, 5950.107483877431, 5969.0, 6275.309715931244, 6282.905756413657, 6290.0, 5996.0, 6174.043023331368, 6182.505399568024, 6184.443400093991, 6195.876082478348, 4209.137126888981, 4426.62373698236, 6478.153726188038, 6823.863522729543, 6882.621455819928, 5802.4197580241635, 6618.93381066188, 6794.45644348451, 6885.414739747102, 6887.690053947559, 5793.8841223175305, 5956.225690660186, 5957.553183511254, 5961.850953726148, 5964.910526342108, 24586.016559337604, 26123.608145877828, 26126.86936565324, 26154.422975979127, 26509.93960241588, 2266.048259730917, 2372.205311220743, 2417.4319035026756, 2506.8370555913807, 2898.0581311073774, 12411.441485133191, 15428.845711542965, 15478.380864765508, 16083.437079702271, 16159.353625854952, 1810.3482746211353, 1827.2051657529018, 1851.9443916967334, 1907.0655378864396, 1950.619629172312, 104105.0, 118195.4090229552, 118690.0, 119270.0, 119371.0, 8881.866771998426, 10522.0, 10933.453327333602, 10962.671119866416, 11092.389918554396, 13563.0, 13576.86423135756, 13616.114952528253, 13673.247971361472, 13677.110987785762, 8341.83316333682, 8375.32493501302, 8448.408611397266, 8516.233538981522, 8518.531480768617, 8433.325333973267, 8437.240648341678, 8461.661533538712, 8591.140885911358, 8665.696700615448, 6515.543911926215, 6634.635095069721, 6637.701303441357, 6639.302873198341, 6686.431653309523, 1791.4446521578352, 1828.168183476515, 1831.899245541508, 1832.3037245846492, 1837.4303965770655, 372.045702772388, 832.488019867777, 973.1630797514132, 992.9602815887283, 1024.9795004099876, 11179.273347232403, 11664.591739289084, 12016.63950081499, 12053.0, 12057.939710301393, 422.71044334630875, 425.28552032585554, 427.1265262538117, 428.4430240687094, 440.36587314267354, 5904.7342869570975, 5968.4926781224085, 6168.228971378578, 6179.3820617937845, 6183.938160618336, 533624.0, 592155.0, 603377.0, 613882.0, 614514.0, 121326.0, 121946.3902680481, 125140.37429812975, 127852.0, 134129.3293533526, 815.5595978171721, 989.7723523589615, 1009.9091081802526, 1015.5024038221219, 1025.348903446302, 15068.321925513597, 17977.820221798138, 19729.40811775649, 19992.90003550031, 20069.29757458482, 37999.43000854934, 48260.0, 52647.0, 52722.94554108973, 53164.73417632887, 67893.98159027717, 71645.0, 72010.91983620146, 72140.0, 74363.0, 7901.328387086932, 7915.248051435105, 8109.0, 8203.917960820316, 8324.209200125979, 7445.664945077377, 12754.744905102032, 12849.0, 13221.206727596375, 13355.53255636048, 11067.0, 11350.035247003767, 11474.70505898807, 11499.425028748361, 11538.423078846192, 11021.393823339793, 11128.38793866345, 11412.942935285271, 11425.828612570984, 11429.657110286706, 11229.4385280734, 11379.943100284445, 11453.370064646524, 11659.901217780882, 11774.646760597196, 7982.521048737094, 8028.671685211457, 8072.152423995396, 8191.426600137933, 8244.54654993981, 10398.376097434179, 10813.459327033459, 11096.935609576942, 11190.83213751778, 11695.005924496512], "value": 9185.88832301883, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1516.5981015031016, 1532.3257766582703, 1734.9132543372837, 1836.1186630417392, 1903.1626084522818, 8277.420580559356, 8771.780705482359, 8772.429792063527, 8778.385513014091, 8796.428232164904, 7751.457397982143, 7945.841083178348, 7953.483023603461, 8445.831083378329, 8565.357598180146, 2308.9884550577267, 2343.16817529777, 2344.2615576093563, 2344.3201471573307, 2344.753800850912, 2302.873341966191, 2332.568474832153, 2339.6256598944174, 2340.8361414700976, 2341.9648705269433, 2273.068042102738, 2283.54329134173, 2288.7253529576474, 2294.2773026496684, 2305.3775480620225, 2311.0640190722784, 2313.595120853844, 2317.40906068953, 2319.2230602748014, 2320.512692334612, 2189.5073608438133, 2214.8006679398773, 2216.312942987679, 2219.2676416782583, 2223.0, 8709.172628600336, 8775.956120219358, 8823.602937867812, 8828.073052329477, 8831.77920551985, 5095.210242412432, 5249.4750524947585, 5252.080885844962, 5257.973710131463, 5270.841874743725, 2878.352370716572, 3476.9130771730656, 3479.4954731564035, 3622.6377362263825, 3631.509746184258, 1074.355386767941, 1075.8977897099762, 1078.2775540387959, 1097.040089921319, 1098.9725256868562, 11162.944185279022, 11333.489992950337, 11353.0, 11361.0, 11425.31448113116, 4486.798094085774, 4510.436195475566, 4676.223035246585, 4681.602063824613, 4720.787564559603, 9002.86495702565, 9111.954440227757, 9741.610335586638, 9754.0733630305, 9774.609015639437, 5227.738613069331, 5249.7112658804135, 5250.816221432232, 5251.186066159751, 5253.212018197301, 4287.142571485715, 4289.163613095417, 4575.611073058761, 4791.496892826272, 4807.831725889577, 3782.111203867089, 3786.848526058927, 3920.4707364505425, 3922.686185105185, 3938.468306778548, 1079.8704155501232, 1209.8729633388543, 1236.7217376090487, 1237.9814302785464, 1256.5790460195753, 824.4947365287884, 845.0197770586213, 898.766320756595, 899.4198741811495, 931.59475628102, 5407.351117865803, 5723.427657234327, 5779.393163717833, 6011.699415029231, 6045.395460453919, 927.9443233406017, 933.8926023507248, 943.9480828554496, 948.9857652135223, 990.6087095597198, 3905.4183055862422, 3982.067772686177, 4047.8785636430953, 4055.047063939972, 4110.7328023678365, 9488.715338539854, 9959.850602240973, 10107.989201079834, 10111.241656875616, 10133.594656213669, 9844.114029737359, 10054.044865737744, 10067.194624430029, 10068.44623545712, 10072.446015469219, 7899.21007899217, 8072.233137851895, 8146.75559733209, 8157.673693052211, 8168.264856162974, 5666.320041595035, 5832.795852145325, 5918.911216331845, 5972.343042265435, 6011.879762404815, 7680.884786728092, 9792.412455252708, 9905.306628535933, 9906.653267135616, 9946.403215807075, 9527.38072025316, 9940.900590994286, 9943.055409736064, 10022.34854734469, 10087.495625218857, 6602.075709400784, 6798.93201067983, 7369.115706115301, 7544.660490277841, 7557.4331925106935, 7446.664900079401, 8000.079990801132, 8141.51150930946, 8311.085780564012, 8389.412741108064, 7292.34368906801, 7409.740659076906, 7435.442341824277, 7448.441366897613, 7565.356944659766, 7273.418126549757, 7286.562806231643, 7304.19653838088, 7312.5246858954, 7353.485256032026, 6893.586384816927, 6893.965530172317, 6995.125609298838, 7000.159980802337, 7003.54476958996, 4269.295566231537, 4937.333459983, 4984.925226121539, 5020.949790502194, 5102.591792656504, 3884.864029758945, 4930.383702037246, 5040.521150490697, 5091.643584949166, 5094.923576146287], "value": 4496.783161597777, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.7082917082917, 294.4155850636487, 303.9103464477978, 306.2756580686674, 307.1859572133845, 4090.1001779608437, 4406.625436837869, 4483.887902802431, 4955.182394904841, 5098.0, 4098.877033688987, 4883.853484395474, 5001.6998980061135, 5016.598672106232, 5021.849344519661, 3886.261610294049, 3933.547642021161, 3942.5466071401725, 3959.6436320731127, 3965.8017099145072, 3698.9458004468734, 3931.174453364797, 3955.8417663293576, 3956.0703234739813, 3970.2059588082357, 3017.0, 3251.658575849537, 3777.622237776228, 3892.5912779158207, 3926.411038344257, 3219.259570298833, 3352.379809735207, 3783.451399547078, 3882.262370149677, 3893.9415908761393, 3375.476801095834, 3406.352792969353, 3408.744344174197, 3414.146463384154, 3417.4361230396953, 3835.8082095895375, 4011.0, 4947.342640215532, 4977.013791724936, 4996.225585034289, 3735.906602334936, 4386.5962891874615, 4443.666724995606, 4447.332900064984, 4467.039586488921, 4839.782209800533, 5305.549028332596, 5306.469353064701, 5326.334208223972, 5366.195070739382, 3606.765560238576, 3736.1967177057063, 3744.5319335083113, 3753.549574051104, 3765.079809297251, 5545.528630066489, 5552.944470555283, 5574.602920744119, 5580.19087232349, 5581.888362232733, 3810.218905124498, 3813.427985802152, 3816.217675376541, 3818.4845045918446, 3826.1773718650625, 3277.573915390984, 3319.9502007469905, 3844.36567966288, 3854.4603755474254, 3856.652901238846, 4207.789610519461, 4393.209222339946, 4402.713823601455, 4405.493368262627, 4425.269830477935, 4643.698159619614, 5335.3864305604575, 5338.115624203888, 5341.893162136736, 5345.518903298722, 1133.7562424078699, 1231.4704676988981, 1277.182603133995, 1300.876416740408, 1320.8216890719823, 829.6515463505222, 844.273924424994, 873.0745409865436, 880.1330689271063, 899.7615631857562, 4266.488021437447, 4552.908941821145, 5009.323741294951, 5014.89970200594, 5028.64799464034, 4068.4710987571425, 4994.925076123861, 5336.893262134736, 5382.461753824587, 5390.568754499631, 4237.788110594458, 4390.780460976938, 4790.616750660009, 4830.95169048305, 4954.331165292711, 2719.551274039761, 2819.4924913515356, 2822.788290878192, 2855.728705772948, 2856.571913873191, 5217.895642087137, 5576.187738984846, 5596.3284405871555, 5603.10350343943, 5638.407967163471, 4593.47175074864, 4875.68308059975, 5176.767045482962, 5268.710220937885, 5563.777448902079, 4520.2089634314525, 4763.30932014849, 5342.252084708066, 5575.972120139373, 5610.186522954149, 4567.680262381601, 4764.8332308369045, 5028.622853285945, 5375.381831089475, 5383.5423988961375, 4733.455652599995, 5315.810513691754, 5324.920126198032, 5343.572514198777, 5357.464253574612, 5200.61959726181, 5205.557527610195, 5594.049011668108, 5619.606627536034, 5655.632383895034, 3316.7346612270476, 3318.303156337196, 3318.937939859222, 3702.9444558331106, 3761.5298087739034, 5461.5357694596405, 5471.23402723627, 5476.342838859362, 5488.286522752017, 5494.313210848644, 4641.468315455824, 5327.706976116351, 5334.973325133349, 5335.973320133375, 5355.143177091647, 4783.545563171493, 5300.6024548158275, 5319.38827034896, 5334.75993580282, 5346.331708536433, 4986.301917731448, 5203.297554830049, 5218.817341393033, 5241.659292145999, 5247.685138891678, 4830.372051633265, 5212.739363031757, 5388.811391601275, 5397.78408863641, 5409.94422119592, 4817.964137710409, 5283.39240987276, 5297.198952356217, 5307.256984022163, 5334.106392230858], "value": 3879.1365738048044, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.4610872682396, 706.6007705646309, 729.6096588325247, 763.9083310002798, 812.9471584347015, 15659.451919182817, 15900.522984310475, 15979.520614381558, 15980.92009539952, 16003.759943600855, 14306.856931430682, 14416.279186040681, 14427.062240954358, 14436.206008669518, 14448.855511444883, 650.8015055408099, 677.9423748981343, 810.1857633078747, 821.9219174178459, 844.6325848255998, 840.4327079221532, 849.5072857742495, 850.5364576305928, 851.7529916324258, 855.4012191465968, 906.3429013964889, 906.4108329585756, 907.1790030022839, 907.3603109807569, 908.8773015642903, 901.4636291406603, 901.7745563609097, 902.255639097747, 902.5171533229757, 903.3766700976371, 510.76249543961865, 530.2708775433779, 631.3370960491511, 652.1652285075112, 661.0843981086182, 14816.0, 14848.62878428048, 14851.702965940729, 14855.405783768636, 14861.92569037148, 13224.933875330658, 13892.722145557032, 13952.720945581032, 13974.0, 14360.066595671247, 12231.877681223252, 12610.243385396905, 12642.367881605884, 12733.808992865115, 13060.477580896753, 4484.461864576239, 4512.458504979423, 4526.207913615104, 4578.35902973584, 4612.515685852971, 95739.0, 110161.44919275433, 112131.43934280277, 112240.4387978063, 112440.0, 10963.0, 11187.272827266203, 11253.324800511995, 11257.380844053656, 11503.942480287546, 21968.121275149133, 22014.229501967355, 22016.77983220147, 22028.374041451156, 22058.669119963215, 7859.331956783737, 8080.030396352476, 8248.010238771385, 8254.958725206336, 8258.256756891788, 4187.769672668032, 4259.0, 4380.102079073782, 4468.061707041493, 4469.7318160910445, 3535.487354333607, 3611.331903597843, 3673.081729567608, 3674.2835147146584, 3675.5773086094905, 668.3450218785617, 676.1683129750435, 680.9216940051957, 681.7307163670323, 682.4983637026781, 533.3280067115385, 603.2158194347419, 656.4540507105793, 657.3262406033758, 713.5682911838367, 9079.863802042975, 9323.25413966881, 9468.763280917894, 9520.23838092951, 9689.563969621384, 557.7462254674114, 591.8549955260988, 670.7082419147687, 675.5541342713785, 682.8600136971909, 4462.776861156929, 4575.405197324327, 4608.5161058088415, 4646.95353046472, 4712.151812673752, 81268.18731812724, 83370.58314708387, 83464.74802877962, 93735.06264937263, 147827.26086369716, 85911.5704421474, 119569.8043019576, 122419.38790305992, 123338.0, 125899.0, 97326.51336743271, 102499.48750256201, 103035.48482257691, 104989.0, 107954.0, 8526.488410695378, 9632.90367096348, 9783.168430683472, 9811.509424528602, 9875.55559999816, 43290.13419731651, 49219.76950576264, 55253.44746552483, 57737.71131144317, 58306.0, 46072.308915365626, 53481.0, 73350.63324683343, 74245.62877185796, 90176.0, 12430.067744919348, 12864.549740759028, 12920.353982301036, 13041.217526948414, 13617.591472255848, 14485.565433037025, 14945.476908308157, 14992.175430351435, 15020.09879407239, 15214.771778423554, 13313.201207927556, 13640.317984100557, 13702.520411785938, 13777.931110344383, 13847.584572462842, 13242.0, 13608.251546165056, 13723.45106195741, 14054.086484378482, 14204.715905681622, 11579.652610421701, 13379.567990779286, 13395.12931659439, 13571.86428135706, 13841.446342146604, 10922.0, 11080.722981925193, 11261.831072533754, 11585.942070289932, 11711.590094346657, 11061.557537698402, 11098.621956594026, 11215.383153926286, 11373.943130284295, 11419.37193454368], "value": 7645.102642099498, "unit": "score"}], "name": "dromaeo_css", "value": 7120.75521143424, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 112.0, 115.0, 124.0, 166.0, 137.0, 114.0], "value": 122.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 117.0, 152.0, 119.0, 145.0, 145.0, 114.0, 164.0, 142.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 181.0, 184.0, 186.0, 182.0, 183.0, 183.0, 184.0, 184.0, 181.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 60.0, 90.0, 61.0, 60.0, 59.0, 60.0, 82.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 91.0, 70.0, 69.0, 69.0, 92.0, 67.0, 69.0, 90.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 92.0, 91.0, 87.0, 92.0, 89.0, 92.0, 87.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 97.0, 105.0, 148.0, 150.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 85.0, 86.0, 86.0, 86.0, 87.0, 84.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 44.0, 78.0, 88.0, 86.0, 54.0, 55.0, 56.0, 56.0, 54.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 67.0, 76.0, 72.0, 68.0, 79.0, 104.0, 103.0, 89.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 89.0, 89.0, 87.0, 89.0, 90.0, 85.0, 91.0, 112.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 156.0, 160.0, 146.0, 143.0, 156.0, 176.0, 156.0, 153.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 173.0, 172.0, 174.0, 173.0, 173.0, 173.0, 173.0, 174.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 70.0, 69.0, 69.0, 68.0, 69.0, 70.0, 67.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1495.8, "alertThreshold": 2.0}]} 02:31:51 INFO - Return code: 0 02:31:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:31:51 INFO - # TBPL SUCCESS # 02:31:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:31:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:31:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:31:51 INFO - 2016-10-01 02:31:51.699 screenresolution[1674:10671] starting screenresolution argv=screenresolution get 02:31:51 INFO - 2016-10-01 02:31:51.703 screenresolution[1674:10671] Display 0: 1600x1200x32@60 02:31:51 INFO - 2016-10-01 02:31:51.709 screenresolution[1675:10674] starting screenresolution argv=screenresolution list 02:31:51 INFO - Available Modes on Display 0 02:31:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:31:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:31:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:31:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:31:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:31:51 INFO - Intel Iris: 02:31:51 INFO - Chipset Model: Intel Iris 02:31:51 INFO - Type: GPU 02:31:51 INFO - Bus: Built-In 02:31:51 INFO - VRAM (Dynamic, Max): 1536 MB 02:31:51 INFO - Vendor: Intel (0x8086) 02:31:51 INFO - Device ID: 0x0a2e 02:31:51 INFO - Revision ID: 0x0009 02:31:51 INFO - Displays: 02:31:51 INFO - Display: 02:31:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:31:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:31:51 INFO - Display Serial Number: 1600x1200 60 02:31:51 INFO - Main Display: Yes 02:31:51 INFO - Mirror: Off 02:31:51 INFO - Online: Yes 02:31:51 INFO - Rotation: Supported 02:31:51 INFO - Return code: 0 02:31:51 INFO - Running post-action listener: _resource_record_post_action 02:31:51 INFO - [mozharness: 2016-10-01 09:31:51.947956Z] Finished run-tests step (success) 02:31:51 INFO - Running post-run listener: _resource_record_post_run 02:31:52 INFO - Total resource usage - Wall time: 1217s; CPU: 53.0%; Read bytes: 33090560; Write bytes: 706924544; Read time: 312; Write time: 1872 02:31:52 INFO - TinderboxPrint: CPU usage
53.3% 02:31:52 INFO - TinderboxPrint: I/O read bytes / time
33,090,560 / 312 02:31:52 INFO - TinderboxPrint: I/O write bytes / time
706,924,544 / 1,872 02:31:52 INFO - TinderboxPrint: CPU idle
2,272.1 (46.7%) 02:31:52 INFO - TinderboxPrint: CPU system
972.2 (20.0%) 02:31:52 INFO - TinderboxPrint: CPU user
1,625.3 (33.4%) 02:31:52 INFO - TinderboxPrint: Swap in / out
436,969,472 / 0 02:31:52 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 408381952; Write bytes: 410089472; Read time: 32623; Write time: 736 02:31:52 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 29199360; Write bytes: 295495680; Read time: 282; Write time: 1130 02:31:52 INFO - Running post-run listener: _upload_blobber_files 02:31:52 INFO - Blob upload gear active. 02:31:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:31:52 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 02:31:52 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'] 02:31:52 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 02:31:52 INFO - (blobuploader) - INFO - Open directory for files ... 02:31:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:31:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:31:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:53 INFO - (blobuploader) - INFO - Done attempting. 02:31:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:31:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:31:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:54 INFO - (blobuploader) - INFO - Done attempting. 02:31:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:31:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:57 INFO - (blobuploader) - INFO - Done attempting. 02:31:57 INFO - (blobuploader) - INFO - Iteration through files over. 02:31:57 INFO - Return code: 0 02:31:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:31:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:31:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf1d1c3c249fe067f13c4b62422159785183acc213035fb1ec669f94b5aaed3b7eaedd26627febf97265a441cd95bf1814eefb6853780b77e47d03d88f3ef297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17f9c817830ed0ddbdac3b3aab7963b19edb853024624245d970e3342d26cfffc9b81a0d3c67cdb5668a3db8abbf7e8db349c81c5ebe25c1b0c657aeb2e38c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/415f7a5cae42c42b689cbb8872ccf1ee8c874edf03bb8e2af9619a1884ce369145f53614de4b85c94223e6327140c058b16253c90105acd14de40fd597ac17c7"} 02:31:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:31:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:31:57 INFO - Contents: 02:31:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf1d1c3c249fe067f13c4b62422159785183acc213035fb1ec669f94b5aaed3b7eaedd26627febf97265a441cd95bf1814eefb6853780b77e47d03d88f3ef297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17f9c817830ed0ddbdac3b3aab7963b19edb853024624245d970e3342d26cfffc9b81a0d3c67cdb5668a3db8abbf7e8db349c81c5ebe25c1b0c657aeb2e38c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/415f7a5cae42c42b689cbb8872ccf1ee8c874edf03bb8e2af9619a1884ce369145f53614de4b85c94223e6327140c058b16253c90105acd14de40fd597ac17c7"} 02:31:57 INFO - Running post-run listener: copy_logs_to_upload_dir 02:31:57 INFO - Copying logs to upload dir... 02:31:57 INFO - mkdir: /builds/slave/test/build/upload/logs 02:31:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.373711 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-01 02:31:57.160682) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 02:31:57.166400) ========= 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.C2kYhSdIf5/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.ERET0j5yjb/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/bf1d1c3c249fe067f13c4b62422159785183acc213035fb1ec669f94b5aaed3b7eaedd26627febf97265a441cd95bf1814eefb6853780b77e47d03d88f3ef297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17f9c817830ed0ddbdac3b3aab7963b19edb853024624245d970e3342d26cfffc9b81a0d3c67cdb5668a3db8abbf7e8db349c81c5ebe25c1b0c657aeb2e38c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/415f7a5cae42c42b689cbb8872ccf1ee8c874edf03bb8e2af9619a1884ce369145f53614de4b85c94223e6327140c058b16253c90105acd14de40fd597ac17c7"} build_url:https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/Ssl4QaVpTleEf3Lt0VrFZQ/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/bf1d1c3c249fe067f13c4b62422159785183acc213035fb1ec669f94b5aaed3b7eaedd26627febf97265a441cd95bf1814eefb6853780b77e47d03d88f3ef297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17f9c817830ed0ddbdac3b3aab7963b19edb853024624245d970e3342d26cfffc9b81a0d3c67cdb5668a3db8abbf7e8db349c81c5ebe25c1b0c657aeb2e38c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/415f7a5cae42c42b689cbb8872ccf1ee8c874edf03bb8e2af9619a1884ce369145f53614de4b85c94223e6327140c058b16253c90105acd14de40fd597ac17c7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 02:31:57.204610) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:31:57.204931) ========= 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.C2kYhSdIf5/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.ERET0j5yjb/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.004704 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 02:31:57.252559) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 02:31:57.252865) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 02:31:57.256197) ========= ========= Total master_lag: 0.21 =========