builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0243 starttime: 1477055538.02 results: success (0) buildid: 20161021030210 builduid: e4b53d73ba5d428189e69984bd17d10c revision: f0f1aaf051d6798e1e73d1feee07ca847333167a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.020073) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.020521) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.020800) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.045491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.045982) ========= 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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-21 06:12:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 56.9M=0s 2016-10-21 06:12:18 (56.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122659 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.390193) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.390505) ========= 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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.019207 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:18.429893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 06:12:18.430216) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f0f1aaf051d6798e1e73d1feee07ca847333167a --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 f0f1aaf051d6798e1e73d1feee07ca847333167a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-21 06:12:18,510 truncating revision to first 12 chars 2016-10-21 06:12:18,510 Setting DEBUG logging. 2016-10-21 06:12:18,510 attempt 1/10 2016-10-21 06:12:18,510 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f0f1aaf051d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-21 06:12:19,817 unpacking tar archive at: mozilla-central-f0f1aaf051d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.567223 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 06:12:20.262500) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:20.262810) ========= script_repo_revision: f0f1aaf051d6798e1e73d1feee07ca847333167a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:20.263210) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:20.263475) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 06:12:20.281099) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-21 06:12:20.281401) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:12:20 INFO - MultiFileLogger online at 20161021 06:12:20 in /builds/slave/test 06:12:20 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 06:12:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:12:20 INFO - {'append_to_log': False, 06:12:20 INFO - 'base_work_dir': '/builds/slave/test', 06:12:20 INFO - 'blob_upload_branch': 'Firefox', 06:12:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:12:20 INFO - 'branch': 'Firefox', 06:12:20 INFO - 'buildbot_json_path': 'buildprops.json', 06:12:20 INFO - 'config_files': ('talos/mac_config.py',), 06:12:20 INFO - 'default_actions': ('clobber', 06:12:20 INFO - 'read-buildbot-config', 06:12:20 INFO - 'download-and-extract', 06:12:20 INFO - 'populate-webroot', 06:12:20 INFO - 'create-virtualenv', 06:12:20 INFO - 'install', 06:12:20 INFO - 'run-tests'), 06:12:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:12:20 INFO - 'download_minidump_stackwalk': True, 06:12:20 INFO - 'download_symbols': 'ondemand', 06:12:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:12:20 INFO - 'tooltool.py': '/tools/tooltool.py', 06:12:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:12:20 INFO - '/tools/misc-python/virtualenv.py')}, 06:12:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:12:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:12:20 INFO - 'installer_path': 'installer.exe', 06:12:20 INFO - 'log_level': 'info', 06:12:20 INFO - 'log_name': 'talos', 06:12:20 INFO - 'log_to_console': True, 06:12:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:12:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:12:20 INFO - 'opt_config_files': (), 06:12:20 INFO - 'pip_index': False, 06:12:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:12:20 INFO - 'cmd': ('bash', 06:12:20 INFO - '-c', 06:12:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:12:20 INFO - 'enabled': True, 06:12:20 INFO - 'halt_on_failure': False, 06:12:20 INFO - 'name': 'check_screen_resolution'},), 06:12:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:12:20 INFO - 'cmd': ('bash', 06:12:20 INFO - '-c', 06:12:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:12:20 INFO - 'enabled': True, 06:12:20 INFO - 'halt_on_failure': False, 06:12:20 INFO - 'name': 'check_screen_resolution'},), 06:12:20 INFO - 'run_cmd_checks_enabled': True, 06:12:20 INFO - 'sps_profile': False, 06:12:20 INFO - 'sps_profile_interval': 0, 06:12:20 INFO - 'suite': 'dromaeojs', 06:12:20 INFO - 'system_bits': '32', 06:12:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:12:20 INFO - 'title': 't-yosemite-r7-0243', 06:12:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:12:20 INFO - 'use_talos_json': True, 06:12:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:12:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:12:20 INFO - 'work_dir': 'build'} 06:12:20 INFO - [mozharness: 2016-10-21 13:12:20.479435Z] Running clobber step. 06:12:20 INFO - Running pre-action listener: _resource_record_pre_action 06:12:20 INFO - Running main action method: clobber 06:12:20 INFO - rmtree: /builds/slave/test/build 06:12:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:12:21 INFO - Running post-action listener: _resource_record_post_action 06:12:21 INFO - [mozharness: 2016-10-21 13:12:21.994982Z] Finished clobber step (success) 06:12:21 INFO - [mozharness: 2016-10-21 13:12:21.995102Z] Running read-buildbot-config step. 06:12:21 INFO - Running pre-action listener: _resource_record_pre_action 06:12:21 INFO - Running main action method: read_buildbot_config 06:12:21 INFO - Using buildbot properties: 06:12:21 INFO - { 06:12:21 INFO - "project": "", 06:12:21 INFO - "product": "firefox", 06:12:21 INFO - "script_repo_revision": "production", 06:12:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:12:21 INFO - "repository": "", 06:12:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:12:21 INFO - "buildid": "20161021030210", 06:12:21 INFO - "stage_platform": "macosx64", 06:12:21 INFO - "basedir": "/builds/slave/test", 06:12:21 INFO - "nightly_build": "True", 06:12:21 INFO - "buildnumber": 12, 06:12:21 INFO - "slavename": "t-yosemite-r7-0243", 06:12:21 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:12:21 INFO - "platform": "yosemite_r7", 06:12:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:12:21 INFO - "branch": "mozilla-central", 06:12:21 INFO - "repo_path": "mozilla-central", 06:12:21 INFO - "revision": "f0f1aaf051d6798e1e73d1feee07ca847333167a", 06:12:21 INFO - "pgo_build": "False", 06:12:21 INFO - "builduid": "e4b53d73ba5d428189e69984bd17d10c", 06:12:21 INFO - "slavebuilddir": "test" 06:12:21 INFO - } 06:12:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:12:21 INFO - Running post-action listener: _resource_record_post_action 06:12:21 INFO - [mozharness: 2016-10-21 13:12:21.998491Z] Finished read-buildbot-config step (success) 06:12:21 INFO - [mozharness: 2016-10-21 13:12:21.998599Z] Running download-and-extract step. 06:12:21 INFO - Running pre-action listener: _resource_record_pre_action 06:12:21 INFO - Running main action method: download_and_extract 06:12:21 INFO - mkdir: /builds/slave/test/build/tests 06:12:21 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')]} 06:12:21 INFO - trying https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:12:21 INFO - Downloading https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/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 06:12:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/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 06:12:22 INFO - Downloaded 1199 bytes. 06:12:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:12:22 INFO - Using the following test package requirements: 06:12:22 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:12:22 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:12:22 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:12:22 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'jsshell-mac.zip'], 06:12:22 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:12:22 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:12:22 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:12:22 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:12:22 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:12:22 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:12:22 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:12:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:12:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:12:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:12:22 INFO - Fetch https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:12:23 INFO - Http code: 200 06:12:23 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 06:12:23 INFO - x-amz-cf-id: dJUnqyMm8aJPZVQpWWiVnRPworrAKbLb3vA0bXk6Ml6zyj1FluijCA== 06:12:23 INFO - x-amz-version-id: NIHDuQMoMjszCCxaCYXkAuOlXGiObRNh 06:12:23 INFO - Expected file size: 16507087 06:12:23 INFO - Obtained file size: 16507087 06:12:30 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 06:12:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:12:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:12:30 INFO - Fetch https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:12:30 INFO - Http code: 200 06:12:30 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 06:12:30 INFO - x-amz-cf-id: XSruH1oLIcoySePctjLSYCswfyK4-dlKYqHSDjPR2eegNTqYrnJ4oA== 06:12:30 INFO - x-amz-version-id: NIHDuQMoMjszCCxaCYXkAuOlXGiObRNh 06:12:31 INFO - Expected file size: 16507087 06:12:31 INFO - Obtained file size: 16507087 06:12:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:12:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:12:37 INFO - Fetch https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:12:38 INFO - Http code: 200 06:12:38 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 06:12:38 INFO - x-amz-cf-id: rN609WKotNCZHGs1sFaYaBWiJH2qI0Zmb88o8EWMxY0VjlAIVTKwsg== 06:12:38 INFO - x-amz-version-id: KXQjB6Kdzf2YOvGBnxM99WUBtJAaNfdw 06:12:38 INFO - Expected file size: 14409028 06:12:38 INFO - Obtained file size: 14409028 06:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:38 INFO - trying https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:12:38 INFO - Downloading https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:12:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/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 06:12:42 INFO - Downloaded 111766528 bytes. 06:12:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:12:42 INFO - mkdir: /builds/slave/test/properties 06:12:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:12:42 INFO - Writing to file /builds/slave/test/properties/build_url 06:12:42 INFO - Contents: 06:12:42 INFO - build_url:https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:12:42 INFO - Running post-action listener: _resource_record_post_action 06:12:42 INFO - Running post-action listener: set_extra_try_arguments 06:12:42 INFO - [mozharness: 2016-10-21 13:12:42.736169Z] Finished download-and-extract step (success) 06:12:42 INFO - [mozharness: 2016-10-21 13:12:42.736296Z] Running populate-webroot step. 06:12:42 INFO - Running pre-action listener: _resource_record_pre_action 06:12:42 INFO - Running main action method: populate_webroot 06:12:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:12:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:12:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:12:42 INFO - '--tppagecycles', 06:12:42 INFO - '7'], 06:12:42 INFO - 'tests': ['tsvgm']}}, 06:12:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:12:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:12:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:12:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:12:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:12:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:12:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['damp', 'tps']}, 06:12:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'tests': ['damp', 'tps']}, 06:12:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['dromaeo_dom']}, 06:12:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:12:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['basic_compositor_video']}, 06:12:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:12:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['a11yr', 06:12:42 INFO - 'ts_paint', 06:12:42 INFO - 'tpaint', 06:12:42 INFO - 'sessionrestore', 06:12:42 INFO - 'sessionrestore_no_auto_restore', 06:12:42 INFO - 'tabpaint']}, 06:12:42 INFO - 'other-e10s': {'tests': ['a11yr', 06:12:42 INFO - 'ts_paint', 06:12:42 INFO - 'tpaint', 06:12:42 INFO - 'sessionrestore', 06:12:42 INFO - 'sessionrestore_no_auto_restore', 06:12:42 INFO - 'tabpaint']}, 06:12:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:12:42 INFO - 'ts_paint', 06:12:42 INFO - 'tpaint', 06:12:42 INFO - 'sessionrestore', 06:12:42 INFO - 'sessionrestore_no_auto_restore', 06:12:42 INFO - 'tabpaint']}, 06:12:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:12:42 INFO - 'ts_paint', 06:12:42 INFO - 'tpaint', 06:12:42 INFO - 'sessionrestore', 06:12:42 INFO - 'sessionrestore_no_auto_restore', 06:12:42 INFO - 'tabpaint']}, 06:12:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['a11yr', 06:12:42 INFO - 'ts_paint', 06:12:42 INFO - 'tpaint', 06:12:42 INFO - 'sessionrestore', 06:12:42 INFO - 'sessionrestore_no_auto_restore', 06:12:42 INFO - 'tabpaint']}, 06:12:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['a11yr', 06:12:42 INFO - 'ts_paint', 06:12:42 INFO - 'tpaint', 06:12:42 INFO - 'sessionrestore', 06:12:42 INFO - 'sessionrestore_no_auto_restore', 06:12:42 INFO - 'tabpaint']}, 06:12:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['tsvgx', 06:12:42 INFO - 'tsvgr_opacity', 06:12:42 INFO - 'tart', 06:12:42 INFO - 'tscrollx', 06:12:42 INFO - 'cart']}, 06:12:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:12:42 INFO - 'tsvgr_opacity', 06:12:42 INFO - 'tart', 06:12:42 INFO - 'tscrollx', 06:12:42 INFO - 'cart']}, 06:12:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'talos_options': ['--disable-e10s'], 06:12:42 INFO - 'tests': ['tp5o']}, 06:12:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'tests': ['tp5o']}, 06:12:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'talos_options': ['--disable-e10s', 06:12:42 INFO - '--xperf_path', 06:12:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:12:42 INFO - 'tests': ['tp5n']}, 06:12:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:12:42 INFO - 'talos_options': ['--xperf_path', 06:12:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:12:42 INFO - 'tests': ['tp5n']}}, 06:12:42 INFO - 'talos.zip': {'path': '', 06:12:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:12:42 INFO - Running post-action listener: _resource_record_post_action 06:12:42 INFO - [mozharness: 2016-10-21 13:12:42.755329Z] Finished populate-webroot step (success) 06:12:42 INFO - [mozharness: 2016-10-21 13:12:42.755470Z] Running create-virtualenv step. 06:12:42 INFO - Running pre-action listener: _resource_record_pre_action 06:12:42 INFO - Running main action method: create_virtualenv 06:12:42 INFO - Creating virtualenv /builds/slave/test/build/venv 06:12:42 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 06:12:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:12:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:12:42 INFO - Using real prefix '/tools/python27' 06:12:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:12:43 INFO - Installing distribute.............................................................................................................................................................................................done. 06:12:46 INFO - Installing pip.................done. 06:12:46 INFO - Return code: 0 06:12:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:12:46 INFO - Reading from file tmpfile_stdout 06:12:46 INFO - Output received: 06:12:46 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) 06:12:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:12:46 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')]} 06:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:46 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')]} 06:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:46 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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:12:46 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 06:12:46 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 06:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:46 INFO - 'HOME': '/Users/cltbld', 06:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:46 INFO - 'LOGNAME': 'cltbld', 06:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:46 INFO - 'MOZ_NO_REMOTE': '1', 06:12:46 INFO - 'NO_EM_RESTART': '1', 06:12:46 INFO - 'PAGER': '/bin/cat', 06:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:46 INFO - 'PWD': '/builds/slave/test', 06:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:46 INFO - 'SHELL': '/bin/bash', 06:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:12:46 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:46 INFO - 'USER': 'cltbld', 06:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:46 INFO - 'XPC_FLAGS': '0x0', 06:12:46 INFO - 'XPC_SERVICE_NAME': '0', 06:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:46 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 06:12:46 INFO - Cleaning up... 06:12:46 INFO - Return code: 0 06:12:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:12:46 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')]} 06:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:46 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')]} 06:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:46 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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:12:46 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 06:12:46 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 06:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:46 INFO - 'HOME': '/Users/cltbld', 06:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:46 INFO - 'LOGNAME': 'cltbld', 06:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:46 INFO - 'MOZ_NO_REMOTE': '1', 06:12:46 INFO - 'NO_EM_RESTART': '1', 06:12:46 INFO - 'PAGER': '/bin/cat', 06:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:46 INFO - 'PWD': '/builds/slave/test', 06:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:46 INFO - 'SHELL': '/bin/bash', 06:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:12:46 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:46 INFO - 'USER': 'cltbld', 06:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:46 INFO - 'XPC_FLAGS': '0x0', 06:12:46 INFO - 'XPC_SERVICE_NAME': '0', 06:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:47 INFO - Downloading/unpacking psutil>=3.1.1 06:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:12:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:12:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:12:50 INFO - Installing collected packages: psutil 06:12:50 INFO - Running setup.py install for psutil 06:12:50 INFO - building 'psutil._psutil_osx' extension 06:12:50 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 06:12:51 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 06:12:51 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 06:12:51 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 06:12:51 INFO - building 'psutil._psutil_posix' extension 06:12:51 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 06:12:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:12:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:12:51 INFO - ^ 06:12:51 INFO - 1 warning generated. 06:12:51 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 06:12:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:12:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:12:51 INFO - Successfully installed psutil 06:12:51 INFO - Cleaning up... 06:12:51 INFO - Return code: 0 06:12:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:12:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:12:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:12:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:51 INFO - 'HOME': '/Users/cltbld', 06:12:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:51 INFO - 'LOGNAME': 'cltbld', 06:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:51 INFO - 'MOZ_NO_REMOTE': '1', 06:12:51 INFO - 'NO_EM_RESTART': '1', 06:12:51 INFO - 'PAGER': '/bin/cat', 06:12:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:51 INFO - 'PWD': '/builds/slave/test', 06:12:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:51 INFO - 'SHELL': '/bin/bash', 06:12:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:12:51 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:12:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:51 INFO - 'USER': 'cltbld', 06:12:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:51 INFO - 'XPC_FLAGS': '0x0', 06:12:51 INFO - 'XPC_SERVICE_NAME': '0', 06:12:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:12:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:12:54 INFO - Installing collected packages: mozsystemmonitor 06:12:54 INFO - Running setup.py install for mozsystemmonitor 06:12:54 INFO - Successfully installed mozsystemmonitor 06:12:54 INFO - Cleaning up... 06:12:54 INFO - Return code: 0 06:12:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:54 INFO - 'HOME': '/Users/cltbld', 06:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:54 INFO - 'LOGNAME': 'cltbld', 06:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:54 INFO - 'MOZ_NO_REMOTE': '1', 06:12:54 INFO - 'NO_EM_RESTART': '1', 06:12:54 INFO - 'PAGER': '/bin/cat', 06:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:54 INFO - 'PWD': '/builds/slave/test', 06:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:54 INFO - 'SHELL': '/bin/bash', 06:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:12:54 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:54 INFO - 'USER': 'cltbld', 06:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:54 INFO - 'XPC_FLAGS': '0x0', 06:12:54 INFO - 'XPC_SERVICE_NAME': '0', 06:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:54 INFO - Downloading/unpacking jsonschema==2.5.1 06:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:12:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:57 INFO - Downloading functools32-3.2.3-2.tar.gz 06:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:12:57 INFO - warning: no files found matching '*.txt' 06:12:57 INFO - no previously-included directories found matching 'build' 06:12:57 INFO - no previously-included directories found matching 'dist' 06:12:57 INFO - no previously-included directories found matching '.git*' 06:12:57 INFO - Installing collected packages: jsonschema, functools32 06:12:57 INFO - Running setup.py install for functools32 06:12:57 INFO - warning: no files found matching '*.txt' 06:12:57 INFO - no previously-included directories found matching 'build' 06:12:57 INFO - no previously-included directories found matching 'dist' 06:12:57 INFO - no previously-included directories found matching '.git*' 06:12:57 INFO - Successfully installed jsonschema functools32 06:12:57 INFO - Cleaning up... 06:12:58 INFO - Return code: 0 06:12:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:12: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')]} 06:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12: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')]} 06:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:58 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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:12:58 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 06:12:58 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 06:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:58 INFO - 'HOME': '/Users/cltbld', 06:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:58 INFO - 'LOGNAME': 'cltbld', 06:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:58 INFO - 'MOZ_NO_REMOTE': '1', 06:12:58 INFO - 'NO_EM_RESTART': '1', 06:12:58 INFO - 'PAGER': '/bin/cat', 06:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:58 INFO - 'PWD': '/builds/slave/test', 06:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:58 INFO - 'SHELL': '/bin/bash', 06:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:12:58 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:58 INFO - 'USER': 'cltbld', 06:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:58 INFO - 'XPC_FLAGS': '0x0', 06:12:58 INFO - 'XPC_SERVICE_NAME': '0', 06:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:12:58 INFO - Cleaning up... 06:12:58 INFO - Return code: 0 06:12:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:12: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')]} 06:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12: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')]} 06:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:58 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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:12:58 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 06:12:58 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 06:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:58 INFO - 'HOME': '/Users/cltbld', 06:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:58 INFO - 'LOGNAME': 'cltbld', 06:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:58 INFO - 'MOZ_NO_REMOTE': '1', 06:12:58 INFO - 'NO_EM_RESTART': '1', 06:12:58 INFO - 'PAGER': '/bin/cat', 06:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:58 INFO - 'PWD': '/builds/slave/test', 06:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:58 INFO - 'SHELL': '/bin/bash', 06:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:12:58 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:58 INFO - 'USER': 'cltbld', 06:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:58 INFO - 'XPC_FLAGS': '0x0', 06:12:58 INFO - 'XPC_SERVICE_NAME': '0', 06:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:58 INFO - Downloading/unpacking blobuploader==1.2.4 06:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:13:01 INFO - Downloading blobuploader-1.2.4.tar.gz 06:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:13:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:13:01 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 06:13:01 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 06:13:01 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 06:13:01 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 06:13:01 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 06:13:01 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 06:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:13:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:13:01 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 06:13:01 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 06:13:01 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 06:13:01 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 06:13:01 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 06:13:01 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 06:13:02 INFO - Downloading docopt-0.6.1.tar.gz 06:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:13:02 INFO - Installing collected packages: blobuploader, requests, docopt 06:13:02 INFO - Running setup.py install for blobuploader 06:13:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:13:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:13:02 INFO - Running setup.py install for requests 06:13:02 INFO - Running setup.py install for docopt 06:13:03 INFO - Successfully installed blobuploader requests docopt 06:13:03 INFO - Cleaning up... 06:13:03 INFO - Return code: 0 06:13:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:13:03 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')]} 06:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:03 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')]} 06:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:03 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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:13:03 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 06:13:03 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 06:13:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:13:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:13:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:13:03 INFO - 'HOME': '/Users/cltbld', 06:13:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:13:03 INFO - 'LOGNAME': 'cltbld', 06:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:13:03 INFO - 'MOZ_NO_REMOTE': '1', 06:13:03 INFO - 'NO_EM_RESTART': '1', 06:13:03 INFO - 'PAGER': '/bin/cat', 06:13:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:13:03 INFO - 'PWD': '/builds/slave/test', 06:13:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:13:03 INFO - 'SHELL': '/bin/bash', 06:13:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:13:03 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:13:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:13:03 INFO - 'USER': 'cltbld', 06:13:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:13:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:13:03 INFO - 'XPC_FLAGS': '0x0', 06:13:03 INFO - 'XPC_SERVICE_NAME': '0', 06:13:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:13:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-1JIm0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:13:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hoBdDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:13:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-6FQwXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:13:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-YT1E2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:13:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-inPglT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-7S2tSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-1kLaFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-7kpjIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-EmSYSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-NiymO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-l8eD1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-7qz1EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-IqiL8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-5hDUXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:13:04 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-zFc5GY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:13:05 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-cE1cFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:13:05 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-_1PFrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:13:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:13:05 INFO - Running setup.py install for manifestparser 06:13:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:13:05 INFO - Running setup.py install for mozcrash 06:13:05 INFO - Running setup.py install for mozdebug 06:13:05 INFO - Running setup.py install for mozdevice 06:13:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:13:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:13:05 INFO - Running setup.py install for mozfile 06:13:06 INFO - Running setup.py install for mozhttpd 06:13:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Running setup.py install for mozinfo 06:13:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Running setup.py install for mozInstall 06:13:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Running setup.py install for mozleak 06:13:06 INFO - Running setup.py install for mozlog 06:13:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Running setup.py install for moznetwork 06:13:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:13:06 INFO - Running setup.py install for mozprocess 06:13:07 INFO - Running setup.py install for mozprofile 06:13:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:13:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:13:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:13:07 INFO - Running setup.py install for mozrunner 06:13:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:13:07 INFO - Running setup.py install for mozscreenshot 06:13:07 INFO - Running setup.py install for moztest 06:13:07 INFO - Running setup.py install for mozversion 06:13:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:13:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:13:07 INFO - Cleaning up... 06:13:07 INFO - Return code: 0 06:13:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:13:07 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')]} 06:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:07 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')]} 06:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:07 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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:13:07 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 06:13:07 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 06:13:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:13:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:13:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:13:07 INFO - 'HOME': '/Users/cltbld', 06:13:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:13:07 INFO - 'LOGNAME': 'cltbld', 06:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:13:07 INFO - 'MOZ_NO_REMOTE': '1', 06:13:07 INFO - 'NO_EM_RESTART': '1', 06:13:07 INFO - 'PAGER': '/bin/cat', 06:13:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:13:07 INFO - 'PWD': '/builds/slave/test', 06:13:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:13:07 INFO - 'SHELL': '/bin/bash', 06:13:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:13:07 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:13:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:13:07 INFO - 'USER': 'cltbld', 06:13:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:13:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:13:07 INFO - 'XPC_FLAGS': '0x0', 06:13:07 INFO - 'XPC_SERVICE_NAME': '0', 06:13:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Xv6H0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:13:08 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)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-KCLEz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:13:08 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)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-YGa0VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:13:08 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)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ox9Ssd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:13:08 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)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-NfOAt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:13:08 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)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-tB3uVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:13:08 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)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-kfua35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:13:08 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)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-MRzYVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:13:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hcd3Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-IERzL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-TngKFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-XXUzzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Qmkm3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-OOQ_8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-X_uDg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-EdrB7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:13:09 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)) 06:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:13:09 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Qu0FAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:13:09 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)) 06:13:09 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)) 06:13:09 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)) 06:13:09 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)) 06:13:09 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)) 06:13:09 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)) 06:13:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:13:09 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 06:13:09 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 06:13:09 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 06:13:09 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 06:13:09 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 06:13:09 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 06:13:12 INFO - Downloading blessings-1.6.tar.gz 06:13:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:13:12 INFO - Installing collected packages: blessings 06:13:12 INFO - Running setup.py install for blessings 06:13:12 INFO - Successfully installed blessings 06:13:12 INFO - Cleaning up... 06:13:12 INFO - Return code: 0 06:13:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:13:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:13:13 INFO - Reading from file tmpfile_stdout 06:13:13 INFO - Current package versions: 06:13:13 INFO - blessings == 1.6 06:13:13 INFO - blobuploader == 1.2.4 06:13:13 INFO - docopt == 0.6.1 06:13:13 INFO - functools32 == 3.2.3-2 06:13:13 INFO - jsonschema == 2.5.1 06:13:13 INFO - manifestparser == 1.1 06:13:13 INFO - mozInstall == 1.12 06:13:13 INFO - mozcrash == 0.17 06:13:13 INFO - mozdebug == 0.1 06:13:13 INFO - mozdevice == 0.48 06:13:13 INFO - mozfile == 1.2 06:13:13 INFO - mozhttpd == 0.7 06:13:13 INFO - mozinfo == 0.9 06:13:13 INFO - mozleak == 0.1 06:13:13 INFO - mozlog == 3.4 06:13:13 INFO - moznetwork == 0.27 06:13:13 INFO - mozprocess == 0.23 06:13:13 INFO - mozprofile == 0.28 06:13:13 INFO - mozrunner == 6.12 06:13:13 INFO - mozscreenshot == 0.1 06:13:13 INFO - mozsystemmonitor == 0.3 06:13:13 INFO - moztest == 0.7 06:13:13 INFO - mozversion == 1.4 06:13:13 INFO - psutil == 3.1.1 06:13:13 INFO - requests == 1.2.3 06:13:13 INFO - wsgiref == 0.1.2 06:13:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:13 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 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:13:13 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 06:13:13 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 06:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:13:13 INFO - 'HOME': '/Users/cltbld', 06:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:13:13 INFO - 'LOGNAME': 'cltbld', 06:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:13:13 INFO - 'MOZ_NO_REMOTE': '1', 06:13:13 INFO - 'NO_EM_RESTART': '1', 06:13:13 INFO - 'PAGER': '/bin/cat', 06:13:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:13:13 INFO - 'PWD': '/builds/slave/test', 06:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:13:13 INFO - 'SHELL': '/bin/bash', 06:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:13:13 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:13:13 INFO - 'USER': 'cltbld', 06:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:13:13 INFO - 'XPC_FLAGS': '0x0', 06:13:13 INFO - 'XPC_SERVICE_NAME': '0', 06:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:13:13 INFO - Cleaning up... 06:13:13 INFO - Return code: 0 06:13:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:13:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104423490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104399570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8011c7a800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, '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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 06:13:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:13:13 INFO - 'HOME': '/Users/cltbld', 06:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:13:13 INFO - 'LOGNAME': 'cltbld', 06:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:13:13 INFO - 'MOZ_NO_REMOTE': '1', 06:13:13 INFO - 'NO_EM_RESTART': '1', 06:13:13 INFO - 'PAGER': '/bin/cat', 06:13:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:13:13 INFO - 'PWD': '/builds/slave/test', 06:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:13:13 INFO - 'SHELL': '/bin/bash', 06:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:13:13 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:13:13 INFO - 'USER': 'cltbld', 06:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:13:13 INFO - 'XPC_FLAGS': '0x0', 06:13:13 INFO - 'XPC_SERVICE_NAME': '0', 06:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:13:13 INFO - Cleaning up... 06:13:13 INFO - Return code: 0 06:13:13 INFO - Running post-action listener: _resource_record_post_action 06:13:13 INFO - Running post-action listener: _start_resource_monitoring 06:13:13 INFO - Starting resource monitoring. 06:13:13 INFO - [mozharness: 2016-10-21 13:13:13.653160Z] Finished create-virtualenv step (success) 06:13:13 INFO - [mozharness: 2016-10-21 13:13:13.654198Z] Running install step. 06:13:13 INFO - Running pre-action listener: _resource_record_pre_action 06:13:13 INFO - Running main action method: install 06:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:13:13 INFO - Reading from file tmpfile_stdout 06:13:13 INFO - Detecting whether we're running mozinstall >=1.0... 06:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:13:14 INFO - Reading from file tmpfile_stdout 06:13:14 INFO - Output received: 06:13:14 INFO - Usage: mozinstall [options] installer 06:13:14 INFO - Options: 06:13:14 INFO - -h, --help show this help message and exit 06:13:14 INFO - -d DEST, --destination=DEST 06:13:14 INFO - Directory to install application into. [default: 06:13:14 INFO - "/builds/slave/test"] 06:13:14 INFO - --app=APP Application being installed. [default: firefox] 06:13:14 INFO - mkdir: /builds/slave/test/build/application 06:13:14 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'] 06:13:14 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 06:13:49 INFO - Reading from file tmpfile_stdout 06:13:49 INFO - Output received: 06:13:49 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:13:49 INFO - Running post-action listener: _resource_record_post_action 06:13:49 INFO - [mozharness: 2016-10-21 13:13:49.280005Z] Finished install step (success) 06:13:49 INFO - [mozharness: 2016-10-21 13:13:49.280138Z] Running run-tests step. 06:13:49 INFO - Running pre-action listener: _resource_record_pre_action 06:13:49 INFO - Running main action method: run_tests 06:13:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:13:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:13:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:13:49 INFO - 2016-10-21 06:13:49.294 screenresolution[2286:20815] starting screenresolution argv=screenresolution get 06:13:49 INFO - 2016-10-21 06:13:49.297 screenresolution[2286:20815] Display 0: 1600x1200x32@60 06:13:49 INFO - 2016-10-21 06:13:49.303 screenresolution[2287:20819] starting screenresolution argv=screenresolution list 06:13:49 INFO - Available Modes on Display 0 06:13:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:13:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:13:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:13:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:13:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:13:49 INFO - Intel Iris: 06:13:49 INFO - Chipset Model: Intel Iris 06:13:49 INFO - Type: GPU 06:13:49 INFO - Bus: Built-In 06:13:49 INFO - VRAM (Dynamic, Max): 1536 MB 06:13:49 INFO - Vendor: Intel (0x8086) 06:13:49 INFO - Device ID: 0x0a2e 06:13:49 INFO - Revision ID: 0x0009 06:13:49 INFO - Displays: 06:13:49 INFO - Display: 06:13:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:13:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:13:49 INFO - Display Serial Number: 1600x1200 60 06:13:49 INFO - Main Display: Yes 06:13:49 INFO - Mirror: Off 06:13:49 INFO - Online: Yes 06:13:49 INFO - Rotation: Supported 06:13:49 INFO - Return code: 0 06:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:13:49 INFO - Python 2.7.3 06:13:49 INFO - Return code: 0 06:13:49 INFO - grabbing minidump binary from tooltool 06:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:13:49 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 0x104237d30>, '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 0x10405ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10422fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:13:49 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 06:13:49 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 06:13:49 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 06:13:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:13:49 INFO - Return code: 0 06:13:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:13:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:13:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:13:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:13:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 06:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --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 06:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fzqMiOTuIE/Render', 06:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:13:49 INFO - 'HOME': '/Users/cltbld', 06:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:13:49 INFO - 'LOGNAME': 'cltbld', 06:13:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:13:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:13:49 INFO - 'MOZ_NO_REMOTE': '1', 06:13:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:13:49 INFO - 'NO_EM_RESTART': '1', 06:13:49 INFO - 'PAGER': '/bin/cat', 06:13:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:13:49 INFO - 'PWD': '/builds/slave/test', 06:13:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:13:49 INFO - 'SHELL': '/bin/bash', 06:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LKp1qh7EHZ/Listeners', 06:13:49 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 06:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:13:49 INFO - 'USER': 'cltbld', 06:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:13:49 INFO - 'XPC_FLAGS': '0x0', 06:13:49 INFO - 'XPC_SERVICE_NAME': '0', 06:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:13:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 06:13:49 INFO - mozversion application_buildid: 20161021030210 06:13:49 INFO - mozversion application_changeset: f0f1aaf051d6798e1e73d1feee07ca847333167a 06:13:49 INFO - mozversion application_display_name: Nightly 06:13:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:13:49 INFO - mozversion application_name: Firefox 06:13:49 INFO - mozversion application_remotingname: firefox 06:13:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:13:49 INFO - mozversion application_vendor: Mozilla 06:13:49 INFO - mozversion application_version: 52.0a1 06:13:49 INFO - mozversion platform_buildid: 20161021030210 06:13:49 INFO - mozversion platform_changeset: f0f1aaf051d6798e1e73d1feee07ca847333167a 06:13:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:13:49 INFO - mozversion platform_version: 52.0a1 06:13:49 INFO - using testdate: 1477055629 06:13:49 INFO - actual date: 1477055629 06:13:49 INFO - starting webserver on 'localhost:52180' 06:13:49 INFO - SUITE-START | Running 2 tests 06:13:49 INFO - TEST-START | dromaeo_css 06:13:49 INFO - operating with platform_type : mac_ 06:13:49 INFO - Initialising browser for dromaeo_css test... 06:13:49 INFO - TEST-INFO | started process 2297 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpA6VYs3/profile http://localhost:52180/getInfo.html) 06:13:52 INFO - PROCESS | 2297 | __metrics Screen width/height:1600/1200 06:13:52 INFO - PROCESS | 2297 | colorDepth:24 06:13:52 INFO - PROCESS | 2297 | Browser inner width/height: 1024/689 06:13:52 INFO - PROCESS | 2297 | __metrics 06:13:52 INFO - PROCESS | 2297 | 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" 06:13:52 INFO - PROCESS | 2297 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:13:53 INFO - TEST-INFO | 2297: exit 0 06:13:53 INFO - Browser initialized. 06:13:53 INFO - Running cycle 1/1 for dromaeo_css test... 06:13:53 INFO - TEST-INFO | started process 2300 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpA6VYs3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:05 INFO - PROCESS | 2300 | RSS: Main: 231829504 06:14:05 INFO - PROCESS | 2300 | 06:14:56 INFO - PROCESS | 2300 | 1477055696145 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] 06:17:08 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:52180/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52180/tests/dromaeo/cssquery-ext.html) 06:17:12 INFO - PROCESS | 2300 | RSS: Main: 512151552 06:17:12 INFO - PROCESS | 2300 | 06:20:15 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:52180/tests/dromaeo/cssquery-ext.html (next: http://localhost:52180/tests/dromaeo/cssquery-jquery.html) 06:20:15 INFO - PROCESS | 2300 | RSS: Main: 371335168 06:20:15 INFO - PROCESS | 2300 | 06:23:18 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:52180/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52180/tests/dromaeo/cssquery-mootools.html) 06:23:18 INFO - PROCESS | 2300 | RSS: Main: 333836288 06:23:18 INFO - PROCESS | 2300 | 06:26:21 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:52180/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52180/tests/dromaeo/cssquery-prototype.html) 06:26:21 INFO - PROCESS | 2300 | RSS: Main: 343031808 06:26:21 INFO - PROCESS | 2300 | 06:29:25 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:52180/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52180/tests/dromaeo/cssquery-yui.html) 06:29:26 INFO - PROCESS | 2300 | RSS: Main: 330653696 06:29:26 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:52180/tests/dromaeo/cssquery-yui.html (next: http://localhost:52180/tests/dromaeo/cssquery-dojo.html) 06:32:29 INFO - PROCESS | 2300 | RSS: Main: 555909120 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | __start_tp_report 06:32:29 INFO - PROCESS | 2300 | _x_x_mozilla_page_load 06:32:29 INFO - PROCESS | 2300 | _x_x_mozilla_page_load_details 06:32:29 INFO - PROCESS | 2300 | |i|pagename|runs| 06:32:29 INFO - PROCESS | 2300 | |0;dojo.html;3109.766767492438;3238.5304130901013;3260.152360386299;3296.15947933277;3336.366090442817;5446.210299506569;5530.751116199771;5544.279243698324;5590.357108932469;5602.887942241158;5545.140503221997;5646.830595082144;5649.954758369705;5650.321961364642;5673.404292549274;5029.994001199756;5046.116929537335;5060.089183946888;5100.872478188056;5104.310918026074;5113.744312784364;5192.091384007784;5198.766055527511;5201.765920533566;5254.238135470355;5159.071367153911;5177.508136727005;5178.197379406198;5208.114620514521;5232.843014709546;5011.574016208627;5045.772940217699;5065.366829146356;5072.771725272371;5074.391073071218;5042.369703787027;5053.620978426596;5055.772490237911;5095.770690318945;5128.307678463397;5537.861553461155;5601.943980560183;5612.887742245132;5623.831285061455;5649.915251271234;5919.674417907014;5975.492083172936;5985.910211346833;6101;6127.203463549755;6094.471245618287;6098.054801505774;6105.906617299921;6140.390334107986;6143.078538219258;4998.825041123543;5546.694931778791;5634.070378387602;5656.095024796013;5680.630759000652;6128.105930682418;6135.631862088289;6149.2620885494025;6157.507399408037;6194.5973511721595;5950.7917222896995;5964.045752679551;6017.2779266488305;6037.879242415127;6050.6672133032225;6159.907601385983;6172.598781079216;6187.412195841388;6188.381161883775;6195.628262304276;5884.440978107073;5929.407059294036;6106.267247930188;6116.235470566179;6144.815655530341;5082.974585127125;5986.341502434729;6072.696365181723;6083.574149809471;6102.0541816018585;4857.927131093036;4868.829590964299;4871.780769865364;4885.389326334208;4915.262710593368;4285.18581469526;4323.092150648336;4363.36731173978;4369.890752731207;4370.104128653618;4773.594244489257;5561.193626924073;5581.832545023656;5602.187682785973;5609.971950140223;6126;6128.877422451526;6138.296437970319;6154.230721159855;6219.973704338733;4820.469748327751;5696.458836410535;5699.974004679198;5707.34365547959;5720;5293.947060529423;5329.227262046981;5339.893202135935;5349.839504814861;5398.541124004503;6180.134781130555;6214.5028397728975;6220.875582488416;6280.057991301159;6289.119523266838;5197.532222100029;6118.832384214075;6145.293291271382;6163.3528479509005;6167.475764559883;6202.472789812917;6249.281332646804;6267.4045965633195;6270.216222972129;6285.43431091204;5564.805231816867;5575.578227551949;5834.620749651259;5891.646501209942;5913.349531551612;6043.576949613484;6063.060225665029;6067.757289708362;6071.7571297147615;6072.787452439144;5878.177055212188;5888.411158884077;5890.440408161217;5892.499137573354;5894.997850365362;4547.97726011381;5842.38654941242;5880.735366908585;5903.7048147593305;5904.439078287556;5766.4233576642;5766.452187042224;5771.336296325976;5774.7690092397515;5776.855578610568;5897.734601943009;5926.170336153028;5927.2590926134235;5927.496162826208;5933.406659334205;5748.942510575008;5764.827055188351;5776.220210271559;5788.594798364074;5804.796832110856 06:32:29 INFO - PROCESS | 2300 | |1;ext.html;468.48232702863334;501.8444282272498;510.7548376779145;513.2378418049198;521.397785557681;26782.5982610261;31706;31764.523532147035;39033.60966390335;39641.60358396415;41724.58275417245;42013.848961327945;42170.7891460543;42257;42321;9350.111739384765;9519.762005949871;9521.23830093591;9542.713718588453;9554.61781528738;7861.2531809478005;8473.322134229247;8677.392582519225;9445.197158241557;10295.897041029606;11248.775024499506;11458.369789661565;11464.14018948578;11466.426678666076;11545.769084618303;11309.321440713584;11368.317900925971;11404;11417.200795944287;11417.915298046672;8402.747917562483;8626.956865215634;8634.697785577537;8730.083341249205;11237;30922.226944326347;38417;38807.61192388096;38882;40854.38718419226;3327.88352407667;3330.7168890644325;3379.6620337966006;3490.3019396120876;3812.2947254757682;29717.257068573243;29919.70080299191;30409.087727367994;30493;30501.542476862865;3104.674009229044;3143.4184675835045;3283.7865538740143;3323.7839540430036;3329.217633856042;468463;468463.99999999657;469834;470288;477598;57617.71191144017;57923.710381447825;57924.710376447845;57974;58134;50459.74770126126;60856;60937.5156235932;60953;61031;15705.528834134995;15966.281517331749;15969;16002.919985399998;16009.599760006093;12380.319082450551;12438.440270187863;12454.003679705602;12456.94116000132;12474.126811123317;13779.931100344435;14112.22382768937;14120.576382708536;15337.616559585877;15651.530454086396;5840.890230856124;6342.587731797418;6425.421712045845;6479.254885688112;6571.638559879252;873.5326600268826;935.7881543401312;937.4047479850208;1048.7692707604467;1049.6903413493037;28977.118119975265;29021;29201.123966281044;29633.851830740707;29919;1074.9301295415773;1131.0216662586968;1153.7577108807077;1157.288267715349;1207.2635692227734;13184.143030702973;14226.573202803933;14959.626009349635;15191.088534687955;15585.844141558438;611693;616746;617092;617132.9143354255;619750;48141.7964550889;48732.7563362188;54728.17907731387;55658.16512752312;56023.439765602634;1341;1378.8345398552237;1397.6925076483158;1400.5028214983533;1404.9929750351182;24649.87675061613;29759.404811904074;30112.54831177578;30644.233894152825;31228;43979.34030989474;44363.778181108886;44396.55603443924;44438.555614443445;44509.442169523914;567457;569484;570789;571106;571193;37659;37997.430038549996;38600;38711.806440967615;38784.418233725955;45769.54230457653;45777.5015537601;47106.76446617882;47138.52861471342;47182;34126.146846329044;36764;37574.624253757116;37634.24731505409;38772.41841372438;36901.44647830338;38015.42976855294;38488.422673660476;39011.80494097511;39603.009924752114;36872.81563592165;37066.258674826895;37676;37932.05169870775;38199.427008594335;31933.840330798197;32701.509477357384;34473.827630861684;35337.46993795044;35474.87150770859;24490.510189795747;24708.752912471366;24778.87610562008;24808.751912481366;24972.875135624206 06:32:29 INFO - PROCESS | 2300 | |2;jquery.html;349.39205781939404;367.79771125880757;367.96320367963193;370.7756807131685;394.4024802424326;14829.925850370746;15142.318595663193;15846.524604261862;20458.89770551149;20653.277135300268;19741.40775776729;20127.094280757363;20686.37940861773;20916.581668366623;21334.46663833401;5116.386033675945;5785.305763308398;6084.878302433949;6088.786892458765;6093.177421048146;5358.517733403993;5965.522758179335;6026;6028.698565071751;6032.215811944441;5566.749496272678;5873.148393482964;5980.162777211194;5989.910151347712;5996.340402555716;5864.179014937891;5899.1446240295345;5902.940970590282;5903.173555702205;5911.054231323011;5072.239164125411;5148.536631703165;5151.45909679482;5342.252084708144;5407.540359069484;13069.477220911247;13113.47546098155;13143.60569182926;13325.333733313393;13350.799738003938;2200.8349373797028;2247.3932038349717;2259.9096036158535;2286.8856557172076;2311.8034967027825;9841.311108222428;9870.062344077302;10296.639617613422;10363.63727269542;14895.702085958328;1851.7314989326471;1852.4627857921187;1874.3027147638527;1878.8538991215348;1917.558961438863;118354.40822795831;122092;125394.37302813519;128299;128668.35665821705;10716.303440276357;11229.719257018476;11322.773544529227;11525.481353338952;11735.882641173479;14558.927205364118;14559.85440145585;14586.4894728684;14605.78091328631;14630.780538291934;8229.753107406787;8546.700865469678;8553.144685531397;8630.525321107274;8652.178043085896;8384.496930184208;8403.663853445794;8430;8567.74296771098;8570.271526920156;4420.336949457607;4524.366588677588;4537.6143027843;4612.377329060601;4613.284940834176;1764.4001039646582;1795.057750434407;1842.484104450756;1913.626842765675;1913.6651086059883;678.4538446550574;904.2359206470522;928.8374534456443;967.9527798705686;1040.422565476168;21466.78533214648;21563;21756.12975480963;22461.773623925234;22737.545249094925;893.8927328720595;949.7673070097732;966.1932286540676;988.5798535622332;1032.9276950613535;9029.264455485893;9826.901730982741;10266.332688375382;10407.011333923265;11176.385298808644;554192;579267;582503;594786;595552;125836.74163258434;126438;126714;130105;139757.30121349328;1016.0296916444778;1018.1549314069305;1021.5760459409411;1032.6024480575034;1050.364529459681;16261.59346016359;17119.48641540756;18602.348917788353;18744.718829217847;19002.52493687669;49998.75015623047;54306.45693543014;54419.72790136024;55560.499777510915;55615.44384556103;78841;79824.02199725034;80278.59860700658;80478.59760701159;80898.5955070221;7833.255840695125;8014;11584.362860042778;11852.170348075551;12246.938765306117;11453.885461145283;12748.49006039748;13241.47034118663;13651.38568764388;13935.790963135358;10187.388756674623;10696.502489651299;11205.88794112081;11335.979761821476;11731.706707332043;10819.513121909376;11314.377709226072;11481.196316258116;11662.70843228926;11810.055195584504;11425.37160456183;11568.88431115678;11823.704407389883;11990.280583165038;12042.458089385824;12041.819372709591;12150.210236334964;12368.505259789868;12429.254244745343;12487.313197773847;9475;11657.300561966309;11693.12301577368;11739.067412416936;11753.05975521939 06:32:29 INFO - PROCESS | 2300 | |3;mootools.html;1404.6207523968528;1550.5193390049085;1765.8057613662495;1901.9334323298688;1971.9309824156157;8461.830763384729;8974.77563060923;8975.461472311657;8978.86531702025;8993.370464067519;7770.766876993685;8088.393370497221;8088.878666819987;8496;8731.738047858558;2360.0795689680995;2389.557931782626;2396.95206095878;2399.544086623536;2399.544086623536;2343.9765602344014;2347.929562113131;2382.7855493005627;2391.4619210677633;2392.497575509145;2353.7763912428377;2374.5844477216506;2381.1189859751917;2392.35406440261;2394.4732158925094;2354.2348736660483;2363.7518060603647;2369.1352656280505;2378.797802186816;2392.7128744550764;2320.7447180810095;2338.4738433852417;2343.871087090209;2344.296710986714;2346.6480027995767;8820.558972051373;8918.464892106429;8926.732198034004;8930.687425940061;8930.86603700945;5187.766550505236;5364;5364.168553874156;5367.275417818583;5373.247745315659;3560.3413368526903;3563.1270338767167;3621.0947263184203;3640.7087433005295;3647.3981793004123;1056.7463808685936;1068.6206396729206;1072.0780129088978;1082.9025387715144;1087.1791797193068;9177.403468774508;11474.368909709963;11573;11608.6517404478;11620.883791162149;4452.30989196675;4582.656300777455;4758.262469317261;4778.1638213312535;4789.209780386267;9825.213982881352;9825.459599722084;9829.066238707312;9833.065858743408;9857.55640996157;5320.388155362106;5320.680759154463;5320.813771517978;5324.1747529133045;5326.893462130735;4348.891277718082;4513.75174365413;4728.976355118271;4869.123557759637;4878.341423907798;3804.923901521954;3837.5586807517493;3926.9018274542796;3946.7567716169315;3985.20295940813;1105.4141305108305;1286.9549565765153;1289.078309009063;1301.804729290614;1304.6281809684385;899.6941040046415;923.7782932096248;924.7040946896929;945.6146745018581;978.9118979291898;5664.915026274609;5831.679257640871;5852.326982396994;5973.402659734079;6061.1514387984835;420.11775271928707;439.2181916189188;439.9604035636808;440.1614923570603;444.38452742951245;3960.6435420812263;3962.580619396596;4050.797460126982;4060.3909413587608;4102.958970410317;10180.796384072426;10679.626213082505;10681.252312338062;10711.571537138427;10714.303570267908;10650.627228047137;10669.89330106689;10673.41296228715;10673.985971332711;10709.571617135227;7859.410544209207;7956.403269754675;8106.148854370324;8106.189381061965;8136.22705842944;5809.622374545641;5840.970795145997;5851.531877449709;5920.496757775637;5971.4327138921735;8573.442726222776;9902.752431189278;9982.35114717541;10005;10016.849747253651;9724.416535007922;9922.057404546556;10062;10114.79770404573;10172.593096276361;6872.656367181721;7120.359167674934;7123.82190445243;7418.443616728875;7490.325870671666;8099;8391.32869370461;8417.284530814704;8469.915300846913;8476.194761497647;7428.851422971618;7505.962470187614;7511.399088072832;7617.27635874591;7716.691332346643;7119.2168861426235;7129.180144283474;7194.676239569337;7360.631968401452;7529.209433009455;6363.713632886624;6799.864002720017;6897.3447522485285;7029.402500787491;7037.964810175917;4910.312556242057;4958.1323268428605;5101.107306221471;5108.821191258288;5152.458991805806;4976.925346119883;5003.874903127451;5128.435872053997;5171.9482805171465;5224.843254702365 06:32:29 INFO - PROCESS | 2300 | |4;prototype.html;265.71170280245934;297.38441426247664;297.4200309396677;306.3276108940874;310.68310323470064;4599.770011499429;5202.713850738208;5254.737263136847;5297.390800057994;5333.919991200135;4050.4329393884805;4223.6409905158025;4971.0803501352275;5115.4142215913;5253.947460525394;3468.959312206328;3575.0515968797067;3938.1532970411354;4012.638862502374;4041.3735870939977;3972.960270397288;4028.0735430850923;4036.4752582164424;4046.858359957387;4060.7969601519953;2688.6153630880112;3360.9077049958737;3908.882733517999;3970.5977883380574;3983.86056488023;3571.535700358963;3913.4716813230134;3964.682825373992;3976.3637817949134;4004.279229738646;3095.7523398128096;3205.3108581655056;3431.4852772084137;3449.1032331593715;3452.4648679454726;4239.456326204284;4375.2999520076955;5061.772220250097;5082.6276905235745;5131.61512886535;4186.35111557709;4221.598948099926;4504.617107545862;4515.525869783691;4565.908681826345;4467.615039337816;5352.223927530486;5425.077736784756;5477.972610136924;5482.17767334899;3058.5412188171954;3737.7570457920147;3738.8504459821847;3782.9810850945846;3792.146766977408;4994.375703037157;5056.367954005749;5185.974070129625;5711.9718450678865;5741.282339707536;3048.024632117701;3298;3440.82795860206;3459.515667806509;3795.8291876865055;3271.2335338917196;3717.4052151655605;3840;3848.692104631623;3860.3823388257742;4495.303227999665;4502.16709908668;4512.210363186429;4517.503074661785;4542.704724192917;4922.8185235543215;5205.219217117463;5486.094794358886;5521.4478552144465;5578.386377498472;1216.4247299746849;1307.6338625184962;1307.9476820927057;1315.605318404475;1316.959601914496;819.7253919936769;844.2528362399198;845.7674139611534;849.3205435651454;849.3375167369462;4212.957870421318;4780.048839195224;5098.821541246039;5109.693418394908;5256.710880901511;5334.333208348957;5610.410906854721;5621;5621.4659607337235;5642.971785141048;4689.413823272091;4765.7140571565815;4916.827911023097;5072.797088116434;5161.251618515369;2504.6243063540614;2531.885970173118;2881.423715256957;2885.4373397187765;2934.4424559333656;5559.444055594408;5808.39011903744;5844.772597754434;5854.443827836349;5893.233879595625;5226.425093239741;5278;5580.525655319262;5648.774049037993;5725.456081672234;5796.768244226003;5800.781835814526;5803.678528588598;5844.937768251195;5873.324567674772;4776;4790.993891282869;5525.033119204043;5616.66300022;5616.69108199053;5511.503964643041;5533.723313834373;5535.875772549476;5554.972225138848;5570.777168913198;4889.850885041941;5545.28835299642;5649.350324712546;5781.364049954417;5794.82615521535;3471.3230919970365;3713.9071523212137;3807.7524960877454;3825.4835597194024;3830.667319432112;4699.835505757282;5492.395836458067;5628.4090170533145;5629.577781666309;5645.350784659652;4780.928286075642;5482.91775623358;5485.750842154697;5505.6696598204235;5525.723713814374;5250.474952504719;5428.448629144295;5474.507294343528;5537.473939975695;5547.611667183259;4979.551840334242;5163.66436181647;5397.514223719884;5416.431274716098;5443.918341224806;5254.65844720092;5362;5391.4608539145775;5400.729963501888;5545.611807173621;4589.5639914208095;4992.925106123338;5384.380796208487;5462;5476.67139971603 06:32:29 INFO - PROCESS | 2300 | |5;yui.html;721.3291638775936;724.9891251631225;775.2789905387987;808.656321063548;830.8255266394057;15422.151781652019;16205.675886482264;16212.94615849969;16225.675486490278;16247.675046499064;14571.489997850078;14706.485273015447;14706.63233419165;14719.852801471981;14748.262586870667;704.7956092733127;724.7246046502322;727.8144073261337;870.2907130688484;877.4954401219293;880.3353468131584;891.6344298837478;904.3036861616552;905.995470022649;907.7639813648466;966.5650457294211;968.5544649461257;969.3360048366891;969.8884628267733;970.6699722094487;963.02734238419;963.4219468318953;965;965.0446058402131;966.5022513405602;547.9616426850122;565.1099518258718;678.1794029224644;697.1285892634207;699.6641612026204;15206.847931520764;15253.771193432107;15263.236838158158;15265.084094954338;15274.38902443912;13651.11267767602;14052.227127507984;14053.85946140546;14102.858971410256;14293.57119286423;12770.169938954032;12787.744245115138;12840.871591284062;13002.284874331908;13107.410166542528;3825.6748176404762;4545.499995000548;4628.652851036152;4642.094791515656;4700;95190.57214141793;95691.52154239184;95889.52055239749;95967.04032959651;96326;10695.679129626124;10849.78300433987;11039.337639741481;11080.390578518098;11444.835891005274;20512.897435513027;20809.959502024838;22251.55496890053;22266.88866555657;22273.777262227493;6909.896351554731;7782.844343113219;8062;8110;8125.065617454068;4344.413504176959;4400.91465692893;4424.247877860771;4456.420665313489;4528.773561321921;3147.3705258948303;3378.7128094111786;3486.1458942559234;3492.4935884296633;3501.9124521887156;629.6064959400375;636.4939872801134;642.4603333200134;643.4723526708148;643.9581427207216;526.6339893773791;536.9167778994262;583.9970800146057;587.4389957590491;649.9252585952581;8632.654693812303;8809.295256379473;8982.730518084469;8990.28077753778;9067.398585045867;510.7573902396332;516.8733660253262;580.8605934575671;629.4586655476287;638.9552731308856;3743.9438408423894;4412.558744125626;4430.180416622935;4432.512423633398;4668.066386722669;92486;92496.68758281096;92601.53699231596;122776.38611806884;140498.2975085118;112229.43885280685;116084;117754.8224517761;119715;122538;33828.830855846056;72722.63638681773;77468;78946.81579776308;97788.51105744425;9612.711618651452;9772.609095636379;9948.850767238642;9950.751231219278;9954.502274886081;47459.06634299451;62715;63803.68098159479;64909.67545162244;65893;74237.25762742304;82956.38610594643;89084;93757;94944;13995.580132596038;14023.50917717875;14262.500812471513;14704.264786760834;14860.256987150384;15059.93095075097;15179.848201517843;15413.614659633598;15540;15888.285027173573;11257.831132532854;13181.406836692564;13749.106308090342;13845.723085538031;14348.139111653334;11584.652460426201;14205.502807401692;14288.499902503363;14641.48754793577;14751.409943602564;13455.932720336335;13729.244891531062;13805.585832425044;14132.940029497624;14196.148231106166;10881.859083910842;10919.890801091888;11287.209895307578;11537.365444900612;11825.172237943261;9577.473238971645;10111;11180.972119811218;11672.70818229551;11759.470823812779 06:32:29 INFO - PROCESS | 2300 | __end_tp_report 06:32:29 INFO - PROCESS | 2300 | __start_cc_report 06:32:29 INFO - PROCESS | 2300 | _x_x_mozilla_cycle_collect,3620 06:32:29 INFO - PROCESS | 2300 | __end_cc_report 06:32:29 INFO - PROCESS | 2300 | __startTimestamp1477056749781__endTimestamp 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | ------- Summary: start ------- 06:32:29 INFO - PROCESS | 2300 | Number of tests: 6 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | [#0] dojo.html Cycles:165 Average:5588.16 Median:5766.42 stddev:633.25 (11.0%) stddev-sans-first:604.61 06:32:29 INFO - PROCESS | 2300 | Values: 3109.8 3238.5 3260.2 3296.2 3336.4 5446.2 5530.8 5544.3 5590.4 5602.9 5545.1 5646.8 5650.0 5650.3 5673.4 5030.0 5046.1 5060.1 5100.9 5104.3 5113.7 5192.1 5198.8 5201.8 5254.2 5159.1 5177.5 5178.2 5208.1 5232.8 5011.6 5045.8 5065.4 5072.8 5074.4 5042.4 5053.6 5055.8 5095.8 5128.3 5537.9 5601.9 5612.9 5623.8 5649.9 5919.7 5975.5 5985.9 6101.0 6127.2 6094.5 6098.1 6105.9 6140.4 6143.1 4998.8 5546.7 5634.1 5656.1 5680.6 6128.1 6135.6 6149.3 6157.5 6194.6 5950.8 5964.0 6017.3 6037.9 6050.7 6159.9 6172.6 6187.4 6188.4 6195.6 5884.4 5929.4 6106.3 6116.2 6144.8 5083.0 5986.3 6072.7 6083.6 6102.1 4857.9 4868.8 4871.8 4885.4 4915.3 4285.2 4323.1 4363.4 4369.9 4370.1 4773.6 5561.2 5581.8 5602.2 5610.0 6126.0 6128.9 6138.3 6154.2 6220.0 4820.5 5696.5 5700.0 5707.3 5720.0 5293.9 5329.2 5339.9 5349.8 5398.5 6180.1 6214.5 6220.9 6280.1 6289.1 5197.5 6118.8 6145.3 6163.4 6167.5 6202.5 6249.3 6267.4 6270.2 6285.4 5564.8 5575.6 5834.6 5891.6 5913.3 6043.6 6063.1 6067.8 6071.8 6072.8 5878.2 5888.4 5890.4 5892.5 5895.0 4548.0 5842.4 5880.7 5903.7 5904.4 5766.4 5766.5 5771.3 5774.8 5776.9 5897.7 5926.2 5927.3 5927.5 5933.4 5748.9 5764.8 5776.2 5788.6 5804.8 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | [#1] ext.html Cycles:180 Average:68197.66 Median:37804.03 stddev:148454.89 (392.7%) stddev-sans-first:148784.25 06:32:29 INFO - PROCESS | 2300 | Values: 468.5 501.8 510.8 513.2 521.4 26782.6 31706.0 31764.5 39033.6 39641.6 41724.6 42013.8 42170.8 42257.0 42321.0 9350.1 9519.8 9521.2 9542.7 9554.6 7861.3 8473.3 8677.4 9445.2 10295.9 11248.8 11458.4 11464.1 11466.4 11545.8 11309.3 11368.3 11404.0 11417.2 11417.9 8402.7 8627.0 8634.7 8730.1 11237.0 30922.2 38417.0 38807.6 38882.0 40854.4 3327.9 3330.7 3379.7 3490.3 3812.3 29717.3 29919.7 30409.1 30493.0 30501.5 3104.7 3143.4 3283.8 3323.8 3329.2 468463.0 468464.0 469834.0 470288.0 477598.0 57617.7 57923.7 57924.7 57974.0 58134.0 50459.7 60856.0 60937.5 60953.0 61031.0 15705.5 15966.3 15969.0 16002.9 16009.6 12380.3 12438.4 12454.0 12456.9 12474.1 13779.9 14112.2 14120.6 15337.6 15651.5 5840.9 6342.6 6425.4 6479.3 6571.6 873.5 935.8 937.4 1048.8 1049.7 28977.1 29021.0 29201.1 29633.9 29919.0 1074.9 1131.0 1153.8 1157.3 1207.3 13184.1 14226.6 14959.6 15191.1 15585.8 611693.0 616746.0 617092.0 617132.9 619750.0 48141.8 48732.8 54728.2 55658.2 56023.4 1341.0 1378.8 1397.7 1400.5 1405.0 24649.9 29759.4 30112.5 30644.2 31228.0 43979.3 44363.8 44396.6 44438.6 44509.4 567457.0 569484.0 570789.0 571106.0 571193.0 37659.0 37997.4 38600.0 38711.8 38784.4 45769.5 45777.5 47106.8 47138.5 47182.0 34126.1 36764.0 37574.6 37634.2 38772.4 36901.4 38015.4 38488.4 39011.8 39603.0 36872.8 37066.3 37676.0 37932.1 38199.4 31933.8 32701.5 34473.8 35337.5 35474.9 24490.5 24708.8 24778.9 24808.8 24972.9 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | [#2] jquery.html Cycles:180 Average:34678.06 Median:20556.09 stddev:97468.27 (474.2%) stddev-sans-first:97707.61 06:32:29 INFO - PROCESS | 2300 | Values: 349.4 367.8 368.0 370.8 394.4 14829.9 15142.3 15846.5 20458.9 20653.3 19741.4 20127.1 20686.4 20916.6 21334.5 5116.4 5785.3 6084.9 6088.8 6093.2 5358.5 5965.5 6026.0 6028.7 6032.2 5566.7 5873.1 5980.2 5989.9 5996.3 5864.2 5899.1 5902.9 5903.2 5911.1 5072.2 5148.5 5151.5 5342.3 5407.5 13069.5 13113.5 13143.6 13325.3 13350.8 2200.8 2247.4 2259.9 2286.9 2311.8 9841.3 9870.1 10296.6 10363.6 14895.7 1851.7 1852.5 1874.3 1878.9 1917.6 118354.4 122092.0 125394.4 128299.0 128668.4 10716.3 11229.7 11322.8 11525.5 11735.9 14558.9 14559.9 14586.5 14605.8 14630.8 8229.8 8546.7 8553.1 8630.5 8652.2 8384.5 8403.7 8430.0 8567.7 8570.3 4420.3 4524.4 4537.6 4612.4 4613.3 1764.4 1795.1 1842.5 1913.6 1913.7 678.5 904.2 928.8 968.0 1040.4 21466.8 21563.0 21756.1 22461.8 22737.5 893.9 949.8 966.2 988.6 1032.9 9029.3 9826.9 10266.3 10407.0 11176.4 554192.0 579267.0 582503.0 594786.0 595552.0 125836.7 126438.0 126714.0 130105.0 139757.3 1016.0 1018.2 1021.6 1032.6 1050.4 16261.6 17119.5 18602.3 18744.7 19002.5 49998.8 54306.5 54419.7 55560.5 55615.4 78841.0 79824.0 80278.6 80478.6 80898.6 7833.3 8014.0 11584.4 11852.2 12246.9 11453.9 12748.5 13241.5 13651.4 13935.8 10187.4 10696.5 11205.9 11336.0 11731.7 10819.5 11314.4 11481.2 11662.7 11810.1 11425.4 11568.9 11823.7 11990.3 12042.5 12041.8 12150.2 12368.5 12429.3 12487.3 9475.0 11657.3 11693.1 11739.1 11753.1 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | [#3] mootools.html Cycles:180 Average:5662.96 Median:4967.53 stddev:3155.09 (63.5%) stddev-sans-first:3147.71 06:32:29 INFO - PROCESS | 2300 | Values: 1404.6 1550.5 1765.8 1901.9 1971.9 8461.8 8974.8 8975.5 8978.9 8993.4 7770.8 8088.4 8088.9 8496.0 8731.7 2360.1 2389.6 2397.0 2399.5 2399.5 2344.0 2347.9 2382.8 2391.5 2392.5 2353.8 2374.6 2381.1 2392.4 2394.5 2354.2 2363.8 2369.1 2378.8 2392.7 2320.7 2338.5 2343.9 2344.3 2346.6 8820.6 8918.5 8926.7 8930.7 8930.9 5187.8 5364.0 5364.2 5367.3 5373.2 3560.3 3563.1 3621.1 3640.7 3647.4 1056.7 1068.6 1072.1 1082.9 1087.2 9177.4 11474.4 11573.0 11608.7 11620.9 4452.3 4582.7 4758.3 4778.2 4789.2 9825.2 9825.5 9829.1 9833.1 9857.6 5320.4 5320.7 5320.8 5324.2 5326.9 4348.9 4513.8 4729.0 4869.1 4878.3 3804.9 3837.6 3926.9 3946.8 3985.2 1105.4 1287.0 1289.1 1301.8 1304.6 899.7 923.8 924.7 945.6 978.9 5664.9 5831.7 5852.3 5973.4 6061.2 420.1 439.2 440.0 440.2 444.4 3960.6 3962.6 4050.8 4060.4 4103.0 10180.8 10679.6 10681.3 10711.6 10714.3 10650.6 10669.9 10673.4 10674.0 10709.6 7859.4 7956.4 8106.1 8106.2 8136.2 5809.6 5841.0 5851.5 5920.5 5971.4 8573.4 9902.8 9982.4 10005.0 10016.8 9724.4 9922.1 10062.0 10114.8 10172.6 6872.7 7120.4 7123.8 7418.4 7490.3 8099.0 8391.3 8417.3 8469.9 8476.2 7428.9 7506.0 7511.4 7617.3 7716.7 7119.2 7129.2 7194.7 7360.6 7529.2 6363.7 6799.9 6897.3 7029.4 7038.0 4910.3 4958.1 5101.1 5108.8 5152.5 4976.9 5003.9 5128.4 5171.9 5224.8 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | [#4] prototype.html Cycles:180 Average:4406.97 Median:4993.65 stddev:1381.72 (27.7%) stddev-sans-first:1350.19 06:32:29 INFO - PROCESS | 2300 | Values: 265.7 297.4 297.4 306.3 310.7 4599.8 5202.7 5254.7 5297.4 5333.9 4050.4 4223.6 4971.1 5115.4 5253.9 3469.0 3575.1 3938.2 4012.6 4041.4 3973.0 4028.1 4036.5 4046.9 4060.8 2688.6 3360.9 3908.9 3970.6 3983.9 3571.5 3913.5 3964.7 3976.4 4004.3 3095.8 3205.3 3431.5 3449.1 3452.5 4239.5 4375.3 5061.8 5082.6 5131.6 4186.4 4221.6 4504.6 4515.5 4565.9 4467.6 5352.2 5425.1 5478.0 5482.2 3058.5 3737.8 3738.9 3783.0 3792.1 4994.4 5056.4 5186.0 5712.0 5741.3 3048.0 3298.0 3440.8 3459.5 3795.8 3271.2 3717.4 3840.0 3848.7 3860.4 4495.3 4502.2 4512.2 4517.5 4542.7 4922.8 5205.2 5486.1 5521.4 5578.4 1216.4 1307.6 1307.9 1315.6 1317.0 819.7 844.3 845.8 849.3 849.3 4213.0 4780.0 5098.8 5109.7 5256.7 5334.3 5610.4 5621.0 5621.5 5643.0 4689.4 4765.7 4916.8 5072.8 5161.3 2504.6 2531.9 2881.4 2885.4 2934.4 5559.4 5808.4 5844.8 5854.4 5893.2 5226.4 5278.0 5580.5 5648.8 5725.5 5796.8 5800.8 5803.7 5844.9 5873.3 4776.0 4791.0 5525.0 5616.7 5616.7 5511.5 5533.7 5535.9 5555.0 5570.8 4889.9 5545.3 5649.4 5781.4 5794.8 3471.3 3713.9 3807.8 3825.5 3830.7 4699.8 5492.4 5628.4 5629.6 5645.4 4780.9 5482.9 5485.8 5505.7 5525.7 5250.5 5428.4 5474.5 5537.5 5547.6 4979.6 5163.7 5397.5 5416.4 5443.9 5254.7 5362.0 5391.5 5400.7 5545.6 4589.6 4992.9 5384.4 5462.0 5476.7 06:32:29 INFO - PROCESS | 2300 | 06:32:29 INFO - PROCESS | 2300 | [#5] yui.html Cycles:180 Average:21952.79 Median:4492.60 stddev:32601.21 (725.7%) stddev-sans-first:32653.69 06:32:29 INFO - PROCESS | 2300 | Values: 721.3 725.0 775.3 808.7 830.8 15422.2 16205.7 16212.9 16225.7 16247.7 14571.5 14706.5 14706.6 14719.9 14748.3 704.8 724.7 727.8 870.3 877.5 880.3 891.6 904.3 906.0 907.8 966.6 968.6 969.3 969.9 970.7 963.0 963.4 965.0 965.0 966.5 548.0 565.1 678.2 697.1 699.7 15206.8 15253.8 15263.2 15265.1 15274.4 13651.1 14052.2 14053.9 14102.9 14293.6 12770.2 12787.7 12840.9 13002.3 13107.4 3825.7 4545.5 4628.7 4642.1 4700.0 95190.6 95691.5 95889.5 95967.0 96326.0 10695.7 10849.8 11039.3 11080.4 11444.8 20512.9 20810.0 22251.6 22266.9 22273.8 6909.9 7782.8 8062.0 8110.0 8125.1 4344.4 4400.9 4424.2 4456.4 4528.8 3147.4 3378.7 3486.1 3492.5 3501.9 629.6 636.5 642.5 643.5 644.0 526.6 536.9 584.0 587.4 649.9 8632.7 8809.3 8982.7 8990.3 9067.4 510.8 516.9 580.9 629.5 639.0 3743.9 4412.6 4430.2 4432.5 4668.1 92486.0 92496.7 92601.5 122776.4 140498.3 112229.4 116084.0 117754.8 119715.0 122538.0 33828.8 72722.6 77468.0 78946.8 97788.5 9612.7 9772.6 9948.9 9950.8 9954.5 47459.1 62715.0 63803.7 64909.7 65893.0 74237.3 82956.4 89084.0 93757.0 94944.0 13995.6 14023.5 14262.5 14704.3 14860.3 15059.9 15179.8 15413.6 15540.0 15888.3 11257.8 13181.4 13749.1 13845.7 14348.1 11584.7 14205.5 14288.5 14641.5 14751.4 13455.9 13729.2 13805.6 14132.9 14196.1 10881.9 10919.9 11287.2 11537.4 11825.2 9577.5 10111.0 11181.0 11672.7 11759.5 06:32:29 INFO - PROCESS | 2300 | -------- Summary: end -------- 06:32:29 INFO - PROCESS | 2300 | 06:32:30 INFO - TEST-INFO | 2300: exit 0 06:32:30 INFO - TEST-OK | dromaeo_css | took 1120596ms 06:32:30 INFO - TEST-START | kraken 06:32:30 INFO - operating with platform_type : mac_ 06:32:30 INFO - Initialising browser for kraken test... 06:32:30 INFO - TEST-INFO | started process 2303 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp3VxEDv/profile http://localhost:52180/getInfo.html) 06:32:32 INFO - PROCESS | 2303 | __metrics Screen width/height:1600/1200 06:32:33 INFO - PROCESS | 2303 | colorDepth:24 06:32:33 INFO - PROCESS | 2303 | Browser inner width/height: 1024/689 06:32:33 INFO - PROCESS | 2303 | __metrics 06:32:33 INFO - PROCESS | 2303 | 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" 06:32:33 INFO - PROCESS | 2303 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:32:33 INFO - TEST-INFO | 2303: exit 0 06:32:33 INFO - Browser initialized. 06:32:33 INFO - Running cycle 1/1 for kraken test... 06:32:33 INFO - TEST-INFO | started process 2305 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp3VxEDv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:32:45 INFO - PROCESS | 2305 | RSS: Main: 231985152 06:32:45 INFO - PROCESS | 2305 | 06:32:48 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52180/tests/kraken/driver.html?testName=audio-beat-detection) 06:32:49 INFO - PROCESS | 2305 | RSS: Main: 290693120 06:32:49 INFO - PROCESS | 2305 | 06:32:52 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52180/tests/kraken/driver.html?testName=audio-dft) 06:32:52 INFO - PROCESS | 2305 | RSS: Main: 292171776 06:32:52 INFO - PROCESS | 2305 | 06:32:56 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52180/tests/kraken/driver.html?testName=audio-fft) 06:32:57 INFO - PROCESS | 2305 | RSS: Main: 384008192 06:32:57 INFO - PROCESS | 2305 | 06:32:59 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52180/tests/kraken/driver.html?testName=audio-oscillator) 06:32:59 INFO - PROCESS | 2305 | RSS: Main: 285548544 06:32:59 INFO - PROCESS | 2305 | 06:33:01 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52180/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:33:02 INFO - PROCESS | 2305 | RSS: Main: 290832384 06:33:02 INFO - PROCESS | 2305 | 06:33:05 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52180/tests/kraken/driver.html?testName=imaging-darkroom) 06:33:05 INFO - PROCESS | 2305 | RSS: Main: 328126464 06:33:05 INFO - PROCESS | 2305 | 06:33:08 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52180/tests/kraken/driver.html?testName=imaging-desaturate) 06:33:09 INFO - PROCESS | 2305 | RSS: Main: 329601024 06:33:09 INFO - PROCESS | 2305 | 06:33:11 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52180/tests/kraken/driver.html?testName=json-parse-financial) 06:33:12 INFO - PROCESS | 2305 | RSS: Main: 327045120 06:33:12 INFO - PROCESS | 2305 | 06:33:14 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52180/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:33:14 INFO - PROCESS | 2305 | RSS: Main: 303271936 06:33:14 INFO - PROCESS | 2305 | 06:33:16 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:33:17 INFO - PROCESS | 2305 | RSS: Main: 308494336 06:33:17 INFO - PROCESS | 2305 | 06:33:19 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:33:20 INFO - PROCESS | 2305 | RSS: Main: 310157312 06:33:20 INFO - PROCESS | 2305 | 06:33:23 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:33:23 INFO - PROCESS | 2305 | RSS: Main: 318849024 06:33:23 INFO - PROCESS | 2305 | 06:33:26 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:33:27 INFO - PROCESS | 2305 | RSS: Main: 318640128 06:33:27 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:52180/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52180/tests/kraken/driver.html?testName=ai-astar) 06:33:29 INFO - PROCESS | 2305 | RSS: Main: 362573824 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | __start_tp_report 06:33:29 INFO - PROCESS | 2305 | _x_x_mozilla_page_load 06:33:29 INFO - PROCESS | 2305 | _x_x_mozilla_page_load_details 06:33:29 INFO - PROCESS | 2305 | |i|pagename|runs| 06:33:29 INFO - PROCESS | 2305 | |0;ai-astar;130;165;162;114;113;112;112;113;114;113 06:33:29 INFO - PROCESS | 2305 | |1;audio-beat-detection;155;115;117;153;112;113;114;114;145;114 06:33:29 INFO - PROCESS | 2305 | |2;audio-dft;184;188;184;187;185;184;182;184;184;184 06:33:29 INFO - PROCESS | 2305 | |3;audio-fft;81;59;59;92;61;60;59;59;83;59 06:33:29 INFO - PROCESS | 2305 | |4;audio-oscillator;89;72;92;70;68;69;92;67;68;91 06:33:29 INFO - PROCESS | 2305 | |5;imaging-gaussian-blur;91;88;91;88;91;89;92;88;91;87 06:33:29 INFO - PROCESS | 2305 | |6;imaging-darkroom;96;96;97;95;95;96;96;96;96;96 06:33:29 INFO - PROCESS | 2305 | |7;imaging-desaturate;82;81;80;82;82;83;84;84;79;88 06:33:29 INFO - PROCESS | 2305 | |8;json-parse-financial;57;56;55;53;53;56;55;54;55;55 06:33:29 INFO - PROCESS | 2305 | |9;json-stringify-tinderbox;70;68;73;81;75;71;69;71;70;72 06:33:29 INFO - PROCESS | 2305 | |10;stanford-crypto-aes;87;87;84;87;90;86;86;85;88;85 06:33:29 INFO - PROCESS | 2305 | |11;stanford-crypto-ccm;148;147;147;129;150;150;157;158;179;152 06:33:29 INFO - PROCESS | 2305 | |12;stanford-crypto-pbkdf2;173;174;174;174;173;173;175;172;173;174 06:33:29 INFO - PROCESS | 2305 | |13;stanford-crypto-sha256-iterative;69;68;69;68;69;70;69;69;69;68 06:33:29 INFO - PROCESS | 2305 | __end_tp_report 06:33:29 INFO - PROCESS | 2305 | __start_cc_report 06:33:29 INFO - PROCESS | 2305 | _x_x_mozilla_cycle_collect,2008 06:33:29 INFO - PROCESS | 2305 | __end_cc_report 06:33:29 INFO - PROCESS | 2305 | __startTimestamp1477056809381__endTimestamp 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | ------- Summary: start ------- 06:33:29 INFO - PROCESS | 2305 | Number of tests: 14 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#0] ai-astar Cycles:10 Average:124.80 Median:114.00 stddev:21.10 (18.5%) stddev-sans-first:22.29 06:33:29 INFO - PROCESS | 2305 | Values: 130.0 165.0 162.0 114.0 113.0 112.0 112.0 113.0 114.0 113.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:116.00 stddev:18.02 (15.5%) stddev-sans-first:15.56 06:33:29 INFO - PROCESS | 2305 | Values: 155.0 115.0 117.0 153.0 112.0 113.0 114.0 114.0 145.0 114.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#2] audio-dft Cycles:10 Average:184.60 Median:184.00 stddev:1.71 (0.9%) stddev-sans-first:1.80 06:33:29 INFO - PROCESS | 2305 | Values: 184.0 188.0 184.0 187.0 185.0 184.0 182.0 184.0 184.0 184.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#3] audio-fft Cycles:10 Average:67.20 Median:60.50 stddev:12.83 (21.2%) stddev-sans-first:12.60 06:33:29 INFO - PROCESS | 2305 | Values: 81.0 59.0 59.0 92.0 61.0 60.0 59.0 59.0 83.0 59.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:80.50 stddev:11.47 (14.2%) stddev-sans-first:11.42 06:33:29 INFO - PROCESS | 2305 | Values: 89.0 72.0 92.0 70.0 68.0 69.0 92.0 67.0 68.0 91.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:91.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 06:33:29 INFO - PROCESS | 2305 | Values: 91.0 88.0 91.0 88.0 91.0 89.0 92.0 88.0 91.0 87.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 06:33:29 INFO - PROCESS | 2305 | Values: 96.0 96.0 97.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:82.50 stddev:2.51 (3.0%) stddev-sans-first:2.65 06:33:29 INFO - PROCESS | 2305 | Values: 82.0 81.0 80.0 82.0 82.0 83.0 84.0 84.0 79.0 88.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:1.29 (2.3%) stddev-sans-first:1.12 06:33:29 INFO - PROCESS | 2305 | Values: 57.0 56.0 55.0 53.0 53.0 56.0 55.0 54.0 55.0 55.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.50 stddev:3.74 (5.2%) stddev-sans-first:3.90 06:33:29 INFO - PROCESS | 2305 | Values: 70.0 68.0 73.0 81.0 75.0 71.0 69.0 71.0 70.0 72.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:87.00 stddev:1.72 (2.0%) stddev-sans-first:1.81 06:33:29 INFO - PROCESS | 2305 | Values: 87.0 87.0 84.0 87.0 90.0 86.0 86.0 85.0 88.0 85.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:151.00 stddev:12.44 (8.2%) stddev-sans-first:13.12 06:33:29 INFO - PROCESS | 2305 | Values: 148.0 147.0 147.0 129.0 150.0 150.0 157.0 158.0 179.0 152.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:174.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 06:33:29 INFO - PROCESS | 2305 | Values: 173.0 174.0 174.0 174.0 173.0 173.0 175.0 172.0 173.0 174.0 06:33:29 INFO - PROCESS | 2305 | 06:33:29 INFO - PROCESS | 2305 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 06:33:29 INFO - PROCESS | 2305 | Values: 69.0 68.0 69.0 68.0 69.0 70.0 69.0 69.0 69.0 68.0 06:33:29 INFO - PROCESS | 2305 | -------- Summary: end -------- 06:33:29 INFO - PROCESS | 2305 | 06:33:30 INFO - TEST-INFO | 2305: exit 0 06:33:30 INFO - TEST-OK | kraken | took 59806ms 06:33:30 INFO - SUITE-END | took 1180s 06:33:30 INFO - Completed test suite (00:19:40) 06:33:30 INFO - javascript benchmark 06:33:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3109.766767492438, 3238.5304130901013, 3260.152360386299, 3296.15947933277, 3336.366090442817, 5446.210299506569, 5530.751116199771, 5544.279243698324, 5590.357108932469, 5602.887942241158, 5545.140503221997, 5646.830595082144, 5649.954758369705, 5650.321961364642, 5673.404292549274, 5029.994001199756, 5046.116929537335, 5060.089183946888, 5100.872478188056, 5104.310918026074, 5113.744312784364, 5192.091384007784, 5198.766055527511, 5201.765920533566, 5254.238135470355, 5159.071367153911, 5177.508136727005, 5178.197379406198, 5208.114620514521, 5232.843014709546, 5011.574016208627, 5045.772940217699, 5065.366829146356, 5072.771725272371, 5074.391073071218, 5042.369703787027, 5053.620978426596, 5055.772490237911, 5095.770690318945, 5128.307678463397, 5537.861553461155, 5601.943980560183, 5612.887742245132, 5623.831285061455, 5649.915251271234, 5919.674417907014, 5975.492083172936, 5985.910211346833, 6101.0, 6127.203463549755, 6094.471245618287, 6098.054801505774, 6105.906617299921, 6140.390334107986, 6143.078538219258, 4998.825041123543, 5546.694931778791, 5634.070378387602, 5656.095024796013, 5680.630759000652, 6128.105930682418, 6135.631862088289, 6149.2620885494025, 6157.507399408037, 6194.5973511721595, 5950.7917222896995, 5964.045752679551, 6017.2779266488305, 6037.879242415127, 6050.6672133032225, 6159.907601385983, 6172.598781079216, 6187.412195841388, 6188.381161883775, 6195.628262304276, 5884.440978107073, 5929.407059294036, 6106.267247930188, 6116.235470566179, 6144.815655530341, 5082.974585127125, 5986.341502434729, 6072.696365181723, 6083.574149809471, 6102.0541816018585, 4857.927131093036, 4868.829590964299, 4871.780769865364, 4885.389326334208, 4915.262710593368, 4285.18581469526, 4323.092150648336, 4363.36731173978, 4369.890752731207, 4370.104128653618, 4773.594244489257, 5561.193626924073, 5581.832545023656, 5602.187682785973, 5609.971950140223, 6126.0, 6128.877422451526, 6138.296437970319, 6154.230721159855, 6219.973704338733, 4820.469748327751, 5696.458836410535, 5699.974004679198, 5707.34365547959, 5720.0, 5293.947060529423, 5329.227262046981, 5339.893202135935, 5349.839504814861, 5398.541124004503, 6180.134781130555, 6214.5028397728975, 6220.875582488416, 6280.057991301159, 6289.119523266838, 5197.532222100029, 6118.832384214075, 6145.293291271382, 6163.3528479509005, 6167.475764559883, 6202.472789812917, 6249.281332646804, 6267.4045965633195, 6270.216222972129, 6285.43431091204, 5564.805231816867, 5575.578227551949, 5834.620749651259, 5891.646501209942, 5913.349531551612, 6043.576949613484, 6063.060225665029, 6067.757289708362, 6071.7571297147615, 6072.787452439144, 5878.177055212188, 5888.411158884077, 5890.440408161217, 5892.499137573354, 5894.997850365362, 4547.97726011381, 5842.38654941242, 5880.735366908585, 5903.7048147593305, 5904.439078287556, 5766.4233576642, 5766.452187042224, 5771.336296325976, 5774.7690092397515, 5776.855578610568, 5897.734601943009, 5926.170336153028, 5927.2590926134235, 5927.496162826208, 5933.406659334205, 5748.942510575008, 5764.827055188351, 5776.220210271559, 5788.594798364074, 5804.796832110856], "value": 5548.564442425195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.48232702863334, 501.8444282272498, 510.7548376779145, 513.2378418049198, 521.397785557681, 26782.5982610261, 31706.0, 31764.523532147035, 39033.60966390335, 39641.60358396415, 41724.58275417245, 42013.848961327945, 42170.7891460543, 42257.0, 42321.0, 9350.111739384765, 9519.762005949871, 9521.23830093591, 9542.713718588453, 9554.61781528738, 7861.2531809478005, 8473.322134229247, 8677.392582519225, 9445.197158241557, 10295.897041029606, 11248.775024499506, 11458.369789661565, 11464.14018948578, 11466.426678666076, 11545.769084618303, 11309.321440713584, 11368.317900925971, 11404.0, 11417.200795944287, 11417.915298046672, 8402.747917562483, 8626.956865215634, 8634.697785577537, 8730.083341249205, 11237.0, 30922.226944326347, 38417.0, 38807.61192388096, 38882.0, 40854.38718419226, 3327.88352407667, 3330.7168890644325, 3379.6620337966006, 3490.3019396120876, 3812.2947254757682, 29717.257068573243, 29919.70080299191, 30409.087727367994, 30493.0, 30501.542476862865, 3104.674009229044, 3143.4184675835045, 3283.7865538740143, 3323.7839540430036, 3329.217633856042, 468463.0, 468463.99999999657, 469834.0, 470288.0, 477598.0, 57617.71191144017, 57923.710381447825, 57924.710376447845, 57974.0, 58134.0, 50459.74770126126, 60856.0, 60937.5156235932, 60953.0, 61031.0, 15705.528834134995, 15966.281517331749, 15969.0, 16002.919985399998, 16009.599760006093, 12380.319082450551, 12438.440270187863, 12454.003679705602, 12456.94116000132, 12474.126811123317, 13779.931100344435, 14112.22382768937, 14120.576382708536, 15337.616559585877, 15651.530454086396, 5840.890230856124, 6342.587731797418, 6425.421712045845, 6479.254885688112, 6571.638559879252, 873.5326600268826, 935.7881543401312, 937.4047479850208, 1048.7692707604467, 1049.6903413493037, 28977.118119975265, 29021.0, 29201.123966281044, 29633.851830740707, 29919.0, 1074.9301295415773, 1131.0216662586968, 1153.7577108807077, 1157.288267715349, 1207.2635692227734, 13184.143030702973, 14226.573202803933, 14959.626009349635, 15191.088534687955, 15585.844141558438, 611693.0, 616746.0, 617092.0, 617132.9143354255, 619750.0, 48141.7964550889, 48732.7563362188, 54728.17907731387, 55658.16512752312, 56023.439765602634, 1341.0, 1378.8345398552237, 1397.6925076483158, 1400.5028214983533, 1404.9929750351182, 24649.87675061613, 29759.404811904074, 30112.54831177578, 30644.233894152825, 31228.0, 43979.34030989474, 44363.778181108886, 44396.55603443924, 44438.555614443445, 44509.442169523914, 567457.0, 569484.0, 570789.0, 571106.0, 571193.0, 37659.0, 37997.430038549996, 38600.0, 38711.806440967615, 38784.418233725955, 45769.54230457653, 45777.5015537601, 47106.76446617882, 47138.52861471342, 47182.0, 34126.146846329044, 36764.0, 37574.624253757116, 37634.24731505409, 38772.41841372438, 36901.44647830338, 38015.42976855294, 38488.422673660476, 39011.80494097511, 39603.009924752114, 36872.81563592165, 37066.258674826895, 37676.0, 37932.05169870775, 38199.427008594335, 31933.840330798197, 32701.509477357384, 34473.827630861684, 35337.46993795044, 35474.87150770859, 24490.510189795747, 24708.752912471366, 24778.87610562008, 24808.751912481366, 24972.875135624206], "value": 19621.72871255754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.39205781939404, 367.79771125880757, 367.96320367963193, 370.7756807131685, 394.4024802424326, 14829.925850370746, 15142.318595663193, 15846.524604261862, 20458.89770551149, 20653.277135300268, 19741.40775776729, 20127.094280757363, 20686.37940861773, 20916.581668366623, 21334.46663833401, 5116.386033675945, 5785.305763308398, 6084.878302433949, 6088.786892458765, 6093.177421048146, 5358.517733403993, 5965.522758179335, 6026.0, 6028.698565071751, 6032.215811944441, 5566.749496272678, 5873.148393482964, 5980.162777211194, 5989.910151347712, 5996.340402555716, 5864.179014937891, 5899.1446240295345, 5902.940970590282, 5903.173555702205, 5911.054231323011, 5072.239164125411, 5148.536631703165, 5151.45909679482, 5342.252084708144, 5407.540359069484, 13069.477220911247, 13113.47546098155, 13143.60569182926, 13325.333733313393, 13350.799738003938, 2200.8349373797028, 2247.3932038349717, 2259.9096036158535, 2286.8856557172076, 2311.8034967027825, 9841.311108222428, 9870.062344077302, 10296.639617613422, 10363.63727269542, 14895.702085958328, 1851.7314989326471, 1852.4627857921187, 1874.3027147638527, 1878.8538991215348, 1917.558961438863, 118354.40822795831, 122092.0, 125394.37302813519, 128299.0, 128668.35665821705, 10716.303440276357, 11229.719257018476, 11322.773544529227, 11525.481353338952, 11735.882641173479, 14558.927205364118, 14559.85440145585, 14586.4894728684, 14605.78091328631, 14630.780538291934, 8229.753107406787, 8546.700865469678, 8553.144685531397, 8630.525321107274, 8652.178043085896, 8384.496930184208, 8403.663853445794, 8430.0, 8567.74296771098, 8570.271526920156, 4420.336949457607, 4524.366588677588, 4537.6143027843, 4612.377329060601, 4613.284940834176, 1764.4001039646582, 1795.057750434407, 1842.484104450756, 1913.626842765675, 1913.6651086059883, 678.4538446550574, 904.2359206470522, 928.8374534456443, 967.9527798705686, 1040.422565476168, 21466.78533214648, 21563.0, 21756.12975480963, 22461.773623925234, 22737.545249094925, 893.8927328720595, 949.7673070097732, 966.1932286540676, 988.5798535622332, 1032.9276950613535, 9029.264455485893, 9826.901730982741, 10266.332688375382, 10407.011333923265, 11176.385298808644, 554192.0, 579267.0, 582503.0, 594786.0, 595552.0, 125836.74163258434, 126438.0, 126714.0, 130105.0, 139757.30121349328, 1016.0296916444778, 1018.1549314069305, 1021.5760459409411, 1032.6024480575034, 1050.364529459681, 16261.59346016359, 17119.48641540756, 18602.348917788353, 18744.718829217847, 19002.52493687669, 49998.75015623047, 54306.45693543014, 54419.72790136024, 55560.499777510915, 55615.44384556103, 78841.0, 79824.02199725034, 80278.59860700658, 80478.59760701159, 80898.5955070221, 7833.255840695125, 8014.0, 11584.362860042778, 11852.170348075551, 12246.938765306117, 11453.885461145283, 12748.49006039748, 13241.47034118663, 13651.38568764388, 13935.790963135358, 10187.388756674623, 10696.502489651299, 11205.88794112081, 11335.979761821476, 11731.706707332043, 10819.513121909376, 11314.377709226072, 11481.196316258116, 11662.70843228926, 11810.055195584504, 11425.37160456183, 11568.88431115678, 11823.704407389883, 11990.280583165038, 12042.458089385824, 12041.819372709591, 12150.210236334964, 12368.505259789868, 12429.254244745343, 12487.313197773847, 9475.0, 11657.300561966309, 11693.12301577368, 11739.067412416936, 11753.05975521939], "value": 9569.667134163245, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.6207523968528, 1550.5193390049085, 1765.8057613662495, 1901.9334323298688, 1971.9309824156157, 8461.830763384729, 8974.77563060923, 8975.461472311657, 8978.86531702025, 8993.370464067519, 7770.766876993685, 8088.393370497221, 8088.878666819987, 8496.0, 8731.738047858558, 2360.0795689680995, 2389.557931782626, 2396.95206095878, 2399.544086623536, 2399.544086623536, 2343.9765602344014, 2347.929562113131, 2382.7855493005627, 2391.4619210677633, 2392.497575509145, 2353.7763912428377, 2374.5844477216506, 2381.1189859751917, 2392.35406440261, 2394.4732158925094, 2354.2348736660483, 2363.7518060603647, 2369.1352656280505, 2378.797802186816, 2392.7128744550764, 2320.7447180810095, 2338.4738433852417, 2343.871087090209, 2344.296710986714, 2346.6480027995767, 8820.558972051373, 8918.464892106429, 8926.732198034004, 8930.687425940061, 8930.86603700945, 5187.766550505236, 5364.0, 5364.168553874156, 5367.275417818583, 5373.247745315659, 3560.3413368526903, 3563.1270338767167, 3621.0947263184203, 3640.7087433005295, 3647.3981793004123, 1056.7463808685936, 1068.6206396729206, 1072.0780129088978, 1082.9025387715144, 1087.1791797193068, 9177.403468774508, 11474.368909709963, 11573.0, 11608.6517404478, 11620.883791162149, 4452.30989196675, 4582.656300777455, 4758.262469317261, 4778.1638213312535, 4789.209780386267, 9825.213982881352, 9825.459599722084, 9829.066238707312, 9833.065858743408, 9857.55640996157, 5320.388155362106, 5320.680759154463, 5320.813771517978, 5324.1747529133045, 5326.893462130735, 4348.891277718082, 4513.75174365413, 4728.976355118271, 4869.123557759637, 4878.341423907798, 3804.923901521954, 3837.5586807517493, 3926.9018274542796, 3946.7567716169315, 3985.20295940813, 1105.4141305108305, 1286.9549565765153, 1289.078309009063, 1301.804729290614, 1304.6281809684385, 899.6941040046415, 923.7782932096248, 924.7040946896929, 945.6146745018581, 978.9118979291898, 5664.915026274609, 5831.679257640871, 5852.326982396994, 5973.402659734079, 6061.1514387984835, 420.11775271928707, 439.2181916189188, 439.9604035636808, 440.1614923570603, 444.38452742951245, 3960.6435420812263, 3962.580619396596, 4050.797460126982, 4060.3909413587608, 4102.958970410317, 10180.796384072426, 10679.626213082505, 10681.252312338062, 10711.571537138427, 10714.303570267908, 10650.627228047137, 10669.89330106689, 10673.41296228715, 10673.985971332711, 10709.571617135227, 7859.410544209207, 7956.403269754675, 8106.148854370324, 8106.189381061965, 8136.22705842944, 5809.622374545641, 5840.970795145997, 5851.531877449709, 5920.496757775637, 5971.4327138921735, 8573.442726222776, 9902.752431189278, 9982.35114717541, 10005.0, 10016.849747253651, 9724.416535007922, 9922.057404546556, 10062.0, 10114.79770404573, 10172.593096276361, 6872.656367181721, 7120.359167674934, 7123.82190445243, 7418.443616728875, 7490.325870671666, 8099.0, 8391.32869370461, 8417.284530814704, 8469.915300846913, 8476.194761497647, 7428.851422971618, 7505.962470187614, 7511.399088072832, 7617.27635874591, 7716.691332346643, 7119.2168861426235, 7129.180144283474, 7194.676239569337, 7360.631968401452, 7529.209433009455, 6363.713632886624, 6799.864002720017, 6897.3447522485285, 7029.402500787491, 7037.964810175917, 4910.312556242057, 4958.1323268428605, 5101.107306221471, 5108.821191258288, 5152.458991805806, 4976.925346119883, 5003.874903127451, 5128.435872053997, 5171.9482805171465, 5224.843254702365], "value": 4494.374919160204, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.71170280245934, 297.38441426247664, 297.4200309396677, 306.3276108940874, 310.68310323470064, 4599.770011499429, 5202.713850738208, 5254.737263136847, 5297.390800057994, 5333.919991200135, 4050.4329393884805, 4223.6409905158025, 4971.0803501352275, 5115.4142215913, 5253.947460525394, 3468.959312206328, 3575.0515968797067, 3938.1532970411354, 4012.638862502374, 4041.3735870939977, 3972.960270397288, 4028.0735430850923, 4036.4752582164424, 4046.858359957387, 4060.7969601519953, 2688.6153630880112, 3360.9077049958737, 3908.882733517999, 3970.5977883380574, 3983.86056488023, 3571.535700358963, 3913.4716813230134, 3964.682825373992, 3976.3637817949134, 4004.279229738646, 3095.7523398128096, 3205.3108581655056, 3431.4852772084137, 3449.1032331593715, 3452.4648679454726, 4239.456326204284, 4375.2999520076955, 5061.772220250097, 5082.6276905235745, 5131.61512886535, 4186.35111557709, 4221.598948099926, 4504.617107545862, 4515.525869783691, 4565.908681826345, 4467.615039337816, 5352.223927530486, 5425.077736784756, 5477.972610136924, 5482.17767334899, 3058.5412188171954, 3737.7570457920147, 3738.8504459821847, 3782.9810850945846, 3792.146766977408, 4994.375703037157, 5056.367954005749, 5185.974070129625, 5711.9718450678865, 5741.282339707536, 3048.024632117701, 3298.0, 3440.82795860206, 3459.515667806509, 3795.8291876865055, 3271.2335338917196, 3717.4052151655605, 3840.0, 3848.692104631623, 3860.3823388257742, 4495.303227999665, 4502.16709908668, 4512.210363186429, 4517.503074661785, 4542.704724192917, 4922.8185235543215, 5205.219217117463, 5486.094794358886, 5521.4478552144465, 5578.386377498472, 1216.4247299746849, 1307.6338625184962, 1307.9476820927057, 1315.605318404475, 1316.959601914496, 819.7253919936769, 844.2528362399198, 845.7674139611534, 849.3205435651454, 849.3375167369462, 4212.957870421318, 4780.048839195224, 5098.821541246039, 5109.693418394908, 5256.710880901511, 5334.333208348957, 5610.410906854721, 5621.0, 5621.4659607337235, 5642.971785141048, 4689.413823272091, 4765.7140571565815, 4916.827911023097, 5072.797088116434, 5161.251618515369, 2504.6243063540614, 2531.885970173118, 2881.423715256957, 2885.4373397187765, 2934.4424559333656, 5559.444055594408, 5808.39011903744, 5844.772597754434, 5854.443827836349, 5893.233879595625, 5226.425093239741, 5278.0, 5580.525655319262, 5648.774049037993, 5725.456081672234, 5796.768244226003, 5800.781835814526, 5803.678528588598, 5844.937768251195, 5873.324567674772, 4776.0, 4790.993891282869, 5525.033119204043, 5616.66300022, 5616.69108199053, 5511.503964643041, 5533.723313834373, 5535.875772549476, 5554.972225138848, 5570.777168913198, 4889.850885041941, 5545.28835299642, 5649.350324712546, 5781.364049954417, 5794.82615521535, 3471.3230919970365, 3713.9071523212137, 3807.7524960877454, 3825.4835597194024, 3830.667319432112, 4699.835505757282, 5492.395836458067, 5628.4090170533145, 5629.577781666309, 5645.350784659652, 4780.928286075642, 5482.91775623358, 5485.750842154697, 5505.6696598204235, 5525.723713814374, 5250.474952504719, 5428.448629144295, 5474.507294343528, 5537.473939975695, 5547.611667183259, 4979.551840334242, 5163.66436181647, 5397.514223719884, 5416.431274716098, 5443.918341224806, 5254.65844720092, 5362.0, 5391.4608539145775, 5400.729963501888, 5545.611807173621, 4589.5639914208095, 4992.925106123338, 5384.380796208487, 5462.0, 5476.67139971603], "value": 3965.508124835838, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.3291638775936, 724.9891251631225, 775.2789905387987, 808.656321063548, 830.8255266394057, 15422.151781652019, 16205.675886482264, 16212.94615849969, 16225.675486490278, 16247.675046499064, 14571.489997850078, 14706.485273015447, 14706.63233419165, 14719.852801471981, 14748.262586870667, 704.7956092733127, 724.7246046502322, 727.8144073261337, 870.2907130688484, 877.4954401219293, 880.3353468131584, 891.6344298837478, 904.3036861616552, 905.995470022649, 907.7639813648466, 966.5650457294211, 968.5544649461257, 969.3360048366891, 969.8884628267733, 970.6699722094487, 963.02734238419, 963.4219468318953, 965.0, 965.0446058402131, 966.5022513405602, 547.9616426850122, 565.1099518258718, 678.1794029224644, 697.1285892634207, 699.6641612026204, 15206.847931520764, 15253.771193432107, 15263.236838158158, 15265.084094954338, 15274.38902443912, 13651.11267767602, 14052.227127507984, 14053.85946140546, 14102.858971410256, 14293.57119286423, 12770.169938954032, 12787.744245115138, 12840.871591284062, 13002.284874331908, 13107.410166542528, 3825.6748176404762, 4545.499995000548, 4628.652851036152, 4642.094791515656, 4700.0, 95190.57214141793, 95691.52154239184, 95889.52055239749, 95967.04032959651, 96326.0, 10695.679129626124, 10849.78300433987, 11039.337639741481, 11080.390578518098, 11444.835891005274, 20512.897435513027, 20809.959502024838, 22251.55496890053, 22266.88866555657, 22273.777262227493, 6909.896351554731, 7782.844343113219, 8062.0, 8110.0, 8125.065617454068, 4344.413504176959, 4400.91465692893, 4424.247877860771, 4456.420665313489, 4528.773561321921, 3147.3705258948303, 3378.7128094111786, 3486.1458942559234, 3492.4935884296633, 3501.9124521887156, 629.6064959400375, 636.4939872801134, 642.4603333200134, 643.4723526708148, 643.9581427207216, 526.6339893773791, 536.9167778994262, 583.9970800146057, 587.4389957590491, 649.9252585952581, 8632.654693812303, 8809.295256379473, 8982.730518084469, 8990.28077753778, 9067.398585045867, 510.7573902396332, 516.8733660253262, 580.8605934575671, 629.4586655476287, 638.9552731308856, 3743.9438408423894, 4412.558744125626, 4430.180416622935, 4432.512423633398, 4668.066386722669, 92486.0, 92496.68758281096, 92601.53699231596, 122776.38611806884, 140498.2975085118, 112229.43885280685, 116084.0, 117754.8224517761, 119715.0, 122538.0, 33828.830855846056, 72722.63638681773, 77468.0, 78946.81579776308, 97788.51105744425, 9612.711618651452, 9772.609095636379, 9948.850767238642, 9950.751231219278, 9954.502274886081, 47459.06634299451, 62715.0, 63803.68098159479, 64909.67545162244, 65893.0, 74237.25762742304, 82956.38610594643, 89084.0, 93757.0, 94944.0, 13995.580132596038, 14023.50917717875, 14262.500812471513, 14704.264786760834, 14860.256987150384, 15059.93095075097, 15179.848201517843, 15413.614659633598, 15540.0, 15888.285027173573, 11257.831132532854, 13181.406836692564, 13749.106308090342, 13845.723085538031, 14348.139111653334, 11584.652460426201, 14205.502807401692, 14288.499902503363, 14641.48754793577, 14751.409943602564, 13455.932720336335, 13729.244891531062, 13805.585832425044, 14132.940029497624, 14196.148231106166, 10881.859083910842, 10919.890801091888, 11287.209895307578, 11537.365444900612, 11825.172237943261, 9577.473238971645, 10111.0, 11180.972119811218, 11672.70818229551, 11759.470823812779], "value": 7664.747078049455, "unit": "score"}], "name": "dromaeo_css", "value": 7225.836186208578, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 165.0, 162.0, 114.0, 113.0, 112.0, 112.0, 113.0, 114.0, 113.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 115.0, 117.0, 153.0, 112.0, 113.0, 114.0, 114.0, 145.0, 114.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 184.0, 187.0, 185.0, 184.0, 182.0, 184.0, 184.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 92.0, 61.0, 60.0, 59.0, 59.0, 83.0, 59.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 92.0, 70.0, 68.0, 69.0, 92.0, 67.0, 68.0, 91.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 88.0, 91.0, 89.0, 92.0, 88.0, 91.0, 87.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 82.0, 82.0, 83.0, 84.0, 84.0, 79.0, 88.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 53.0, 53.0, 56.0, 55.0, 54.0, 55.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 73.0, 81.0, 75.0, 71.0, 69.0, 71.0, 70.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 87.0, 90.0, 86.0, 86.0, 85.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 129.0, 150.0, 150.0, 157.0, 158.0, 179.0, 152.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 174.0, 174.0, 173.0, 173.0, 175.0, 172.0, 173.0, 174.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 68.0, 69.0, 70.0, 69.0, 69.0, 69.0, 68.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1455.0, "alertThreshold": 2.0}]} 06:33:30 INFO - Return code: 0 06:33:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:33:30 INFO - # TBPL SUCCESS # 06:33:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:33:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:33:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:33:30 INFO - 2016-10-21 06:33:30.742 screenresolution[2309:23081] starting screenresolution argv=screenresolution get 06:33:30 INFO - 2016-10-21 06:33:30.746 screenresolution[2309:23081] Display 0: 1600x1200x32@60 06:33:30 INFO - 2016-10-21 06:33:30.752 screenresolution[2310:23084] starting screenresolution argv=screenresolution list 06:33:30 INFO - Available Modes on Display 0 06:33:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:33:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:33:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:33:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:33:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:33:30 INFO - Intel Iris: 06:33:30 INFO - Chipset Model: Intel Iris 06:33:30 INFO - Type: GPU 06:33:30 INFO - Bus: Built-In 06:33:30 INFO - VRAM (Dynamic, Max): 1536 MB 06:33:30 INFO - Vendor: Intel (0x8086) 06:33:30 INFO - Device ID: 0x0a2e 06:33:30 INFO - Revision ID: 0x0009 06:33:30 INFO - Displays: 06:33:30 INFO - Display: 06:33:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:33:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:33:30 INFO - Display Serial Number: 1600x1200 60 06:33:30 INFO - Main Display: Yes 06:33:30 INFO - Mirror: Off 06:33:30 INFO - Online: Yes 06:33:30 INFO - Rotation: Supported 06:33:30 INFO - Return code: 0 06:33:30 INFO - Running post-action listener: _resource_record_post_action 06:33:30 INFO - [mozharness: 2016-10-21 13:33:30.987042Z] Finished run-tests step (success) 06:33:30 INFO - Running post-run listener: _resource_record_post_run 06:33:31 INFO - Total resource usage - Wall time: 1216s; CPU: 54.0%; Read bytes: 167936; Write bytes: 707581440; Read time: 2; Write time: 1989 06:33:31 INFO - TinderboxPrint: CPU usage
53.6% 06:33:31 INFO - TinderboxPrint: I/O read bytes / time
167,936 / 2 06:33:31 INFO - TinderboxPrint: I/O write bytes / time
707,581,440 / 1,989 06:33:31 INFO - TinderboxPrint: CPU idle
2,255.9 (46.4%) 06:33:31 INFO - TinderboxPrint: CPU system
978.1 (20.1%) 06:33:31 INFO - TinderboxPrint: CPU user
1,631.7 (33.5%) 06:33:31 INFO - TinderboxPrint: Swap in / out
405,794,816 / 0 06:33:31 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 394963456; Write bytes: 392719872; Read time: 30417; Write time: 825 06:33:31 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 167936; Write bytes: 294471680; Read time: 2; Write time: 1107 06:33:31 INFO - Running post-run listener: _upload_blobber_files 06:33:31 INFO - Blob upload gear active. 06:33:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:33:31 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 06:33:31 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'] 06:33:31 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 06:33:31 INFO - (blobuploader) - INFO - Open directory for files ... 06:33:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:33:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:33:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:33:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:33:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:33:32 INFO - (blobuploader) - INFO - Done attempting. 06:33:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:33:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:33:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:33:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:33:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:33:33 INFO - (blobuploader) - INFO - Done attempting. 06:33:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:33:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:33:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:33:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:33:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:33:35 INFO - (blobuploader) - INFO - Done attempting. 06:33:35 INFO - (blobuploader) - INFO - Iteration through files over. 06:33:35 INFO - Return code: 0 06:33:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:33:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:33:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/343bc6575dce12eed899c9fe3294fca9ca33a2462a69bef4594bef03d8e94a61b4386876998206e7a25ab2fed02f276fdea36a48fe2823e0cf1d8bb41e8ca649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd4a4ceffacebafc4da15fe0eae6f78be0bb7cb0d46429b61b3ae785bc5ca7ecd9443534d24b604a7156291c82424a5e8ef0258e42fa0d8f3ce6f93305bb7d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbb1812d2f7fdf5f8e156123d318a81c9c945a41268663d2549eeee2000a5975e99e9b33cb41d8c9d2d26f96dd92abfa8efc1d3855bada7a0eb43b643a788cd5"} 06:33:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:33:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:33:35 INFO - Contents: 06:33:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/343bc6575dce12eed899c9fe3294fca9ca33a2462a69bef4594bef03d8e94a61b4386876998206e7a25ab2fed02f276fdea36a48fe2823e0cf1d8bb41e8ca649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd4a4ceffacebafc4da15fe0eae6f78be0bb7cb0d46429b61b3ae785bc5ca7ecd9443534d24b604a7156291c82424a5e8ef0258e42fa0d8f3ce6f93305bb7d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbb1812d2f7fdf5f8e156123d318a81c9c945a41268663d2549eeee2000a5975e99e9b33cb41d8c9d2d26f96dd92abfa8efc1d3855bada7a0eb43b643a788cd5"} 06:33:35 INFO - Running post-run listener: copy_logs_to_upload_dir 06:33:35 INFO - Copying logs to upload dir... 06:33:35 INFO - mkdir: /builds/slave/test/build/upload/logs 06:33:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.392076 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-21 06:33:35.584217) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-21 06:33:35.587562) ========= 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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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/343bc6575dce12eed899c9fe3294fca9ca33a2462a69bef4594bef03d8e94a61b4386876998206e7a25ab2fed02f276fdea36a48fe2823e0cf1d8bb41e8ca649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd4a4ceffacebafc4da15fe0eae6f78be0bb7cb0d46429b61b3ae785bc5ca7ecd9443534d24b604a7156291c82424a5e8ef0258e42fa0d8f3ce6f93305bb7d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbb1812d2f7fdf5f8e156123d318a81c9c945a41268663d2549eeee2000a5975e99e9b33cb41d8c9d2d26f96dd92abfa8efc1d3855bada7a0eb43b643a788cd5"} build_url:https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/IoxvCZdRQa29fuNgGGwE4g/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/343bc6575dce12eed899c9fe3294fca9ca33a2462a69bef4594bef03d8e94a61b4386876998206e7a25ab2fed02f276fdea36a48fe2823e0cf1d8bb41e8ca649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd4a4ceffacebafc4da15fe0eae6f78be0bb7cb0d46429b61b3ae785bc5ca7ecd9443534d24b604a7156291c82424a5e8ef0258e42fa0d8f3ce6f93305bb7d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbb1812d2f7fdf5f8e156123d318a81c9c945a41268663d2549eeee2000a5975e99e9b33cb41d8c9d2d26f96dd92abfa8efc1d3855bada7a0eb43b643a788cd5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-21 06:33:35.619822) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 06:33:35.620114) ========= 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.fzqMiOTuIE/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.LKp1qh7EHZ/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005299 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 06:33:35.669112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-21 06:33:35.669469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-21 06:33:35.669790) ========= ========= Total master_lag: 0.48 =========