builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1479835375.81 results: success (0) buildid: 20161122065350 builduid: 40b2dc3438984cb783bdf476bc844d20 revision: 1a3194836cb4c3da6ba3a9742a2d25cf26669b55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:55.812167) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:55.812622) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:55.812894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:55.837461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:55.837801) ========= 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.pflgFVC3gx/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.EFIVBpfJuc/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-22 09:22:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2016-11-22 09:22:55 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112048 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:55.973146) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:55.973455) ========= 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.pflgFVC3gx/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.EFIVBpfJuc/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.019807 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:56.021819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:56.022177) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1a3194836cb4c3da6ba3a9742a2d25cf26669b55 --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 1a3194836cb4c3da6ba3a9742a2d25cf26669b55 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pflgFVC3gx/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.EFIVBpfJuc/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-22 09:22:56,099 truncating revision to first 12 chars 2016-11-22 09:22:56,099 Setting DEBUG logging. 2016-11-22 09:22:56,100 attempt 1/10 2016-11-22 09:22:56,100 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1a3194836cb4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 09:22:56,765 unpacking tar archive at: mozilla-central-1a3194836cb4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.914253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:56.959861) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:56.960199) ========= script_repo_revision: 1a3194836cb4c3da6ba3a9742a2d25cf26669b55 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:56.960638) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:56.960907) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 09:22:56.980635) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-22 09:22:56.980989) ========= /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.pflgFVC3gx/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.EFIVBpfJuc/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:22:57 INFO - MultiFileLogger online at 20161122 09:22:57 in /builds/slave/test 09:22:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:22:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:22:57 INFO - {'append_to_log': False, 09:22:57 INFO - 'base_work_dir': '/builds/slave/test', 09:22:57 INFO - 'blob_upload_branch': 'Firefox', 09:22:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:22:57 INFO - 'branch': 'Firefox', 09:22:57 INFO - 'buildbot_json_path': 'buildprops.json', 09:22:57 INFO - 'config_files': ('talos/mac_config.py',), 09:22:57 INFO - 'default_actions': ('clobber', 09:22:57 INFO - 'read-buildbot-config', 09:22:57 INFO - 'download-and-extract', 09:22:57 INFO - 'populate-webroot', 09:22:57 INFO - 'create-virtualenv', 09:22:57 INFO - 'install', 09:22:57 INFO - 'run-tests'), 09:22:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:22:57 INFO - 'download_minidump_stackwalk': True, 09:22:57 INFO - 'download_symbols': 'ondemand', 09:22:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:22:57 INFO - 'tooltool.py': '/tools/tooltool.py', 09:22:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:22:57 INFO - '/tools/misc-python/virtualenv.py')}, 09:22:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:22:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:22:57 INFO - 'installer_path': 'installer.exe', 09:22:57 INFO - 'log_level': 'info', 09:22:57 INFO - 'log_name': 'talos', 09:22:57 INFO - 'log_to_console': True, 09:22:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:22:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:22:57 INFO - 'opt_config_files': (), 09:22:57 INFO - 'pip_index': False, 09:22:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:22:57 INFO - 'cmd': ('bash', 09:22:57 INFO - '-c', 09:22:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:22:57 INFO - 'enabled': True, 09:22:57 INFO - 'halt_on_failure': False, 09:22:57 INFO - 'name': 'check_screen_resolution'},), 09:22:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:22:57 INFO - 'cmd': ('bash', 09:22:57 INFO - '-c', 09:22:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:22:57 INFO - 'enabled': True, 09:22:57 INFO - 'halt_on_failure': False, 09:22:57 INFO - 'name': 'check_screen_resolution'},), 09:22:57 INFO - 'run_cmd_checks_enabled': True, 09:22:57 INFO - 'sps_profile': False, 09:22:57 INFO - 'sps_profile_interval': 0, 09:22:57 INFO - 'suite': 'dromaeojs', 09:22:57 INFO - 'system_bits': '32', 09:22:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:22:57 INFO - 'title': 't-yosemite-r7-0132', 09:22:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:22:57 INFO - 'use_talos_json': True, 09:22:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:22:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:22:57 INFO - 'work_dir': 'build'} 09:22:57 INFO - [mozharness: 2016-11-22 17:22:57.180693Z] Running clobber step. 09:22:57 INFO - Running pre-action listener: _resource_record_pre_action 09:22:57 INFO - Running main action method: clobber 09:22:57 INFO - rmtree: /builds/slave/test/build 09:22:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:22:58 INFO - Running post-action listener: _resource_record_post_action 09:22:58 INFO - [mozharness: 2016-11-22 17:22:58.829919Z] Finished clobber step (success) 09:22:58 INFO - [mozharness: 2016-11-22 17:22:58.830110Z] Running read-buildbot-config step. 09:22:58 INFO - Running pre-action listener: _resource_record_pre_action 09:22:58 INFO - Running main action method: read_buildbot_config 09:22:58 INFO - Using buildbot properties: 09:22:58 INFO - { 09:22:58 INFO - "project": "", 09:22:58 INFO - "product": "firefox", 09:22:58 INFO - "script_repo_revision": "production", 09:22:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:22:58 INFO - "repository": "", 09:22:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:22:58 INFO - "buildid": "20161122065350", 09:22:58 INFO - "pgo_build": "False", 09:22:58 INFO - "basedir": "/builds/slave/test", 09:22:58 INFO - "buildnumber": 1, 09:22:58 INFO - "slavename": "t-yosemite-r7-0132", 09:22:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:22:58 INFO - "platform": "yosemite_r7", 09:22:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:22:58 INFO - "branch": "mozilla-central", 09:22:58 INFO - "repo_path": "mozilla-central", 09:22:58 INFO - "revision": "1a3194836cb4c3da6ba3a9742a2d25cf26669b55", 09:22:58 INFO - "stage_platform": "macosx64", 09:22:58 INFO - "builduid": "40b2dc3438984cb783bdf476bc844d20", 09:22:58 INFO - "slavebuilddir": "test" 09:22:58 INFO - } 09:22:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:22:58 INFO - Running post-action listener: _resource_record_post_action 09:22:58 INFO - [mozharness: 2016-11-22 17:22:58.833301Z] Finished read-buildbot-config step (success) 09:22:58 INFO - [mozharness: 2016-11-22 17:22:58.833394Z] Running download-and-extract step. 09:22:58 INFO - Running pre-action listener: _resource_record_pre_action 09:22:58 INFO - Running main action method: download_and_extract 09:22:58 INFO - mkdir: /builds/slave/test/build/tests 09:22: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')]} 09:22:58 INFO - trying https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:22:58 INFO - Downloading https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:22:59 INFO - Downloaded 1199 bytes. 09:22:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:22:59 INFO - Using the following test package requirements: 09:22:59 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:22:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:22:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:22:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'jsshell-mac.zip'], 09:22:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:22:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:22:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:22:59 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:22:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:22:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:22:59 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:22:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:22:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:22:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:22:59 INFO - Fetch https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:23:00 INFO - Http code: 200 09:23:00 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 09:23:00 INFO - x-amz-cf-id: hIeS5m87Kl9k-BD_Iokvutd2ses73lK3Udv3qRJ73IZElfgPhad7kw== 09:23:00 INFO - x-amz-version-id: uLqvQcFlUaJZwtO.SS0B0rQsHYOnqAlS 09:23:00 INFO - Expected file size: 16248602 09:23:00 INFO - Obtained file size: 16248602 09:23:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:23:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:23:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:23:07 INFO - Fetch https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:23:07 INFO - Http code: 200 09:23:07 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 09:23:07 INFO - x-amz-cf-id: utB4PIoQAfRBwV-vcWKtHe-zDMNQW6Q1FQTdALeMivupyHIEkpUgAA== 09:23:07 INFO - x-amz-version-id: uLqvQcFlUaJZwtO.SS0B0rQsHYOnqAlS 09:23:08 INFO - Expected file size: 16248602 09:23:08 INFO - Obtained file size: 16248602 09:23:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:23:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:23:14 INFO - Fetch https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:23:14 INFO - Http code: 200 09:23:14 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 09:23:14 INFO - x-amz-cf-id: evZ64j40L5NiRkHDVZmZnn63cTFzgpw3PNIaxYbP-5w78B6cpbMBrg== 09:23:14 INFO - x-amz-version-id: 1gu5BRkeaSX2L2J5E78x3A4wcEh6HUlB 09:23:15 INFO - Expected file size: 14409392 09:23:15 INFO - Obtained file size: 14409392 09:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:15 INFO - trying https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:23:15 INFO - Downloading https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:23:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:23:18 INFO - Downloaded 107410869 bytes. 09:23:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:23:18 INFO - mkdir: /builds/slave/test/properties 09:23:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:23:18 INFO - Writing to file /builds/slave/test/properties/build_url 09:23:18 INFO - Contents: 09:23:18 INFO - build_url:https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:23:18 INFO - Running post-action listener: _resource_record_post_action 09:23:18 INFO - Running post-action listener: set_extra_try_arguments 09:23:18 INFO - [mozharness: 2016-11-22 17:23:18.833483Z] Finished download-and-extract step (success) 09:23:18 INFO - [mozharness: 2016-11-22 17:23:18.833602Z] Running populate-webroot step. 09:23:18 INFO - Running pre-action listener: _resource_record_pre_action 09:23:18 INFO - Running main action method: populate_webroot 09:23:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:23:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:23:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:23:18 INFO - '--tppagecycles', 09:23:18 INFO - '7'], 09:23:18 INFO - 'tests': ['tsvgm']}}, 09:23:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:23:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:23:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:23:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:23:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:23:18 INFO - 'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:23:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:23:18 INFO - 'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['damp', 'tps']}, 09:23:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:23:18 INFO - 'tests': ['damp', 'tps']}, 09:23:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['dromaeo_dom']}, 09:23:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:23:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['basic_compositor_video']}, 09:23:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:23:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['a11yr', 09:23:18 INFO - 'ts_paint', 09:23:18 INFO - 'tpaint', 09:23:18 INFO - 'sessionrestore', 09:23:18 INFO - 'sessionrestore_no_auto_restore', 09:23:18 INFO - 'tabpaint']}, 09:23:18 INFO - 'other-e10s': {'tests': ['a11yr', 09:23:18 INFO - 'ts_paint', 09:23:18 INFO - 'tpaint', 09:23:18 INFO - 'sessionrestore', 09:23:18 INFO - 'sessionrestore_no_auto_restore', 09:23:18 INFO - 'tabpaint']}, 09:23:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:23:18 INFO - 'ts_paint', 09:23:18 INFO - 'tpaint', 09:23:18 INFO - 'sessionrestore', 09:23:18 INFO - 'sessionrestore_no_auto_restore', 09:23:18 INFO - 'tabpaint']}, 09:23:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:23:18 INFO - 'ts_paint', 09:23:18 INFO - 'tpaint', 09:23:18 INFO - 'sessionrestore', 09:23:18 INFO - 'sessionrestore_no_auto_restore', 09:23:18 INFO - 'tabpaint']}, 09:23:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['a11yr', 09:23:18 INFO - 'ts_paint', 09:23:18 INFO - 'tpaint', 09:23:18 INFO - 'sessionrestore', 09:23:18 INFO - 'sessionrestore_no_auto_restore', 09:23:18 INFO - 'tabpaint']}, 09:23:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['a11yr', 09:23:18 INFO - 'ts_paint', 09:23:18 INFO - 'tpaint', 09:23:18 INFO - 'sessionrestore', 09:23:18 INFO - 'sessionrestore_no_auto_restore', 09:23:18 INFO - 'tabpaint']}, 09:23:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['tsvgx', 09:23:18 INFO - 'tsvgr_opacity', 09:23:18 INFO - 'tart', 09:23:18 INFO - 'tscrollx', 09:23:18 INFO - 'cart']}, 09:23:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:23:18 INFO - 'tsvgr_opacity', 09:23:18 INFO - 'tart', 09:23:18 INFO - 'tscrollx', 09:23:18 INFO - 'cart']}, 09:23:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:23:18 INFO - 'talos_options': ['--disable-e10s'], 09:23:18 INFO - 'tests': ['tp5o']}, 09:23:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:23:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:23:18 INFO - 'talos_options': ['--disable-e10s', 09:23:18 INFO - '--xperf_path', 09:23:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:18 INFO - 'tests': ['tp5n']}, 09:23:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:23:18 INFO - 'talos_options': ['--xperf_path', 09:23:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:18 INFO - 'tests': ['tp5n']}}, 09:23:18 INFO - 'talos.zip': {'path': '', 09:23:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:23:18 INFO - Running post-action listener: _resource_record_post_action 09:23:18 INFO - [mozharness: 2016-11-22 17:23:18.851730Z] Finished populate-webroot step (success) 09:23:18 INFO - [mozharness: 2016-11-22 17:23:18.851862Z] Running create-virtualenv step. 09:23:18 INFO - Running pre-action listener: _resource_record_pre_action 09:23:18 INFO - Running main action method: create_virtualenv 09:23:18 INFO - Creating virtualenv /builds/slave/test/build/venv 09:23:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:23:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:23:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:23:18 INFO - Using real prefix '/tools/python27' 09:23:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:23:19 INFO - Installing distribute.............................................................................................................................................................................................done. 09:23:22 INFO - Installing pip.................done. 09:23:22 INFO - Return code: 0 09:23:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:23:22 INFO - Reading from file tmpfile_stdout 09:23:22 INFO - Output received: 09:23:22 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:23:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:22 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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:22 INFO - 'HOME': '/Users/cltbld', 09:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:22 INFO - 'LOGNAME': 'cltbld', 09:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:22 INFO - 'MOZ_NO_REMOTE': '1', 09:23:22 INFO - 'NO_EM_RESTART': '1', 09:23:22 INFO - 'PAGER': '/bin/cat', 09:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:22 INFO - 'PWD': '/builds/slave/test', 09:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:22 INFO - 'SHELL': '/bin/bash', 09:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:22 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:22 INFO - 'USER': 'cltbld', 09:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:22 INFO - 'XPC_FLAGS': '0x0', 09:23:22 INFO - 'XPC_SERVICE_NAME': '0', 09:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:23:23 INFO - Cleaning up... 09:23:23 INFO - Return code: 0 09:23:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:23 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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:23 INFO - 'HOME': '/Users/cltbld', 09:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:23 INFO - 'LOGNAME': 'cltbld', 09:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:23 INFO - 'MOZ_NO_REMOTE': '1', 09:23:23 INFO - 'NO_EM_RESTART': '1', 09:23:23 INFO - 'PAGER': '/bin/cat', 09:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:23 INFO - 'PWD': '/builds/slave/test', 09:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:23 INFO - 'SHELL': '/bin/bash', 09:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:23 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:23 INFO - 'USER': 'cltbld', 09:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:23 INFO - 'XPC_FLAGS': '0x0', 09:23:23 INFO - 'XPC_SERVICE_NAME': '0', 09:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:23 INFO - Downloading/unpacking psutil>=3.1.1 09:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:23:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:26 INFO - Installing collected packages: psutil 09:23:26 INFO - Running setup.py install for psutil 09:23:26 INFO - building 'psutil._psutil_osx' extension 09:23:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:23:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:23:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:23:27 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:23:27 INFO - building 'psutil._psutil_posix' extension 09:23:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:23:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:23:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:23:27 INFO - ^ 09:23:27 INFO - 1 warning generated. 09:23:27 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:23:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:27 INFO - Successfully installed psutil 09:23:27 INFO - Cleaning up... 09:23:27 INFO - Return code: 0 09:23:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:27 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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:27 INFO - 'HOME': '/Users/cltbld', 09:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:27 INFO - 'LOGNAME': 'cltbld', 09:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:27 INFO - 'MOZ_NO_REMOTE': '1', 09:23:27 INFO - 'NO_EM_RESTART': '1', 09:23:27 INFO - 'PAGER': '/bin/cat', 09:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:27 INFO - 'PWD': '/builds/slave/test', 09:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:27 INFO - 'SHELL': '/bin/bash', 09:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:27 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:27 INFO - 'USER': 'cltbld', 09:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:27 INFO - 'XPC_FLAGS': '0x0', 09:23:27 INFO - 'XPC_SERVICE_NAME': '0', 09:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:23:30 INFO - Installing collected packages: mozsystemmonitor 09:23:30 INFO - Running setup.py install for mozsystemmonitor 09:23:30 INFO - Successfully installed mozsystemmonitor 09:23:30 INFO - Cleaning up... 09:23:30 INFO - Return code: 0 09:23:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:30 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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:30 INFO - 'HOME': '/Users/cltbld', 09:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:30 INFO - 'LOGNAME': 'cltbld', 09:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:30 INFO - 'MOZ_NO_REMOTE': '1', 09:23:30 INFO - 'NO_EM_RESTART': '1', 09:23:30 INFO - 'PAGER': '/bin/cat', 09:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:30 INFO - 'PWD': '/builds/slave/test', 09:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:30 INFO - 'SHELL': '/bin/bash', 09:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:30 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:30 INFO - 'USER': 'cltbld', 09:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:30 INFO - 'XPC_FLAGS': '0x0', 09:23:30 INFO - 'XPC_SERVICE_NAME': '0', 09:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:31 INFO - Downloading/unpacking jsonschema==2.5.1 09:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:23:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:23:34 INFO - warning: no files found matching '*.txt' 09:23:34 INFO - no previously-included directories found matching 'build' 09:23:34 INFO - no previously-included directories found matching 'dist' 09:23:34 INFO - no previously-included directories found matching '.git*' 09:23:34 INFO - Installing collected packages: jsonschema, functools32 09:23:34 INFO - Running setup.py install for functools32 09:23:34 INFO - warning: no files found matching '*.txt' 09:23:34 INFO - no previously-included directories found matching 'build' 09:23:34 INFO - no previously-included directories found matching 'dist' 09:23:34 INFO - no previously-included directories found matching '.git*' 09:23:34 INFO - Successfully installed jsonschema functools32 09:23:34 INFO - Cleaning up... 09:23:34 INFO - Return code: 0 09:23:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:23:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:34 INFO - 'HOME': '/Users/cltbld', 09:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:34 INFO - 'LOGNAME': 'cltbld', 09:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:34 INFO - 'MOZ_NO_REMOTE': '1', 09:23:34 INFO - 'NO_EM_RESTART': '1', 09:23:34 INFO - 'PAGER': '/bin/cat', 09:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:34 INFO - 'PWD': '/builds/slave/test', 09:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:34 INFO - 'SHELL': '/bin/bash', 09:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:34 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:34 INFO - 'USER': 'cltbld', 09:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:34 INFO - 'XPC_FLAGS': '0x0', 09:23:34 INFO - 'XPC_SERVICE_NAME': '0', 09:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:23:34 INFO - Cleaning up... 09:23:34 INFO - Return code: 0 09:23:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:23:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:34 INFO - 'HOME': '/Users/cltbld', 09:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:34 INFO - 'LOGNAME': 'cltbld', 09:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:34 INFO - 'MOZ_NO_REMOTE': '1', 09:23:34 INFO - 'NO_EM_RESTART': '1', 09:23:34 INFO - 'PAGER': '/bin/cat', 09:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:34 INFO - 'PWD': '/builds/slave/test', 09:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:34 INFO - 'SHELL': '/bin/bash', 09:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:34 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:34 INFO - 'USER': 'cltbld', 09:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:34 INFO - 'XPC_FLAGS': '0x0', 09:23:34 INFO - 'XPC_SERVICE_NAME': '0', 09:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:34 INFO - Downloading/unpacking blobuploader==1.2.4 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:23:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:23:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:38 INFO - Downloading docopt-0.6.1.tar.gz 09:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:23:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:23:38 INFO - Running setup.py install for blobuploader 09:23:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:23:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:23:38 INFO - Running setup.py install for requests 09:23:39 INFO - Running setup.py install for docopt 09:23:39 INFO - Successfully installed blobuploader requests docopt 09:23:39 INFO - Cleaning up... 09:23:39 INFO - Return code: 0 09:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:39 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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:39 INFO - 'HOME': '/Users/cltbld', 09:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:39 INFO - 'LOGNAME': 'cltbld', 09:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:39 INFO - 'MOZ_NO_REMOTE': '1', 09:23:39 INFO - 'NO_EM_RESTART': '1', 09:23:39 INFO - 'PAGER': '/bin/cat', 09:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:39 INFO - 'PWD': '/builds/slave/test', 09:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:39 INFO - 'SHELL': '/bin/bash', 09:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:39 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:39 INFO - 'USER': 'cltbld', 09:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:39 INFO - 'XPC_FLAGS': '0x0', 09:23:39 INFO - 'XPC_SERVICE_NAME': '0', 09:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Rq6Z8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-fCaOWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-KfnGQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-HHuXLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ycm0fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ifWwio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Ral0je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Fb5QF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-IohJcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-orCn9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CKp1zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Gq625q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-c4znWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-1deWcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ZaocRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-IrwZ_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-g8R68f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:23:41 INFO - Running setup.py install for manifestparser 09:23:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:23:41 INFO - Running setup.py install for mozcrash 09:23:41 INFO - Running setup.py install for mozdebug 09:23:42 INFO - Running setup.py install for mozdevice 09:23:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Running setup.py install for mozfile 09:23:42 INFO - Running setup.py install for mozhttpd 09:23:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Running setup.py install for mozinfo 09:23:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Running setup.py install for mozInstall 09:23:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:23:42 INFO - Running setup.py install for mozleak 09:23:42 INFO - Running setup.py install for mozlog 09:23:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:23:43 INFO - Running setup.py install for moznetwork 09:23:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:23:43 INFO - Running setup.py install for mozprocess 09:23:43 INFO - Running setup.py install for mozprofile 09:23:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:23:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:23:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:23:43 INFO - Running setup.py install for mozrunner 09:23:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:23:43 INFO - Running setup.py install for mozscreenshot 09:23:43 INFO - Running setup.py install for moztest 09:23:44 INFO - Running setup.py install for mozversion 09:23:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:23:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:23:44 INFO - Cleaning up... 09:23:44 INFO - Return code: 0 09:23:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:44 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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:44 INFO - 'HOME': '/Users/cltbld', 09:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:44 INFO - 'LOGNAME': 'cltbld', 09:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:44 INFO - 'MOZ_NO_REMOTE': '1', 09:23:44 INFO - 'NO_EM_RESTART': '1', 09:23:44 INFO - 'PAGER': '/bin/cat', 09:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:44 INFO - 'PWD': '/builds/slave/test', 09:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:44 INFO - 'SHELL': '/bin/bash', 09:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:44 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:44 INFO - 'USER': 'cltbld', 09:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:44 INFO - 'XPC_FLAGS': '0x0', 09:23:44 INFO - 'XPC_SERVICE_NAME': '0', 09:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:44 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ZYIdxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:44 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-QGQCJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:44 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-90uOpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:44 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WkdXWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:44 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-SIKyN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-DAaxSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-8u65IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-sNik2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-146Isq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-zpbJp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-dN79lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-1Q03jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-2xu5gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:45 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-zUaWfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-c_SYFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-e6VV1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-aLHAGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:23:46 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:23:46 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:23:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:48 INFO - Downloading blessings-1.6.tar.gz 09:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:23:48 INFO - Installing collected packages: blessings 09:23:48 INFO - Running setup.py install for blessings 09:23:49 INFO - Successfully installed blessings 09:23:49 INFO - Cleaning up... 09:23:49 INFO - Return code: 0 09:23:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:23:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:49 INFO - Reading from file tmpfile_stdout 09:23:49 INFO - Current package versions: 09:23:49 INFO - blessings == 1.6 09:23:49 INFO - blobuploader == 1.2.4 09:23:49 INFO - docopt == 0.6.1 09:23:49 INFO - functools32 == 3.2.3-2 09:23:49 INFO - jsonschema == 2.5.1 09:23:49 INFO - manifestparser == 1.1 09:23:49 INFO - mozInstall == 1.12 09:23:49 INFO - mozcrash == 1.0 09:23:49 INFO - mozdebug == 0.1 09:23:49 INFO - mozdevice == 0.49 09:23:49 INFO - mozfile == 1.2 09:23:49 INFO - mozhttpd == 0.7 09:23:49 INFO - mozinfo == 0.9 09:23:49 INFO - mozleak == 0.1 09:23:49 INFO - mozlog == 3.4 09:23:49 INFO - moznetwork == 0.27 09:23:49 INFO - mozprocess == 0.23 09:23:49 INFO - mozprofile == 0.28 09:23:49 INFO - mozrunner == 6.13 09:23:49 INFO - mozscreenshot == 0.1 09:23:49 INFO - mozsystemmonitor == 0.3 09:23:49 INFO - moztest == 0.7 09:23:49 INFO - mozversion == 1.4 09:23:49 INFO - psutil == 3.1.1 09:23:49 INFO - requests == 1.2.3 09:23:49 INFO - wsgiref == 0.1.2 09:23:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:49 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 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:49 INFO - 'HOME': '/Users/cltbld', 09:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:49 INFO - 'LOGNAME': 'cltbld', 09:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:49 INFO - 'MOZ_NO_REMOTE': '1', 09:23:49 INFO - 'NO_EM_RESTART': '1', 09:23:49 INFO - 'PAGER': '/bin/cat', 09:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:49 INFO - 'PWD': '/builds/slave/test', 09:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:49 INFO - 'SHELL': '/bin/bash', 09:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:49 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:49 INFO - 'USER': 'cltbld', 09:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:49 INFO - 'XPC_FLAGS': '0x0', 09:23:49 INFO - 'XPC_SERVICE_NAME': '0', 09:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:23:49 INFO - Cleaning up... 09:23:49 INFO - Return code: 0 09:23:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4423f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9101d9c1a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, '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.pflgFVC3gx/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.EFIVBpfJuc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:49 INFO - 'HOME': '/Users/cltbld', 09:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:49 INFO - 'LOGNAME': 'cltbld', 09:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:49 INFO - 'MOZ_NO_REMOTE': '1', 09:23:49 INFO - 'NO_EM_RESTART': '1', 09:23:49 INFO - 'PAGER': '/bin/cat', 09:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:49 INFO - 'PWD': '/builds/slave/test', 09:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:49 INFO - 'SHELL': '/bin/bash', 09:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:23:49 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:49 INFO - 'USER': 'cltbld', 09:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:49 INFO - 'XPC_FLAGS': '0x0', 09:23:49 INFO - 'XPC_SERVICE_NAME': '0', 09:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:23:50 INFO - Cleaning up... 09:23:50 INFO - Return code: 0 09:23:50 INFO - Running post-action listener: _resource_record_post_action 09:23:50 INFO - Running post-action listener: _start_resource_monitoring 09:23:50 INFO - Starting resource monitoring. 09:23:50 INFO - [mozharness: 2016-11-22 17:23:50.098952Z] Finished create-virtualenv step (success) 09:23:50 INFO - [mozharness: 2016-11-22 17:23:50.099929Z] Running install step. 09:23:50 INFO - Running pre-action listener: _resource_record_pre_action 09:23:50 INFO - Running main action method: install 09:23:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:50 INFO - Reading from file tmpfile_stdout 09:23:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:23:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:23:50 INFO - Reading from file tmpfile_stdout 09:23:50 INFO - Output received: 09:23:50 INFO - Usage: mozinstall [options] installer 09:23:50 INFO - Options: 09:23:50 INFO - -h, --help show this help message and exit 09:23:50 INFO - -d DEST, --destination=DEST 09:23:50 INFO - Directory to install application into. [default: 09:23:50 INFO - "/builds/slave/test"] 09:23:50 INFO - --app=APP Application being installed. [default: firefox] 09:23:50 INFO - mkdir: /builds/slave/test/build/application 09:23:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:24:23 INFO - Reading from file tmpfile_stdout 09:24:23 INFO - Output received: 09:24:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:24:23 INFO - Running post-action listener: _resource_record_post_action 09:24:23 INFO - [mozharness: 2016-11-22 17:24:23.847947Z] Finished install step (success) 09:24:23 INFO - [mozharness: 2016-11-22 17:24:23.848077Z] Running run-tests step. 09:24:23 INFO - Running pre-action listener: _resource_record_pre_action 09:24:23 INFO - Running main action method: run_tests 09:24:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:24:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:24:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:24:23 INFO - 2016-11-22 09:24:23.862 screenresolution[1896:18683] starting screenresolution argv=screenresolution get 09:24:23 INFO - 2016-11-22 09:24:23.865 screenresolution[1896:18683] Display 0: 1600x1200x32@60 09:24:23 INFO - 2016-11-22 09:24:23.871 screenresolution[1897:18686] starting screenresolution argv=screenresolution list 09:24:23 INFO - Available Modes on Display 0 09:24:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:24:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:24:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:24:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:24:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:24:24 INFO - Intel Iris: 09:24:24 INFO - Chipset Model: Intel Iris 09:24:24 INFO - Type: GPU 09:24:24 INFO - Bus: Built-In 09:24:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:24:24 INFO - Vendor: Intel (0x8086) 09:24:24 INFO - Device ID: 0x0a2e 09:24:24 INFO - Revision ID: 0x0009 09:24:24 INFO - Displays: 09:24:24 INFO - Display: 09:24:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:24:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:24:24 INFO - Display Serial Number: 1600x1200 60 09:24:24 INFO - Main Display: Yes 09:24:24 INFO - Mirror: Off 09:24:24 INFO - Online: Yes 09:24:24 INFO - Rotation: Supported 09:24:24 INFO - Return code: 0 09:24:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:24:24 INFO - Python 2.7.3 09:24:24 INFO - Return code: 0 09:24:24 INFO - grabbing minidump binary from tooltool 09:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:24 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 0x10a258d30>, '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 0x10a0bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a250b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:24:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:24:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:24:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:24:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:24 INFO - Return code: 0 09:24:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:24:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:24:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:24:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:24:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:24:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:24:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pflgFVC3gx/Render', 09:24:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:24 INFO - 'HOME': '/Users/cltbld', 09:24:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:24 INFO - 'LOGNAME': 'cltbld', 09:24:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:24:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:24:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:24 INFO - 'MOZ_NO_REMOTE': '1', 09:24:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:24:24 INFO - 'NO_EM_RESTART': '1', 09:24:24 INFO - 'PAGER': '/bin/cat', 09:24:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:24 INFO - 'PWD': '/builds/slave/test', 09:24:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:24:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:24 INFO - 'SHELL': '/bin/bash', 09:24:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EFIVBpfJuc/Listeners', 09:24:24 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:24:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:24 INFO - 'USER': 'cltbld', 09:24:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:24 INFO - 'XPC_FLAGS': '0x0', 09:24:24 INFO - 'XPC_SERVICE_NAME': '0', 09:24:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:24:24 INFO - mozversion application_buildid: 20161122065350 09:24:24 INFO - mozversion application_changeset: 1a3194836cb4c3da6ba3a9742a2d25cf26669b55 09:24:24 INFO - mozversion application_display_name: Nightly 09:24:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:24 INFO - mozversion application_name: Firefox 09:24:24 INFO - mozversion application_remotingname: firefox 09:24:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:24:24 INFO - mozversion application_vendor: Mozilla 09:24:24 INFO - mozversion application_version: 53.0a1 09:24:24 INFO - mozversion platform_buildid: 20161122065350 09:24:24 INFO - mozversion platform_changeset: 1a3194836cb4c3da6ba3a9742a2d25cf26669b55 09:24:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:24:24 INFO - mozversion platform_version: 53.0a1 09:24:24 INFO - using testdate: 1479835464 09:24:24 INFO - actual date: 1479835464 09:24:24 INFO - starting webserver on 'localhost:53650' 09:24:24 INFO - SUITE-START | Running 2 tests 09:24:24 INFO - TEST-START | dromaeo_css 09:24:24 INFO - operating with platform_type : mac_ 09:24:24 INFO - Initialising browser for dromaeo_css test... 09:24:24 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpQlwaxu/profile http://localhost:53650/getInfo.html) 09:24:26 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 09:24:26 INFO - PROCESS | 1907 | colorDepth:24 09:24:26 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 09:24:26 INFO - PROCESS | 1907 | __metrics 09:24:27 INFO - PROCESS | 1907 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:24:27 INFO - PROCESS | 1907 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:24:27 INFO - TEST-INFO | 1907: exit 0 09:24:27 INFO - Browser initialized. 09:24:27 INFO - Running cycle 1/1 for dromaeo_css test... 09:24:27 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpQlwaxu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:40 INFO - PROCESS | 1909 | RSS: Main: 244117504 09:24:40 INFO - PROCESS | 1909 | 09:25:31 INFO - PROCESS | 1909 | 1479835531855 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:27:44 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53650/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53650/tests/dromaeo/cssquery-ext.html) 09:27:46 INFO - PROCESS | 1909 | RSS: Main: 397647872 09:27:46 INFO - PROCESS | 1909 | 09:30:49 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53650/tests/dromaeo/cssquery-ext.html (next: http://localhost:53650/tests/dromaeo/cssquery-jquery.html) 09:30:49 INFO - PROCESS | 1909 | RSS: Main: 366206976 09:30:49 INFO - PROCESS | 1909 | 09:33:52 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53650/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53650/tests/dromaeo/cssquery-mootools.html) 09:33:52 INFO - PROCESS | 1909 | RSS: Main: 335577088 09:33:52 INFO - PROCESS | 1909 | 09:36:55 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53650/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53650/tests/dromaeo/cssquery-prototype.html) 09:36:55 INFO - PROCESS | 1909 | RSS: Main: 353230848 09:36:55 INFO - PROCESS | 1909 | 09:39:59 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53650/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53650/tests/dromaeo/cssquery-yui.html) 09:39:59 INFO - PROCESS | 1909 | RSS: Main: 331501568 09:39:59 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53650/tests/dromaeo/cssquery-yui.html (next: http://localhost:53650/tests/dromaeo/cssquery-dojo.html) 09:43:03 INFO - PROCESS | 1909 | RSS: Main: 439087104 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | __start_tp_report 09:43:03 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 09:43:03 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 09:43:03 INFO - PROCESS | 1909 | |i|pagename|runs| 09:43:03 INFO - PROCESS | 1909 | |0;dojo.html;3287.8027318360896;3287.8191699456534;3306.09082502312;3308.0737393529803;3325.800451972882;5459.426760190182;5468.015757163709;5535.197396377523;5581.218629391883;5635.802746903859;5520.6687598744;5524.33707955046;5567.721613919298;5614.157876318544;5630.042892708249;5041.193409054552;5043.394792624872;5049.4698056704065;5076.923846142311;5097.770600322976;5121.28302037715;5183.196604526304;5188.16989281715;5194.35070616173;5242.475752424765;5012.273220383024;5148.459411761767;5156.690598564098;5165.535101840815;5249.107651699202;5020.0963826511215;5075.28945947564;5077.39071311445;5090.465501122403;5139.049275883974;5064.493550644943;5089.745512724386;5099.617528685363;5128.282040514331;5135.152699804527;5543.417941116206;5559.749811258503;5579.7489112990015;5597.692126933017;5608.55131589476;5923.348431672513;5984.611000284967;6009.729562169713;6048.516118710537;6076.605020673642;6116.388361163892;6124.050772130327;6128.908066379008;6138.232720909887;6139.570230083896;5588.133839254922;5638.746256418471;5643.887122257532;5649.632773869726;5678.205051292781;5142.537171654569;6115.331611965994;6159.753609855556;6171.56799024073;6173.722182501798;5932.2584676915385;5945.316288626776;5946.940530594725;5964.7315870785915;5968.37332080134;6031.457168854824;6103.9509299957435;6124.777645458507;6134.263888333339;6135.07973803933;5824.213731146326;5955.880882382328;5996.37038110992;6018.759249630053;6024.8795024100145;5255.658382205144;6066.241719785027;6072.028475443908;6074.787382441594;6077.3314935357075;4776.785044672998;4868.513148685103;4880.6095512358925;4882.623516977543;4942.35749352589;4166.145940082276;4285.957140428618;4315.029118448324;4318.697691161651;4370.235208838504;5595.580331475075;5601.215829783833;5618.353889302701;5625.690587017753;5640.661560306395;6130.264368275835;6130.295016073209;6139.048447490646;6156.5998210116195;6172.753089876444;5342.278792362981;5541.168824676251;5570.805021824298;5593.105103183471;5598.3282006159525;4387.824487020547;5267.3152490176035;5275.709835959059;5282.52457278839;5283.313169287968;6234.9688251558455;6240.781572644935;6248.062790581449;6251.499880009679;6257;6043.2748070231855;6080.635161890301;6097.451229389376;6102;6126.969365153146;6232.719527621359;6242.750289988349;6257.687115644108;6259.248890133031;6264.123022776724;5899.764009439746;5911.970440147772;5913.970430147821;6005.549583781147;6027.126066720389;6125.356837532173;6145.508359331154;6149.446549810538;6159.476444502088;6163.691815409122;5928.614640048383;5929.762809487572;5944.346121926498;5952.672603006876;5979.790707325223;5674.971625141848;5724.3703192647945;5763.452472015152;5785.768569257182;5885.5880088395115;5689.975804355256;5731.254936858182;5745.74144163522;5753.3958934311295;5787.826365208882;5847.1229315603;5870.589058765845;5873.353931067493;5888.941110588839;5893.970530147321;5597;5671.234383358194;5703.515201207943;5720.570957178312;5725.971370143289 09:43:03 INFO - PROCESS | 1909 | |1;ext.html;426.7602614405154;457.8901063744701;474.9501302363254;507.38352901225005;518.7328525809208;28917.855410722943;31312.53031204531;38367.99999999996;39315.80342098293;39422;39476.407853882214;39691.801540992325;39717.60282397175;39738;39980.60019399805;9209.631614735403;9393.859092113624;9403.529823508832;9408.106229908148;9425.905740942571;8404.579771011455;8631.784205394884;8632.093630168836;8708.477491350539;8708.738737837875;11017.669469915916;11244;11287.661370158867;11419.42902854858;11432.771344573104;11087.611933582326;11162.665120046411;11169.162312826571;11175.329480231212;11197.104231661448;8321.542315172663;8555.401121921468;11106.111511079094;11193.440327983568;11234.438278086145;39118.41322380167;39898.800505997286;39993.20013599741;40012.19975600472;40087.59912400868;3272.8199949002797;3286.65490123536;3656.1956369598647;3800.5439347278502;3841.5390153181797;29089.854550727112;29375.2656183595;29472.26319342012;29528.11415657534;29605.555916661266;3098.4732595458827;3162.16202706283;3176.682331766828;3216.4049650814673;3264.5592844965863;363595.18202409084;480078;481452;483404.58297708636;487018;47728.28407573889;48899.75550122298;54470.182947255824;55418.72290638521;55827.72086139624;55428.33714988557;57276.713616431654;57963.4203657958;58005.70997144987;58008.709956449944;15341.463048793239;15485.612859678598;15486.380544778309;15493.302801373966;15516.301766420538;12049.156559040783;12153.966912812333;12155.696107597381;12163.817542736866;12178.330191839534;13392.732145357038;13742.831859292071;13751.037427379988;14941.103533788008;15058.32237549313;6550.443212327005;6675.599464032173;6739.157605299338;6790.490713196431;6853.931460685429;782.9843403131905;800.4876878797575;916.4821875640242;943.6272672294406;1004.2668851738272;26306.605400919;27622.447551048866;27704.584431233547;27966;28165;917.3624331089828;935.5836652689624;1031.8555402243692;1055.4828134214333;1076.553230409387;13674.384652690653;14168.220747858966;14317.35571899267;14434.350454229585;14444.566662999916;637164;643458;644262;644494;644743;47206.05587888223;47995.52004479981;52495.475045249055;53690.73154634202;54540;1216.6106845809434;1224.3082658298033;1257.7547378261334;1264.1151194163938;1266.5883587834176;24514;29432;29558.304150954813;29576.852115739282;30143.698563014088;42686.146277074906;43126.78436607797;43160.78419607882;43167;43274.567254327056;629592;647055;647979;781287;784242;23969.28092157238;24198.395040124138;24212.852329197092;24245.636315455635;24266.75733242645;42309.73070807881;42470.78764606157;43000;43343;43486.56513434825;35815;36105.45841812322;36373.45439818458;37014;37098.44352334771;33272.83363583167;36661.266774663825;36674.44988325124;36692.89921302365;36914.57582150172;34895.47656785201;35169.64830351664;35304.47043294404;35323.47014794831;36378.090547736516;26497.470050599266;31602;32320.191995199366;32406.675933240367;32633.510497343035;23746.762532374454;23982;24233.75766242315;24394.26817195487;24447.14434994838 09:43:03 INFO - PROCESS | 1909 | |2;jquery.html;352.22510476950714;369.7300970291685;369.9482072509848;382.48937668212926;402.1634999201661;14683.853161468382;14970.700585988274;15518.836087293455;19892.801071989277;20176.59646807063;18791.87248765075;19069.61860762784;19714.211431542757;19984.30054948077;19990.70013949792;5698.173764804101;6033.969830150843;6043.063325184581;6052.818415447521;6055.606385584945;4587.15137699525;5955.838611470765;6005.699715014253;6020.428059334378;6020.969895150518;4687.296905464191;5957.1659967604355;5961;5962.582619216657;5974.462298393144;5797.971010144944;5909.852253693649;5915.585908986394;5916.467517923386;5918.704064796786;5106.974465127688;5136.820211292624;5147.6654017488745;5152.407473140562;5361.061814182542;12819.679508012281;12834.358282085952;13048.804267935988;13054.86945130546;13057;1902;2521.7100033496017;2594.740525947409;2627.8423294602194;2734.4804487147576;9688.46713430761;9768.07203315684;10163.33938294016;12064;14951.925240373728;1515.7347464193833;1734.3582874336576;1764.9647007059787;1796.362291386552;1814.0748218408708;99051;106521.46739266331;119898;124795.9999999991;126733.36633316774;9545.856812147824;10778.083862871586;11124.55501779936;11292.43537823122;11495.827562586568;12818.55135070268;13367.933160334136;14321.209848768904;14349.210793406253;14352.56942291712;6349.746010159542;8015.078265999368;8199.262066413932;8337.291330236876;8353.498790072616;7993.440459167919;7993.560354180459;8011.679532818622;8166.142555031755;8180.936478257789;4426.468823741172;4437.977810110993;4469.664775141878;4495.662825288116;4528.139653465831;1401.4464286606733;1554.6890621875443;1808.8462480689288;1859.711744679577;1878.6900161473204;631.1983780598614;704.7040243097911;816.6651672814118;869.752120645613;882.955852207387;19740.50648733793;20019.09914053871;20271.4932126698;20309.59380812405;20582.27962021322;850.8553545897213;853.3147034606726;877.1886005445066;881.7266647339217;909.5770466733001;10176.13502852251;10249.180065594735;10624.096951759188;10625.73435664099;10635.361878287327;549172;558574;559192;566966;571288;99775;119928;128334;128689;136871;815.5106935838568;913.6939125393068;955.7849483866074;994.557421947241;997.0627610046533;18702.625947481247;18894.622107558047;18948.905255473634;19015.049247537845;19023.714644280622;36882.63117368792;45871.770641146584;50426.991460169855;53270.46729532655;53492.73253633707;66827;69120.6543967297;71450.4274786269;74767;75706.6214668923;6615.966920165369;7043.894341584778;7101.928980710333;7209.747658831916;7318.890216646861;8986.68546600866;12185.756284874431;12188.268703877795;12332.630021099381;12524.937375313066;10629.627963021258;10723;10792.190585705946;11104.888951110708;11112.666620001413;8849.716791065388;10573.048425641422;10683.679489615324;10926.72683182895;11058.447077646246;10600.416977066336;10719.464026798785;10891.564337426415;11174.27367221128;11285.492152853305;5864.648121112747;6016.217891674054;7320.341169294789;7345.971563981154;7429.219931907288;8899;10305.175585952957;10409.42748148859;10617.893821062;10997.450127493434 09:43:03 INFO - PROCESS | 1909 | |3;mootools.html;1447.6525633847875;1479.3342995651958;1593.9282732277047;1745.982540174598;1771.548255194926;8108.270255676988;8501.829963400729;8506.404551681384;8521.573921303941;8580.141985801416;6132.693365331726;7721.227877212276;7723.72966946157;8021.799455013613;8272.172782721726;1949.5905859769466;2303.49323148907;2306.2158865985602;2333.7899589036983;2336.5443738471004;2306.7577904320055;2342.0397636968846;2351.177088019188;2359.634256690216;2361.6929799126087;2270.6821045053625;2270.9545809083893;2291.3813270416977;2298.4139044543617;2319.698439202901;2217.1463986365206;2246.3822448826704;2246.472079061419;2248.392933907836;2249.876256805875;2314.90740370385;2342.6837376954063;2345.2495201535517;2348.389418751137;2355.823313251496;8229.25936665697;8418.747437576883;8424.115467875907;8426.283765879907;8467.661293548312;5063.113955057887;5224.138017227153;5230.660007099526;5231.8168864089575;5235.0838603244665;3279.2293810954407;3328.983355083233;3501;3590.0486371111674;3590.1024743814046;1048.3709774135534;1076.569372251098;1080.3140006096185;1083.268793564341;1097.040089921319;9667.758306042335;10357.482125893675;10709.571617135383;10989.505472253768;11034.282771619819;4169.937450938239;4182.811773470201;4278.379634952915;4287.8070486828165;4402.691811573223;8652.961644602688;8659.696910608225;8659.826803463895;8662.177093176138;8677.6528938843;5087.084324821568;5090.447413538832;5091.159958606789;5096.261042148868;5105.9489405106215;3630.2920930418845;4288.699791014661;4735.242361222188;4788.904221915543;4791.161546729309;3549.4143466328283;3577.5885773136424;3620.330238905758;3665.2119794244363;3670.064133645903;978.2760757039881;1101.5869049106584;1107.413071072333;1113.933164010162;1116.2911551165068;798.0941631248459;814.6904176413044;819.8032472206628;842.2714352085525;842.4145219072808;5575.553955683536;5683.54531637468;5767.68277744728;5801.6519008859605;5954.106883967353;781.2382926646587;796.4345314826538;826.1325608111459;848.3340577646518;862.779991102265;2976.749765098664;3486.2822587852247;3645.9635403646153;3739.513863197767;3757.8496860125833;9686.75783105414;10302.381857088449;10302.948485257526;10358.948205259076;10359.430231337197;10134.746631334128;10254.6410875619;10265.178785697126;10270.743231419125;10277.845832312521;6769.646070785764;7322.743703970335;7664.770056898302;7832.255935686101;7876.606169691492;5660.943390566042;5669.603127781181;5823.359430462561;5831.416858314305;5859.560532959959;7690.6539205737;8207.686770116752;9245.399049061789;9277.536123193948;9408.717738467856;9419.105185007413;9503;9535.52322383892;9634.03659634031;9639.132478077006;6527.67361631907;6761.019652150312;6767.627780472121;7040.683169257498;7204.243554426919;7516.924830751623;7859.607019649109;7864.6854125834325;7933;7941.761747147595;6127.938720612737;6817.727290908507;6961.791146265417;6966.5471744336455;6968.616726080114;6528.902066469102;6856.211535673461;6902.06822079033;6937.132858392701;6957.617331046638;6378.808635740936;6403.775867844603;6443.96778016107;6484.708188131451;6502.739890404331;3953.525576930787;3960.772160630214;4423.668224883082;4514.255147900691;4548.658850586286;3975.4816986966166;4614.653900957374;4665.090307390162;4712.740799256074;4793.592544633745 09:43:03 INFO - PROCESS | 1909 | |4;prototype.html;275.72289848702053;296.8011432340332;306.20386993816084;310.7855579650041;314.46540880503153;4737.720815379849;5143.305653736745;5240.554552863011;5241.375173696148;5273.419923808378;4144.171165766844;4660.718302466823;4893.632977526682;4900.87747806305;4947.901041979158;3346.4478361070396;3663.56037275526;3935.3309937310655;3965.841366345357;3986.1629057897876;3368;3885.261800257956;3965.781881996489;3977.9801100994455;3985.9038764339894;3344.8494817733263;3894.435306880501;3924.2347742190286;3926.509186351706;3966.7818269995137;3914.6085391460624;3917.3536366499493;3918.5493668228232;3928.273269445162;3954.288228118923;2964.2510918557937;3367.3938691035605;3401.5748031496064;3403.234272288715;3406.863725450979;4357.477102747659;5022.196448568248;5042.159611741725;5063.468335824759;5069.417017043051;4338.457692788402;4339.839265212393;4369.58488943553;4452.664200739798;4460.264056430685;4507.752073635948;5106.544634779108;5206.817761378372;5357.517823395874;5417.295751552312;3193.499055443938;3440.415129428003;3631.2918980798754;3681.950644066428;3689.3912504437003;4961.677490963112;4993.500649934977;5318.840434786963;5621.952194420011;5664.995105188514;3305.272839975202;3625.3655610268097;3730.7948062856262;3733;3793.6941571316756;3121.4225368306484;3211.550382946389;3796.468494410785;3800.809959502014;3804.565078349858;3582.7281315133273;3693.538307711536;4321.848735294249;4417.690761646654;4421.447319085114;4498.3927169832305;5286.524212820865;5298.867528311746;5368.570928983526;5442.374126975448;1092.6230450494568;1226.9938650306692;1229.1825935752804;1231.4520038582923;1233.2970206981934;792.528445574882;800.4956877167348;801.4590151647615;822.2394285286206;839.2530647723531;3835.252125835491;4463.419755431773;4464.888377790581;4813.0026039194045;4853.3447984522345;4442.644588432918;4707.411573553306;5109.259157422227;5149.871253218699;5229.581633469314;3901.4147877817986;4395.143156948009;4404.713693609905;4566.931496027562;4770.876959752876;2387.570237357292;2682.5052700988267;2748.945021099567;2758.2690586994463;2813.12793034156;4671.5094915034115;4769.2369220925175;5052.271773242482;5431.022415965085;5485.424030476823;4606.907861842745;4854.660173787801;5120.105560942475;5455.972720136374;5496.883699775615;5409.918851217156;5426.941746359422;5428.67427954324;5430.67415955044;5471.50756431923;4670.696404733681;5149.665271757323;5235.554977826775;5270.077736396039;5298.443663415286;4799.256115302133;4886.584640305614;5132.820351287687;5372.973135134299;5607.719614019201;5782.2823273061595;5785.8303136323875;5821.041691649719;5830.2420685310635;5840.970795146167;3156.4160630283473;3742.232842267383;3756.7745935243975;3762.911584070867;3788.962110378861;5338.786448542015;5545.195946587803;5591.748371323381;5629.662220266797;5648.322201335866;4513.638908887347;4848.539388758062;5382.30030096085;5392.784288628568;5415.48552887475;5137.255098010843;5182.263941579613;5376.252717866787;5414.2928535732135;5452.737114097605;4649.790759415902;4687.460941991714;5058.367704036996;5288.762005709676;5294.523492885659;5048.823291184791;5256.9211461827335;5273.419923808455;5278.81524146653;5413.756380962787;4697.483276839476;5273.551748101454;5283.022640811462;5324.600654950972;5375.462453754593 09:43:03 INFO - PROCESS | 1909 | |5;yui.html;695.3290075077549;720.1106633307863;729.8835756361689;795.3676826922596;800.6037011679219;14415.711685766291;14943.85056149441;14954.32705528251;14955.102693838377;14955.327010284536;13395.732085358288;13551.728965420685;13591.38838752256;13599.38802753876;13610.387532561033;647.7344288841576;665.4809248785955;669.7120238297555;782.7221336425572;801.2788490358665;813.446856137826;813.877918312252;825.405707890321;826.4875777018257;828.962696678648;883.8718385834051;884.8009197930478;890.812929284851;893.3255392178928;894.2309613732185;683.9042534045237;709.2871663977721;883.0154377868695;883.8497455432592;887.2325438495687;619.5415392609482;644.0050122560608;645.1709553224084;646.3278190681685;647.8963365861487;13850.584482465541;13865.306734663327;13875.514356997457;13883.930580347034;13903.304834758323;10705.357678539312;12386.504539818396;12419.379031048502;12589.622311330675;12620.873791262153;11479.655610331703;11760.353180575064;11945.402729863472;11998.220115692453;12356.443960021821;4242.257604919127;4275.4228179195725;4287.678424118204;4384.868453946387;4390.034192477649;79280.41439171242;79669.20330796676;80248.19751802465;80516.11742121291;83074.58462707647;10019.599216031424;10082.798344033079;10091.848622270672;10095.697129086138;10326.380417174994;15801.446949356718;18552.628947420977;19030;19047.714284285747;19094.71357929632;7143.107111611049;7248.492605517668;7328.523645962995;7494.288042635941;7642.821795788173;4219.9789001055415;4253.447051883235;4305.203537345601;4383.912321753547;4436.44544431946;3130.2174456385906;3221.4845624700406;3222.9838850806063;3223.8065716057113;3228.6448490666007;615.9234623057855;620.6586377492362;646.8932626116732;648.5622205011692;650.0152269311997;493.64457590534755;513.2352794336412;515.6957395136887;518.4711594173997;543.3208489388264;7961.761147165595;7962.800929976681;8099.392545559107;8255.42212045163;8424.831503369898;508.1412413021981;547.2666626720214;551.1347184919717;555.3779766661285;596.7791916990654;3155.0377134974055;3828.464015037897;4143.461350024478;4317.762523061262;4432.445944256968;90456.547717261;93247;99263;100206;103828;83701.35226335366;84794;86263.56868215618;87254.56372718095;94041;70099.64950175217;83425;84778.57610711906;85784;87852;6670.833229169116;8664.653413863374;9092.408993415407;9114.13415725505;9504.904950950402;43409.04659290276;52669.20996184984;53020.73489632527;53694.46305536895;54843.725781370835;60994.69502652458;73804.63097684477;77195.07012324737;77864.22135778796;78230.82653760312;11889.46497407636;11947.10396720232;12126.151169418055;12168.45241964096;12406.689832754253;13673.86326136726;13719;13787.172769633855;13834.377453014436;14273.57179284623;12369.443375048324;12437.244862312938;12458;12525.185862918881;13303.068785184943;11611.825822612498;12111.697207569528;12258.14193006481;12341.01271898264;12437.62687119388;12262.632121036384;12378.876211238132;12415.289501842739;12426.502939882303;12580.811287830873;10250.469817343055;10480.371177729361;10700.732481688021;10882.183836212158;10919.727006824893;10501.319788833749;10784.245102843039;10806.243562950518;10859;10870.402127882726 09:43:03 INFO - PROCESS | 1909 | __end_tp_report 09:43:03 INFO - PROCESS | 1909 | __start_cc_report 09:43:03 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,1492 09:43:03 INFO - PROCESS | 1909 | __end_cc_report 09:43:03 INFO - PROCESS | 1909 | __startTimestamp1479836583467__endTimestamp 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | ------- Summary: start ------- 09:43:03 INFO - PROCESS | 1909 | Number of tests: 6 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | [#0] dojo.html Cycles:165 Average:5590.34 Median:5720.57 stddev:621.16 (10.9%) stddev-sans-first:596.22 09:43:03 INFO - PROCESS | 1909 | Values: 3287.8 3287.8 3306.1 3308.1 3325.8 5459.4 5468.0 5535.2 5581.2 5635.8 5520.7 5524.3 5567.7 5614.2 5630.0 5041.2 5043.4 5049.5 5076.9 5097.8 5121.3 5183.2 5188.2 5194.4 5242.5 5012.3 5148.5 5156.7 5165.5 5249.1 5020.1 5075.3 5077.4 5090.5 5139.0 5064.5 5089.7 5099.6 5128.3 5135.2 5543.4 5559.7 5579.7 5597.7 5608.6 5923.3 5984.6 6009.7 6048.5 6076.6 6116.4 6124.1 6128.9 6138.2 6139.6 5588.1 5638.7 5643.9 5649.6 5678.2 5142.5 6115.3 6159.8 6171.6 6173.7 5932.3 5945.3 5946.9 5964.7 5968.4 6031.5 6104.0 6124.8 6134.3 6135.1 5824.2 5955.9 5996.4 6018.8 6024.9 5255.7 6066.2 6072.0 6074.8 6077.3 4776.8 4868.5 4880.6 4882.6 4942.4 4166.1 4286.0 4315.0 4318.7 4370.2 5595.6 5601.2 5618.4 5625.7 5640.7 6130.3 6130.3 6139.0 6156.6 6172.8 5342.3 5541.2 5570.8 5593.1 5598.3 4387.8 5267.3 5275.7 5282.5 5283.3 6235.0 6240.8 6248.1 6251.5 6257.0 6043.3 6080.6 6097.5 6102.0 6127.0 6232.7 6242.8 6257.7 6259.2 6264.1 5899.8 5912.0 5914.0 6005.5 6027.1 6125.4 6145.5 6149.4 6159.5 6163.7 5928.6 5929.8 5944.3 5952.7 5979.8 5675.0 5724.4 5763.5 5785.8 5885.6 5690.0 5731.3 5745.7 5753.4 5787.8 5847.1 5870.6 5873.4 5888.9 5894.0 5597.0 5671.2 5703.5 5720.6 5726.0 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | [#1] ext.html Cycles:180 Average:71070.62 Median:20017.14 stddev:164029.02 (819.4%) stddev-sans-first:164403.40 09:43:03 INFO - PROCESS | 1909 | Values: 426.8 457.9 475.0 507.4 518.7 28917.9 31312.5 38368.0 39315.8 39422.0 39476.4 39691.8 39717.6 39738.0 39980.6 9209.6 9393.9 9403.5 9408.1 9425.9 8404.6 8631.8 8632.1 8708.5 8708.7 11017.7 11244.0 11287.7 11419.4 11432.8 11087.6 11162.7 11169.2 11175.3 11197.1 8321.5 8555.4 11106.1 11193.4 11234.4 39118.4 39898.8 39993.2 40012.2 40087.6 3272.8 3286.7 3656.2 3800.5 3841.5 29089.9 29375.3 29472.3 29528.1 29605.6 3098.5 3162.2 3176.7 3216.4 3264.6 363595.2 480078.0 481452.0 483404.6 487018.0 47728.3 48899.8 54470.2 55418.7 55827.7 55428.3 57276.7 57963.4 58005.7 58008.7 15341.5 15485.6 15486.4 15493.3 15516.3 12049.2 12154.0 12155.7 12163.8 12178.3 13392.7 13742.8 13751.0 14941.1 15058.3 6550.4 6675.6 6739.2 6790.5 6853.9 783.0 800.5 916.5 943.6 1004.3 26306.6 27622.4 27704.6 27966.0 28165.0 917.4 935.6 1031.9 1055.5 1076.6 13674.4 14168.2 14317.4 14434.4 14444.6 637164.0 643458.0 644262.0 644494.0 644743.0 47206.1 47995.5 52495.5 53690.7 54540.0 1216.6 1224.3 1257.8 1264.1 1266.6 24514.0 29432.0 29558.3 29576.9 30143.7 42686.1 43126.8 43160.8 43167.0 43274.6 629592.0 647055.0 647979.0 781287.0 784242.0 23969.3 24198.4 24212.9 24245.6 24266.8 42309.7 42470.8 43000.0 43343.0 43486.6 35815.0 36105.5 36373.5 37014.0 37098.4 33272.8 36661.3 36674.4 36692.9 36914.6 34895.5 35169.6 35304.5 35323.5 36378.1 26497.5 31602.0 32320.2 32406.7 32633.5 23746.8 23982.0 24233.8 24394.3 24447.1 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | [#2] jquery.html Cycles:180 Average:32764.62 Median:376.22 stddev:93873.72 (24951.9%) stddev-sans-first:94105.51 09:43:03 INFO - PROCESS | 1909 | Values: 352.2 369.7 369.9 382.5 402.2 14683.9 14970.7 15518.8 19892.8 20176.6 18791.9 19069.6 19714.2 19984.3 19990.7 5698.2 6034.0 6043.1 6052.8 6055.6 4587.2 5955.8 6005.7 6020.4 6021.0 4687.3 5957.2 5961.0 5962.6 5974.5 5798.0 5909.9 5915.6 5916.5 5918.7 5107.0 5136.8 5147.7 5152.4 5361.1 12819.7 12834.4 13048.8 13054.9 13057.0 1902.0 2521.7 2594.7 2627.8 2734.5 9688.5 9768.1 10163.3 12064.0 14951.9 1515.7 1734.4 1765.0 1796.4 1814.1 99051.0 106521.5 119898.0 124796.0 126733.4 9545.9 10778.1 11124.6 11292.4 11495.8 12818.6 13367.9 14321.2 14349.2 14352.6 6349.7 8015.1 8199.3 8337.3 8353.5 7993.4 7993.6 8011.7 8166.1 8180.9 4426.5 4438.0 4469.7 4495.7 4528.1 1401.4 1554.7 1808.8 1859.7 1878.7 631.2 704.7 816.7 869.8 883.0 19740.5 20019.1 20271.5 20309.6 20582.3 850.9 853.3 877.2 881.7 909.6 10176.1 10249.2 10624.1 10625.7 10635.4 549172.0 558574.0 559192.0 566966.0 571288.0 99775.0 119928.0 128334.0 128689.0 136871.0 815.5 913.7 955.8 994.6 997.1 18702.6 18894.6 18948.9 19015.0 19023.7 36882.6 45871.8 50427.0 53270.5 53492.7 66827.0 69120.7 71450.4 74767.0 75706.6 6616.0 7043.9 7101.9 7209.7 7318.9 8986.7 12185.8 12188.3 12332.6 12524.9 10629.6 10723.0 10792.2 11104.9 11112.7 8849.7 10573.0 10683.7 10926.7 11058.4 10600.4 10719.5 10891.6 11174.3 11285.5 5864.6 6016.2 7320.3 7346.0 7429.2 8899.0 10305.2 10409.4 10617.9 10997.5 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | [#3] mootools.html Cycles:180 Average:5317.74 Median:5093.71 stddev:2950.11 (57.9%) stddev-sans-first:2944.05 09:43:03 INFO - PROCESS | 1909 | Values: 1447.7 1479.3 1593.9 1746.0 1771.5 8108.3 8501.8 8506.4 8521.6 8580.1 6132.7 7721.2 7723.7 8021.8 8272.2 1949.6 2303.5 2306.2 2333.8 2336.5 2306.8 2342.0 2351.2 2359.6 2361.7 2270.7 2271.0 2291.4 2298.4 2319.7 2217.1 2246.4 2246.5 2248.4 2249.9 2314.9 2342.7 2345.2 2348.4 2355.8 8229.3 8418.7 8424.1 8426.3 8467.7 5063.1 5224.1 5230.7 5231.8 5235.1 3279.2 3329.0 3501.0 3590.0 3590.1 1048.4 1076.6 1080.3 1083.3 1097.0 9667.8 10357.5 10709.6 10989.5 11034.3 4169.9 4182.8 4278.4 4287.8 4402.7 8653.0 8659.7 8659.8 8662.2 8677.7 5087.1 5090.4 5091.2 5096.3 5105.9 3630.3 4288.7 4735.2 4788.9 4791.2 3549.4 3577.6 3620.3 3665.2 3670.1 978.3 1101.6 1107.4 1113.9 1116.3 798.1 814.7 819.8 842.3 842.4 5575.6 5683.5 5767.7 5801.7 5954.1 781.2 796.4 826.1 848.3 862.8 2976.7 3486.3 3646.0 3739.5 3757.8 9686.8 10302.4 10302.9 10358.9 10359.4 10134.7 10254.6 10265.2 10270.7 10277.8 6769.6 7322.7 7664.8 7832.3 7876.6 5660.9 5669.6 5823.4 5831.4 5859.6 7690.7 8207.7 9245.4 9277.5 9408.7 9419.1 9503.0 9535.5 9634.0 9639.1 6527.7 6761.0 6767.6 7040.7 7204.2 7516.9 7859.6 7864.7 7933.0 7941.8 6127.9 6817.7 6961.8 6966.5 6968.6 6528.9 6856.2 6902.1 6937.1 6957.6 6378.8 6403.8 6444.0 6484.7 6502.7 3953.5 3960.8 4423.7 4514.3 4548.7 3975.5 4614.7 4665.1 4712.7 4793.6 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | [#4] prototype.html Cycles:180 Average:4272.28 Median:4722.57 stddev:1318.43 (27.9%) stddev-sans-first:1287.56 09:43:03 INFO - PROCESS | 1909 | Values: 275.7 296.8 306.2 310.8 314.5 4737.7 5143.3 5240.6 5241.4 5273.4 4144.2 4660.7 4893.6 4900.9 4947.9 3346.4 3663.6 3935.3 3965.8 3986.2 3368.0 3885.3 3965.8 3978.0 3985.9 3344.8 3894.4 3924.2 3926.5 3966.8 3914.6 3917.4 3918.5 3928.3 3954.3 2964.3 3367.4 3401.6 3403.2 3406.9 4357.5 5022.2 5042.2 5063.5 5069.4 4338.5 4339.8 4369.6 4452.7 4460.3 4507.8 5106.5 5206.8 5357.5 5417.3 3193.5 3440.4 3631.3 3682.0 3689.4 4961.7 4993.5 5318.8 5622.0 5665.0 3305.3 3625.4 3730.8 3733.0 3793.7 3121.4 3211.6 3796.5 3800.8 3804.6 3582.7 3693.5 4321.8 4417.7 4421.4 4498.4 5286.5 5298.9 5368.6 5442.4 1092.6 1227.0 1229.2 1231.5 1233.3 792.5 800.5 801.5 822.2 839.3 3835.3 4463.4 4464.9 4813.0 4853.3 4442.6 4707.4 5109.3 5149.9 5229.6 3901.4 4395.1 4404.7 4566.9 4770.9 2387.6 2682.5 2748.9 2758.3 2813.1 4671.5 4769.2 5052.3 5431.0 5485.4 4606.9 4854.7 5120.1 5456.0 5496.9 5409.9 5426.9 5428.7 5430.7 5471.5 4670.7 5149.7 5235.6 5270.1 5298.4 4799.3 4886.6 5132.8 5373.0 5607.7 5782.3 5785.8 5821.0 5830.2 5841.0 3156.4 3742.2 3756.8 3762.9 3789.0 5338.8 5545.2 5591.7 5629.7 5648.3 4513.6 4848.5 5382.3 5392.8 5415.5 5137.3 5182.3 5376.3 5414.3 5452.7 4649.8 4687.5 5058.4 5288.8 5294.5 5048.8 5256.9 5273.4 5278.8 5413.8 4697.5 5273.6 5283.0 5324.6 5375.5 09:43:03 INFO - PROCESS | 1909 | 09:43:03 INFO - PROCESS | 1909 | [#5] yui.html Cycles:180 Average:19396.63 Median:4411.24 stddev:27794.51 (630.1%) stddev-sans-first:27837.01 09:43:03 INFO - PROCESS | 1909 | Values: 695.3 720.1 729.9 795.4 800.6 14415.7 14943.9 14954.3 14955.1 14955.3 13395.7 13551.7 13591.4 13599.4 13610.4 647.7 665.5 669.7 782.7 801.3 813.4 813.9 825.4 826.5 829.0 883.9 884.8 890.8 893.3 894.2 683.9 709.3 883.0 883.8 887.2 619.5 644.0 645.2 646.3 647.9 13850.6 13865.3 13875.5 13883.9 13903.3 10705.4 12386.5 12419.4 12589.6 12620.9 11479.7 11760.4 11945.4 11998.2 12356.4 4242.3 4275.4 4287.7 4384.9 4390.0 79280.4 79669.2 80248.2 80516.1 83074.6 10019.6 10082.8 10091.8 10095.7 10326.4 15801.4 18552.6 19030.0 19047.7 19094.7 7143.1 7248.5 7328.5 7494.3 7642.8 4220.0 4253.4 4305.2 4383.9 4436.4 3130.2 3221.5 3223.0 3223.8 3228.6 615.9 620.7 646.9 648.6 650.0 493.6 513.2 515.7 518.5 543.3 7961.8 7962.8 8099.4 8255.4 8424.8 508.1 547.3 551.1 555.4 596.8 3155.0 3828.5 4143.5 4317.8 4432.4 90456.5 93247.0 99263.0 100206.0 103828.0 83701.4 84794.0 86263.6 87254.6 94041.0 70099.6 83425.0 84778.6 85784.0 87852.0 6670.8 8664.7 9092.4 9114.1 9504.9 43409.0 52669.2 53020.7 53694.5 54843.7 60994.7 73804.6 77195.1 77864.2 78230.8 11889.5 11947.1 12126.2 12168.5 12406.7 13673.9 13719.0 13787.2 13834.4 14273.6 12369.4 12437.2 12458.0 12525.2 13303.1 11611.8 12111.7 12258.1 12341.0 12437.6 12262.6 12378.9 12415.3 12426.5 12580.8 10250.5 10480.4 10700.7 10882.2 10919.7 10501.3 10784.2 10806.2 10859.0 10870.4 09:43:03 INFO - PROCESS | 1909 | -------- Summary: end -------- 09:43:03 INFO - PROCESS | 1909 | 09:43:04 INFO - TEST-INFO | 1909: exit 0 09:43:04 INFO - TEST-OK | dromaeo_css | took 1119771ms 09:43:04 INFO - TEST-START | kraken 09:43:04 INFO - operating with platform_type : mac_ 09:43:04 INFO - Initialising browser for kraken test... 09:43:04 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpduKkkJ/profile http://localhost:53650/getInfo.html) 09:43:06 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 09:43:06 INFO - PROCESS | 1913 | colorDepth:24 09:43:06 INFO - PROCESS | 1913 | Browser inner width/height: 1024/689 09:43:06 INFO - PROCESS | 1913 | __metrics 09:43:06 INFO - PROCESS | 1913 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:43:06 INFO - PROCESS | 1913 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:43:07 INFO - TEST-INFO | 1913: exit 0 09:43:07 INFO - Browser initialized. 09:43:07 INFO - Running cycle 1/1 for kraken test... 09:43:07 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpduKkkJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:19 INFO - PROCESS | 1914 | RSS: Main: 244121600 09:43:19 INFO - PROCESS | 1914 | 09:43:22 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53650/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:23 INFO - PROCESS | 1914 | RSS: Main: 294273024 09:43:23 INFO - PROCESS | 1914 | 09:43:26 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53650/tests/kraken/driver.html?testName=audio-dft) 09:43:26 INFO - PROCESS | 1914 | RSS: Main: 294047744 09:43:26 INFO - PROCESS | 1914 | 09:43:30 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53650/tests/kraken/driver.html?testName=audio-fft) 09:43:30 INFO - PROCESS | 1914 | RSS: Main: 383819776 09:43:30 INFO - PROCESS | 1914 | 09:43:32 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53650/tests/kraken/driver.html?testName=audio-oscillator) 09:43:33 INFO - PROCESS | 1914 | RSS: Main: 288964608 09:43:33 INFO - PROCESS | 1914 | 09:43:35 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53650/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:43:36 INFO - PROCESS | 1914 | RSS: Main: 291405824 09:43:36 INFO - PROCESS | 1914 | 09:43:38 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53650/tests/kraken/driver.html?testName=imaging-darkroom) 09:43:39 INFO - PROCESS | 1914 | RSS: Main: 333037568 09:43:39 INFO - PROCESS | 1914 | 09:43:42 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53650/tests/kraken/driver.html?testName=imaging-desaturate) 09:43:43 INFO - PROCESS | 1914 | RSS: Main: 335327232 09:43:43 INFO - PROCESS | 1914 | 09:43:45 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53650/tests/kraken/driver.html?testName=json-parse-financial) 09:43:46 INFO - PROCESS | 1914 | RSS: Main: 334168064 09:43:46 INFO - PROCESS | 1914 | 09:43:48 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53650/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:43:48 INFO - PROCESS | 1914 | RSS: Main: 310636544 09:43:48 INFO - PROCESS | 1914 | 09:43:50 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:43:51 INFO - PROCESS | 1914 | RSS: Main: 311623680 09:43:51 INFO - PROCESS | 1914 | 09:43:53 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:43:54 INFO - PROCESS | 1914 | RSS: Main: 313970688 09:43:54 INFO - PROCESS | 1914 | 09:43:57 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:43:58 INFO - PROCESS | 1914 | RSS: Main: 331264000 09:43:58 INFO - PROCESS | 1914 | 09:44:00 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:01 INFO - PROCESS | 1914 | RSS: Main: 324280320 09:44:01 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53650/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53650/tests/kraken/driver.html?testName=ai-astar) 09:44:03 INFO - PROCESS | 1914 | RSS: Main: 367910912 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | __start_tp_report 09:44:03 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 09:44:03 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 09:44:03 INFO - PROCESS | 1914 | |i|pagename|runs| 09:44:03 INFO - PROCESS | 1914 | |0;ai-astar;113;113;115;114;113;115;113;114;111;113 09:44:03 INFO - PROCESS | 1914 | |1;audio-beat-detection;154;113;111;151;113;113;111;113;144;112 09:44:03 INFO - PROCESS | 1914 | |2;audio-dft;182;187;184;187;184;182;182;180;184;185 09:44:03 INFO - PROCESS | 1914 | |3;audio-fft;83;51;62;108;88;84;60;58;84;58 09:44:03 INFO - PROCESS | 1914 | |4;audio-oscillator;88;71;96;70;88;100;126;76;69;92 09:44:03 INFO - PROCESS | 1914 | |5;imaging-gaussian-blur;88;86;87;87;88;84;90;86;88;86 09:44:03 INFO - PROCESS | 1914 | |6;imaging-darkroom;95;96;96;95;123;164;96;97;98;96 09:44:03 INFO - PROCESS | 1914 | |7;imaging-desaturate;85;87;88;85;88;83;83;87;88;88 09:44:03 INFO - PROCESS | 1914 | |8;json-parse-financial;57;54;54;53;54;54;53;53;55;53 09:44:03 INFO - PROCESS | 1914 | |9;json-stringify-tinderbox;73;72;70;74;75;94;103;100;76;71 09:44:03 INFO - PROCESS | 1914 | |10;stanford-crypto-aes;87;86;88;87;84;86;82;84;88;88 09:44:03 INFO - PROCESS | 1914 | |11;stanford-crypto-ccm;150;152;164;151;146;168;163;161;225;166 09:44:03 INFO - PROCESS | 1914 | |12;stanford-crypto-pbkdf2;173;175;174;174;174;174;171;170;171;173 09:44:03 INFO - PROCESS | 1914 | |13;stanford-crypto-sha256-iterative;66;68;69;67;68;67;68;67;67;67 09:44:03 INFO - PROCESS | 1914 | __end_tp_report 09:44:03 INFO - PROCESS | 1914 | __start_cc_report 09:44:03 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,1960 09:44:03 INFO - PROCESS | 1914 | __end_cc_report 09:44:03 INFO - PROCESS | 1914 | __startTimestamp1479836643548__endTimestamp 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | ------- Summary: start ------- 09:44:03 INFO - PROCESS | 1914 | Number of tests: 14 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.50 stddev:1.17 (1.0%) stddev-sans-first:1.24 09:44:03 INFO - PROCESS | 1914 | Values: 113.0 113.0 115.0 114.0 113.0 115.0 113.0 114.0 111.0 113.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:113.00 stddev:18.23 (16.1%) stddev-sans-first:15.65 09:44:03 INFO - PROCESS | 1914 | Values: 154.0 113.0 111.0 151.0 113.0 113.0 111.0 113.0 144.0 112.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:2.26 (1.2%) stddev-sans-first:2.32 09:44:03 INFO - PROCESS | 1914 | Values: 182.0 187.0 184.0 187.0 184.0 182.0 182.0 180.0 184.0 185.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#3] audio-fft Cycles:10 Average:73.60 Median:72.50 stddev:18.30 (25.2%) stddev-sans-first:19.09 09:44:03 INFO - PROCESS | 1914 | Values: 83.0 51.0 62.0 108.0 88.0 84.0 60.0 58.0 84.0 58.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:82.00 stddev:17.59 (21.5%) stddev-sans-first:18.66 09:44:03 INFO - PROCESS | 1914 | Values: 88.0 71.0 96.0 70.0 88.0 100.0 126.0 76.0 69.0 92.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.50 stddev:1.63 (1.9%) stddev-sans-first:1.69 09:44:03 INFO - PROCESS | 1914 | Values: 88.0 86.0 87.0 87.0 88.0 84.0 90.0 86.0 88.0 86.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#6] imaging-darkroom Cycles:10 Average:105.60 Median:96.00 stddev:22.21 (23.1%) stddev-sans-first:23.22 09:44:03 INFO - PROCESS | 1914 | Values: 95.0 96.0 96.0 95.0 123.0 164.0 96.0 97.0 98.0 96.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:87.50 stddev:2.04 (2.3%) stddev-sans-first:2.12 09:44:03 INFO - PROCESS | 1914 | Values: 85.0 87.0 88.0 85.0 88.0 83.0 83.0 87.0 88.0 88.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.25 (2.3%) stddev-sans-first:0.71 09:44:03 INFO - PROCESS | 1914 | Values: 57.0 54.0 54.0 53.0 54.0 54.0 53.0 53.0 55.0 53.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:73.50 stddev:12.87 (17.5%) stddev-sans-first:13.33 09:44:03 INFO - PROCESS | 1914 | Values: 73.0 72.0 70.0 74.0 75.0 94.0 103.0 100.0 76.0 71.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:87.00 stddev:2.05 (2.4%) stddev-sans-first:2.15 09:44:03 INFO - PROCESS | 1914 | Values: 87.0 86.0 88.0 87.0 84.0 86.0 82.0 84.0 88.0 88.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#11] stanford-crypto-ccm Cycles:10 Average:164.60 Median:163.50 stddev:22.56 (13.8%) stddev-sans-first:23.30 09:44:03 INFO - PROCESS | 1914 | Values: 150.0 152.0 164.0 151.0 146.0 168.0 163.0 161.0 225.0 166.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:174.00 stddev:1.66 (1.0%) stddev-sans-first:1.76 09:44:03 INFO - PROCESS | 1914 | Values: 173.0 175.0 174.0 174.0 174.0 174.0 171.0 170.0 171.0 173.0 09:44:03 INFO - PROCESS | 1914 | 09:44:03 INFO - PROCESS | 1914 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:0.84 (1.2%) stddev-sans-first:0.73 09:44:03 INFO - PROCESS | 1914 | Values: 66.0 68.0 69.0 67.0 68.0 67.0 68.0 67.0 67.0 67.0 09:44:03 INFO - PROCESS | 1914 | -------- Summary: end -------- 09:44:03 INFO - PROCESS | 1914 | 09:44:04 INFO - TEST-INFO | 1914: exit 0 09:44:04 INFO - TEST-OK | kraken | took 60426ms 09:44:04 INFO - SUITE-END | took 1180s 09:44:04 INFO - Completed test suite (00:19:40) 09:44:04 INFO - javascript benchmark 09:44:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.8027318360896, 3287.8191699456534, 3306.09082502312, 3308.0737393529803, 3325.800451972882, 5459.426760190182, 5468.015757163709, 5535.197396377523, 5581.218629391883, 5635.802746903859, 5520.6687598744, 5524.33707955046, 5567.721613919298, 5614.157876318544, 5630.042892708249, 5041.193409054552, 5043.394792624872, 5049.4698056704065, 5076.923846142311, 5097.770600322976, 5121.28302037715, 5183.196604526304, 5188.16989281715, 5194.35070616173, 5242.475752424765, 5012.273220383024, 5148.459411761767, 5156.690598564098, 5165.535101840815, 5249.107651699202, 5020.0963826511215, 5075.28945947564, 5077.39071311445, 5090.465501122403, 5139.049275883974, 5064.493550644943, 5089.745512724386, 5099.617528685363, 5128.282040514331, 5135.152699804527, 5543.417941116206, 5559.749811258503, 5579.7489112990015, 5597.692126933017, 5608.55131589476, 5923.348431672513, 5984.611000284967, 6009.729562169713, 6048.516118710537, 6076.605020673642, 6116.388361163892, 6124.050772130327, 6128.908066379008, 6138.232720909887, 6139.570230083896, 5588.133839254922, 5638.746256418471, 5643.887122257532, 5649.632773869726, 5678.205051292781, 5142.537171654569, 6115.331611965994, 6159.753609855556, 6171.56799024073, 6173.722182501798, 5932.2584676915385, 5945.316288626776, 5946.940530594725, 5964.7315870785915, 5968.37332080134, 6031.457168854824, 6103.9509299957435, 6124.777645458507, 6134.263888333339, 6135.07973803933, 5824.213731146326, 5955.880882382328, 5996.37038110992, 6018.759249630053, 6024.8795024100145, 5255.658382205144, 6066.241719785027, 6072.028475443908, 6074.787382441594, 6077.3314935357075, 4776.785044672998, 4868.513148685103, 4880.6095512358925, 4882.623516977543, 4942.35749352589, 4166.145940082276, 4285.957140428618, 4315.029118448324, 4318.697691161651, 4370.235208838504, 5595.580331475075, 5601.215829783833, 5618.353889302701, 5625.690587017753, 5640.661560306395, 6130.264368275835, 6130.295016073209, 6139.048447490646, 6156.5998210116195, 6172.753089876444, 5342.278792362981, 5541.168824676251, 5570.805021824298, 5593.105103183471, 5598.3282006159525, 4387.824487020547, 5267.3152490176035, 5275.709835959059, 5282.52457278839, 5283.313169287968, 6234.9688251558455, 6240.781572644935, 6248.062790581449, 6251.499880009679, 6257.0, 6043.2748070231855, 6080.635161890301, 6097.451229389376, 6102.0, 6126.969365153146, 6232.719527621359, 6242.750289988349, 6257.687115644108, 6259.248890133031, 6264.123022776724, 5899.764009439746, 5911.970440147772, 5913.970430147821, 6005.549583781147, 6027.126066720389, 6125.356837532173, 6145.508359331154, 6149.446549810538, 6159.476444502088, 6163.691815409122, 5928.614640048383, 5929.762809487572, 5944.346121926498, 5952.672603006876, 5979.790707325223, 5674.971625141848, 5724.3703192647945, 5763.452472015152, 5785.768569257182, 5885.5880088395115, 5689.975804355256, 5731.254936858182, 5745.74144163522, 5753.3958934311295, 5787.826365208882, 5847.1229315603, 5870.589058765845, 5873.353931067493, 5888.941110588839, 5893.970530147321, 5597.0, 5671.234383358194, 5703.515201207943, 5720.570957178312, 5725.971370143289], "value": 5551.069768673419, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.7602614405154, 457.8901063744701, 474.9501302363254, 507.38352901225005, 518.7328525809208, 28917.855410722943, 31312.53031204531, 38367.99999999996, 39315.80342098293, 39422.0, 39476.407853882214, 39691.801540992325, 39717.60282397175, 39738.0, 39980.60019399805, 9209.631614735403, 9393.859092113624, 9403.529823508832, 9408.106229908148, 9425.905740942571, 8404.579771011455, 8631.784205394884, 8632.093630168836, 8708.477491350539, 8708.738737837875, 11017.669469915916, 11244.0, 11287.661370158867, 11419.42902854858, 11432.771344573104, 11087.611933582326, 11162.665120046411, 11169.162312826571, 11175.329480231212, 11197.104231661448, 8321.542315172663, 8555.401121921468, 11106.111511079094, 11193.440327983568, 11234.438278086145, 39118.41322380167, 39898.800505997286, 39993.20013599741, 40012.19975600472, 40087.59912400868, 3272.8199949002797, 3286.65490123536, 3656.1956369598647, 3800.5439347278502, 3841.5390153181797, 29089.854550727112, 29375.2656183595, 29472.26319342012, 29528.11415657534, 29605.555916661266, 3098.4732595458827, 3162.16202706283, 3176.682331766828, 3216.4049650814673, 3264.5592844965863, 363595.18202409084, 480078.0, 481452.0, 483404.58297708636, 487018.0, 47728.28407573889, 48899.75550122298, 54470.182947255824, 55418.72290638521, 55827.72086139624, 55428.33714988557, 57276.713616431654, 57963.4203657958, 58005.70997144987, 58008.709956449944, 15341.463048793239, 15485.612859678598, 15486.380544778309, 15493.302801373966, 15516.301766420538, 12049.156559040783, 12153.966912812333, 12155.696107597381, 12163.817542736866, 12178.330191839534, 13392.732145357038, 13742.831859292071, 13751.037427379988, 14941.103533788008, 15058.32237549313, 6550.443212327005, 6675.599464032173, 6739.157605299338, 6790.490713196431, 6853.931460685429, 782.9843403131905, 800.4876878797575, 916.4821875640242, 943.6272672294406, 1004.2668851738272, 26306.605400919, 27622.447551048866, 27704.584431233547, 27966.0, 28165.0, 917.3624331089828, 935.5836652689624, 1031.8555402243692, 1055.4828134214333, 1076.553230409387, 13674.384652690653, 14168.220747858966, 14317.35571899267, 14434.350454229585, 14444.566662999916, 637164.0, 643458.0, 644262.0, 644494.0, 644743.0, 47206.05587888223, 47995.52004479981, 52495.475045249055, 53690.73154634202, 54540.0, 1216.6106845809434, 1224.3082658298033, 1257.7547378261334, 1264.1151194163938, 1266.5883587834176, 24514.0, 29432.0, 29558.304150954813, 29576.852115739282, 30143.698563014088, 42686.146277074906, 43126.78436607797, 43160.78419607882, 43167.0, 43274.567254327056, 629592.0, 647055.0, 647979.0, 781287.0, 784242.0, 23969.28092157238, 24198.395040124138, 24212.852329197092, 24245.636315455635, 24266.75733242645, 42309.73070807881, 42470.78764606157, 43000.0, 43343.0, 43486.56513434825, 35815.0, 36105.45841812322, 36373.45439818458, 37014.0, 37098.44352334771, 33272.83363583167, 36661.266774663825, 36674.44988325124, 36692.89921302365, 36914.57582150172, 34895.47656785201, 35169.64830351664, 35304.47043294404, 35323.47014794831, 36378.090547736516, 26497.470050599266, 31602.0, 32320.191995199366, 32406.675933240367, 32633.510497343035, 23746.762532374454, 23982.0, 24233.75766242315, 24394.26817195487, 24447.14434994838], "value": 18968.035564777063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.22510476950714, 369.7300970291685, 369.9482072509848, 382.48937668212926, 402.1634999201661, 14683.853161468382, 14970.700585988274, 15518.836087293455, 19892.801071989277, 20176.59646807063, 18791.87248765075, 19069.61860762784, 19714.211431542757, 19984.30054948077, 19990.70013949792, 5698.173764804101, 6033.969830150843, 6043.063325184581, 6052.818415447521, 6055.606385584945, 4587.15137699525, 5955.838611470765, 6005.699715014253, 6020.428059334378, 6020.969895150518, 4687.296905464191, 5957.1659967604355, 5961.0, 5962.582619216657, 5974.462298393144, 5797.971010144944, 5909.852253693649, 5915.585908986394, 5916.467517923386, 5918.704064796786, 5106.974465127688, 5136.820211292624, 5147.6654017488745, 5152.407473140562, 5361.061814182542, 12819.679508012281, 12834.358282085952, 13048.804267935988, 13054.86945130546, 13057.0, 1902.0, 2521.7100033496017, 2594.740525947409, 2627.8423294602194, 2734.4804487147576, 9688.46713430761, 9768.07203315684, 10163.33938294016, 12064.0, 14951.925240373728, 1515.7347464193833, 1734.3582874336576, 1764.9647007059787, 1796.362291386552, 1814.0748218408708, 99051.0, 106521.46739266331, 119898.0, 124795.9999999991, 126733.36633316774, 9545.856812147824, 10778.083862871586, 11124.55501779936, 11292.43537823122, 11495.827562586568, 12818.55135070268, 13367.933160334136, 14321.209848768904, 14349.210793406253, 14352.56942291712, 6349.746010159542, 8015.078265999368, 8199.262066413932, 8337.291330236876, 8353.498790072616, 7993.440459167919, 7993.560354180459, 8011.679532818622, 8166.142555031755, 8180.936478257789, 4426.468823741172, 4437.977810110993, 4469.664775141878, 4495.662825288116, 4528.139653465831, 1401.4464286606733, 1554.6890621875443, 1808.8462480689288, 1859.711744679577, 1878.6900161473204, 631.1983780598614, 704.7040243097911, 816.6651672814118, 869.752120645613, 882.955852207387, 19740.50648733793, 20019.09914053871, 20271.4932126698, 20309.59380812405, 20582.27962021322, 850.8553545897213, 853.3147034606726, 877.1886005445066, 881.7266647339217, 909.5770466733001, 10176.13502852251, 10249.180065594735, 10624.096951759188, 10625.73435664099, 10635.361878287327, 549172.0, 558574.0, 559192.0, 566966.0, 571288.0, 99775.0, 119928.0, 128334.0, 128689.0, 136871.0, 815.5106935838568, 913.6939125393068, 955.7849483866074, 994.557421947241, 997.0627610046533, 18702.625947481247, 18894.622107558047, 18948.905255473634, 19015.049247537845, 19023.714644280622, 36882.63117368792, 45871.770641146584, 50426.991460169855, 53270.46729532655, 53492.73253633707, 66827.0, 69120.6543967297, 71450.4274786269, 74767.0, 75706.6214668923, 6615.966920165369, 7043.894341584778, 7101.928980710333, 7209.747658831916, 7318.890216646861, 8986.68546600866, 12185.756284874431, 12188.268703877795, 12332.630021099381, 12524.937375313066, 10629.627963021258, 10723.0, 10792.190585705946, 11104.888951110708, 11112.666620001413, 8849.716791065388, 10573.048425641422, 10683.679489615324, 10926.72683182895, 11058.447077646246, 10600.416977066336, 10719.464026798785, 10891.564337426415, 11174.27367221128, 11285.492152853305, 5864.648121112747, 6016.217891674054, 7320.341169294789, 7345.971563981154, 7429.219931907288, 8899.0, 10305.175585952957, 10409.42748148859, 10617.893821062, 10997.450127493434], "value": 8926.301648226661, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.6525633847875, 1479.3342995651958, 1593.9282732277047, 1745.982540174598, 1771.548255194926, 8108.270255676988, 8501.829963400729, 8506.404551681384, 8521.573921303941, 8580.141985801416, 6132.693365331726, 7721.227877212276, 7723.72966946157, 8021.799455013613, 8272.172782721726, 1949.5905859769466, 2303.49323148907, 2306.2158865985602, 2333.7899589036983, 2336.5443738471004, 2306.7577904320055, 2342.0397636968846, 2351.177088019188, 2359.634256690216, 2361.6929799126087, 2270.6821045053625, 2270.9545809083893, 2291.3813270416977, 2298.4139044543617, 2319.698439202901, 2217.1463986365206, 2246.3822448826704, 2246.472079061419, 2248.392933907836, 2249.876256805875, 2314.90740370385, 2342.6837376954063, 2345.2495201535517, 2348.389418751137, 2355.823313251496, 8229.25936665697, 8418.747437576883, 8424.115467875907, 8426.283765879907, 8467.661293548312, 5063.113955057887, 5224.138017227153, 5230.660007099526, 5231.8168864089575, 5235.0838603244665, 3279.2293810954407, 3328.983355083233, 3501.0, 3590.0486371111674, 3590.1024743814046, 1048.3709774135534, 1076.569372251098, 1080.3140006096185, 1083.268793564341, 1097.040089921319, 9667.758306042335, 10357.482125893675, 10709.571617135383, 10989.505472253768, 11034.282771619819, 4169.937450938239, 4182.811773470201, 4278.379634952915, 4287.8070486828165, 4402.691811573223, 8652.961644602688, 8659.696910608225, 8659.826803463895, 8662.177093176138, 8677.6528938843, 5087.084324821568, 5090.447413538832, 5091.159958606789, 5096.261042148868, 5105.9489405106215, 3630.2920930418845, 4288.699791014661, 4735.242361222188, 4788.904221915543, 4791.161546729309, 3549.4143466328283, 3577.5885773136424, 3620.330238905758, 3665.2119794244363, 3670.064133645903, 978.2760757039881, 1101.5869049106584, 1107.413071072333, 1113.933164010162, 1116.2911551165068, 798.0941631248459, 814.6904176413044, 819.8032472206628, 842.2714352085525, 842.4145219072808, 5575.553955683536, 5683.54531637468, 5767.68277744728, 5801.6519008859605, 5954.106883967353, 781.2382926646587, 796.4345314826538, 826.1325608111459, 848.3340577646518, 862.779991102265, 2976.749765098664, 3486.2822587852247, 3645.9635403646153, 3739.513863197767, 3757.8496860125833, 9686.75783105414, 10302.381857088449, 10302.948485257526, 10358.948205259076, 10359.430231337197, 10134.746631334128, 10254.6410875619, 10265.178785697126, 10270.743231419125, 10277.845832312521, 6769.646070785764, 7322.743703970335, 7664.770056898302, 7832.255935686101, 7876.606169691492, 5660.943390566042, 5669.603127781181, 5823.359430462561, 5831.416858314305, 5859.560532959959, 7690.6539205737, 8207.686770116752, 9245.399049061789, 9277.536123193948, 9408.717738467856, 9419.105185007413, 9503.0, 9535.52322383892, 9634.03659634031, 9639.132478077006, 6527.67361631907, 6761.019652150312, 6767.627780472121, 7040.683169257498, 7204.243554426919, 7516.924830751623, 7859.607019649109, 7864.6854125834325, 7933.0, 7941.761747147595, 6127.938720612737, 6817.727290908507, 6961.791146265417, 6966.5471744336455, 6968.616726080114, 6528.902066469102, 6856.211535673461, 6902.06822079033, 6937.132858392701, 6957.617331046638, 6378.808635740936, 6403.775867844603, 6443.96778016107, 6484.708188131451, 6502.739890404331, 3953.525576930787, 3960.772160630214, 4423.668224883082, 4514.255147900691, 4548.658850586286, 3975.4816986966166, 4614.653900957374, 4665.090307390162, 4712.740799256074, 4793.592544633745], "value": 4291.423370668717, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.72289848702053, 296.8011432340332, 306.20386993816084, 310.7855579650041, 314.46540880503153, 4737.720815379849, 5143.305653736745, 5240.554552863011, 5241.375173696148, 5273.419923808378, 4144.171165766844, 4660.718302466823, 4893.632977526682, 4900.87747806305, 4947.901041979158, 3346.4478361070396, 3663.56037275526, 3935.3309937310655, 3965.841366345357, 3986.1629057897876, 3368.0, 3885.261800257956, 3965.781881996489, 3977.9801100994455, 3985.9038764339894, 3344.8494817733263, 3894.435306880501, 3924.2347742190286, 3926.509186351706, 3966.7818269995137, 3914.6085391460624, 3917.3536366499493, 3918.5493668228232, 3928.273269445162, 3954.288228118923, 2964.2510918557937, 3367.3938691035605, 3401.5748031496064, 3403.234272288715, 3406.863725450979, 4357.477102747659, 5022.196448568248, 5042.159611741725, 5063.468335824759, 5069.417017043051, 4338.457692788402, 4339.839265212393, 4369.58488943553, 4452.664200739798, 4460.264056430685, 4507.752073635948, 5106.544634779108, 5206.817761378372, 5357.517823395874, 5417.295751552312, 3193.499055443938, 3440.415129428003, 3631.2918980798754, 3681.950644066428, 3689.3912504437003, 4961.677490963112, 4993.500649934977, 5318.840434786963, 5621.952194420011, 5664.995105188514, 3305.272839975202, 3625.3655610268097, 3730.7948062856262, 3733.0, 3793.6941571316756, 3121.4225368306484, 3211.550382946389, 3796.468494410785, 3800.809959502014, 3804.565078349858, 3582.7281315133273, 3693.538307711536, 4321.848735294249, 4417.690761646654, 4421.447319085114, 4498.3927169832305, 5286.524212820865, 5298.867528311746, 5368.570928983526, 5442.374126975448, 1092.6230450494568, 1226.9938650306692, 1229.1825935752804, 1231.4520038582923, 1233.2970206981934, 792.528445574882, 800.4956877167348, 801.4590151647615, 822.2394285286206, 839.2530647723531, 3835.252125835491, 4463.419755431773, 4464.888377790581, 4813.0026039194045, 4853.3447984522345, 4442.644588432918, 4707.411573553306, 5109.259157422227, 5149.871253218699, 5229.581633469314, 3901.4147877817986, 4395.143156948009, 4404.713693609905, 4566.931496027562, 4770.876959752876, 2387.570237357292, 2682.5052700988267, 2748.945021099567, 2758.2690586994463, 2813.12793034156, 4671.5094915034115, 4769.2369220925175, 5052.271773242482, 5431.022415965085, 5485.424030476823, 4606.907861842745, 4854.660173787801, 5120.105560942475, 5455.972720136374, 5496.883699775615, 5409.918851217156, 5426.941746359422, 5428.67427954324, 5430.67415955044, 5471.50756431923, 4670.696404733681, 5149.665271757323, 5235.554977826775, 5270.077736396039, 5298.443663415286, 4799.256115302133, 4886.584640305614, 5132.820351287687, 5372.973135134299, 5607.719614019201, 5782.2823273061595, 5785.8303136323875, 5821.041691649719, 5830.2420685310635, 5840.970795146167, 3156.4160630283473, 3742.232842267383, 3756.7745935243975, 3762.911584070867, 3788.962110378861, 5338.786448542015, 5545.195946587803, 5591.748371323381, 5629.662220266797, 5648.322201335866, 4513.638908887347, 4848.539388758062, 5382.30030096085, 5392.784288628568, 5415.48552887475, 5137.255098010843, 5182.263941579613, 5376.252717866787, 5414.2928535732135, 5452.737114097605, 4649.790759415902, 4687.460941991714, 5058.367704036996, 5288.762005709676, 5294.523492885659, 5048.823291184791, 5256.9211461827335, 5273.419923808455, 5278.81524146653, 5413.756380962787, 4697.483276839476, 5273.551748101454, 5283.022640811462, 5324.600654950972, 5375.462453754593], "value": 3852.419873306137, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.3290075077549, 720.1106633307863, 729.8835756361689, 795.3676826922596, 800.6037011679219, 14415.711685766291, 14943.85056149441, 14954.32705528251, 14955.102693838377, 14955.327010284536, 13395.732085358288, 13551.728965420685, 13591.38838752256, 13599.38802753876, 13610.387532561033, 647.7344288841576, 665.4809248785955, 669.7120238297555, 782.7221336425572, 801.2788490358665, 813.446856137826, 813.877918312252, 825.405707890321, 826.4875777018257, 828.962696678648, 883.8718385834051, 884.8009197930478, 890.812929284851, 893.3255392178928, 894.2309613732185, 683.9042534045237, 709.2871663977721, 883.0154377868695, 883.8497455432592, 887.2325438495687, 619.5415392609482, 644.0050122560608, 645.1709553224084, 646.3278190681685, 647.8963365861487, 13850.584482465541, 13865.306734663327, 13875.514356997457, 13883.930580347034, 13903.304834758323, 10705.357678539312, 12386.504539818396, 12419.379031048502, 12589.622311330675, 12620.873791262153, 11479.655610331703, 11760.353180575064, 11945.402729863472, 11998.220115692453, 12356.443960021821, 4242.257604919127, 4275.4228179195725, 4287.678424118204, 4384.868453946387, 4390.034192477649, 79280.41439171242, 79669.20330796676, 80248.19751802465, 80516.11742121291, 83074.58462707647, 10019.599216031424, 10082.798344033079, 10091.848622270672, 10095.697129086138, 10326.380417174994, 15801.446949356718, 18552.628947420977, 19030.0, 19047.714284285747, 19094.71357929632, 7143.107111611049, 7248.492605517668, 7328.523645962995, 7494.288042635941, 7642.821795788173, 4219.9789001055415, 4253.447051883235, 4305.203537345601, 4383.912321753547, 4436.44544431946, 3130.2174456385906, 3221.4845624700406, 3222.9838850806063, 3223.8065716057113, 3228.6448490666007, 615.9234623057855, 620.6586377492362, 646.8932626116732, 648.5622205011692, 650.0152269311997, 493.64457590534755, 513.2352794336412, 515.6957395136887, 518.4711594173997, 543.3208489388264, 7961.761147165595, 7962.800929976681, 8099.392545559107, 8255.42212045163, 8424.831503369898, 508.1412413021981, 547.2666626720214, 551.1347184919717, 555.3779766661285, 596.7791916990654, 3155.0377134974055, 3828.464015037897, 4143.461350024478, 4317.762523061262, 4432.445944256968, 90456.547717261, 93247.0, 99263.0, 100206.0, 103828.0, 83701.35226335366, 84794.0, 86263.56868215618, 87254.56372718095, 94041.0, 70099.64950175217, 83425.0, 84778.57610711906, 85784.0, 87852.0, 6670.833229169116, 8664.653413863374, 9092.408993415407, 9114.13415725505, 9504.904950950402, 43409.04659290276, 52669.20996184984, 53020.73489632527, 53694.46305536895, 54843.725781370835, 60994.69502652458, 73804.63097684477, 77195.07012324737, 77864.22135778796, 78230.82653760312, 11889.46497407636, 11947.10396720232, 12126.151169418055, 12168.45241964096, 12406.689832754253, 13673.86326136726, 13719.0, 13787.172769633855, 13834.377453014436, 14273.57179284623, 12369.443375048324, 12437.244862312938, 12458.0, 12525.185862918881, 13303.068785184943, 11611.825822612498, 12111.697207569528, 12258.14193006481, 12341.01271898264, 12437.62687119388, 12262.632121036384, 12378.876211238132, 12415.289501842739, 12426.502939882303, 12580.811287830873, 10250.469817343055, 10480.371177729361, 10700.732481688021, 10882.183836212158, 10919.727006824893, 10501.319788833749, 10784.245102843039, 10806.243562950518, 10859.0, 10870.402127882726], "value": 7001.058128223579, "unit": "score"}], "name": "dromaeo_css", "value": 6909.335956623018, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 114.0, 113.0, 115.0, 113.0, 114.0, 111.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 113.0, 111.0, 151.0, 113.0, 113.0, 111.0, 113.0, 144.0, 112.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 184.0, 187.0, 184.0, 182.0, 182.0, 180.0, 184.0, 185.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 51.0, 62.0, 108.0, 88.0, 84.0, 60.0, 58.0, 84.0, 58.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 96.0, 70.0, 88.0, 100.0, 126.0, 76.0, 69.0, 92.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 87.0, 88.0, 84.0, 90.0, 86.0, 88.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 123.0, 164.0, 96.0, 97.0, 98.0, 96.0], "value": 105.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 88.0, 85.0, 88.0, 83.0, 83.0, 87.0, 88.0, 88.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 53.0, 54.0, 54.0, 53.0, 53.0, 55.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 70.0, 74.0, 75.0, 94.0, 103.0, 100.0, 76.0, 71.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 87.0, 84.0, 86.0, 82.0, 84.0, 88.0, 88.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 164.0, 151.0, 146.0, 168.0, 163.0, 161.0, 225.0, 166.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 174.0, 174.0, 174.0, 174.0, 171.0, 170.0, 171.0, 173.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 67.0, 68.0, 67.0, 68.0, 67.0, 67.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1486.3000000000002, "alertThreshold": 2.0}]} 09:44:04 INFO - Return code: 0 09:44:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:44:04 INFO - # TBPL SUCCESS # 09:44:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:04 INFO - 2016-11-22 09:44:04.901 screenresolution[1918:20714] starting screenresolution argv=screenresolution get 09:44:04 INFO - 2016-11-22 09:44:04.903 screenresolution[1918:20714] Display 0: 1600x1200x32@60 09:44:04 INFO - 2016-11-22 09:44:04.910 screenresolution[1919:20718] starting screenresolution argv=screenresolution list 09:44:04 INFO - Available Modes on Display 0 09:44:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:05 INFO - Intel Iris: 09:44:05 INFO - Chipset Model: Intel Iris 09:44:05 INFO - Type: GPU 09:44:05 INFO - Bus: Built-In 09:44:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:05 INFO - Vendor: Intel (0x8086) 09:44:05 INFO - Device ID: 0x0a2e 09:44:05 INFO - Revision ID: 0x0009 09:44:05 INFO - Displays: 09:44:05 INFO - Display: 09:44:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:05 INFO - Display Serial Number: 1600x1200 60 09:44:05 INFO - Main Display: Yes 09:44:05 INFO - Mirror: Off 09:44:05 INFO - Online: Yes 09:44:05 INFO - Rotation: Supported 09:44:05 INFO - Return code: 0 09:44:05 INFO - Running post-action listener: _resource_record_post_action 09:44:05 INFO - [mozharness: 2016-11-22 17:44:05.154855Z] Finished run-tests step (success) 09:44:05 INFO - Running post-run listener: _resource_record_post_run 09:44:05 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 679936; Write bytes: 695073792; Read time: 8; Write time: 1901 09:44:05 INFO - TinderboxPrint: CPU usage
53.6% 09:44:05 INFO - TinderboxPrint: I/O read bytes / time
679,936 / 8 09:44:05 INFO - TinderboxPrint: I/O write bytes / time
695,073,792 / 1,901 09:44:05 INFO - TinderboxPrint: CPU idle
2,253.2 (46.4%) 09:44:05 INFO - TinderboxPrint: CPU system
975.6 (20.1%) 09:44:05 INFO - TinderboxPrint: CPU user
1,628.9 (33.5%) 09:44:05 INFO - TinderboxPrint: Swap in / out
383,033,344 / 0 09:44:05 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 367383040; Write bytes: 366565376; Read time: 28470; Write time: 693 09:44:05 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 679936; Write bytes: 307281408; Read time: 8; Write time: 1120 09:44:05 INFO - Running post-run listener: _upload_blobber_files 09:44:05 INFO - Blob upload gear active. 09:44:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:44:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:44:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:44:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:44:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:44:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:07 INFO - (blobuploader) - INFO - Done attempting. 09:44:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:44:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:08 INFO - (blobuploader) - INFO - Done attempting. 09:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:44:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:10 INFO - (blobuploader) - INFO - Done attempting. 09:44:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:44:10 INFO - Return code: 0 09:44:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:44:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:44:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eedb3c6ed7c541408245578dede807f702a1fcb87b9a6fe9d462fdb7854eba594cef9df4dca915f22ee0217edec1cdc7a0d293860d08ede75b6987c9548e67d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12a446bc9ab3038041a946b79e20397f9484853151134477d6bed2911ddae63438ac1b9f6640e72fea37091a77f2b92daf9aa5d2d90ddbf1813acd122d3b2a5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13cb2506dbeb4bf570812e5e33e18f76353c1d293881850b649723642a8c834a85a5e7b33493475f84bfcb94b54423a943c9989b8e564861294ff27f750be81"} 09:44:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:44:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:44:10 INFO - Contents: 09:44:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eedb3c6ed7c541408245578dede807f702a1fcb87b9a6fe9d462fdb7854eba594cef9df4dca915f22ee0217edec1cdc7a0d293860d08ede75b6987c9548e67d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12a446bc9ab3038041a946b79e20397f9484853151134477d6bed2911ddae63438ac1b9f6640e72fea37091a77f2b92daf9aa5d2d90ddbf1813acd122d3b2a5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13cb2506dbeb4bf570812e5e33e18f76353c1d293881850b649723642a8c834a85a5e7b33493475f84bfcb94b54423a943c9989b8e564861294ff27f750be81"} 09:44:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:44:10 INFO - Copying logs to upload dir... 09:44:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:44:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.538426 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-22 09:44:10.556749) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:44:10.560562) ========= 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.pflgFVC3gx/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.EFIVBpfJuc/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/eedb3c6ed7c541408245578dede807f702a1fcb87b9a6fe9d462fdb7854eba594cef9df4dca915f22ee0217edec1cdc7a0d293860d08ede75b6987c9548e67d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12a446bc9ab3038041a946b79e20397f9484853151134477d6bed2911ddae63438ac1b9f6640e72fea37091a77f2b92daf9aa5d2d90ddbf1813acd122d3b2a5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13cb2506dbeb4bf570812e5e33e18f76353c1d293881850b649723642a8c834a85a5e7b33493475f84bfcb94b54423a943c9989b8e564861294ff27f750be81"} build_url:https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/G1Nr-jRSS3ameztHCeFfTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eedb3c6ed7c541408245578dede807f702a1fcb87b9a6fe9d462fdb7854eba594cef9df4dca915f22ee0217edec1cdc7a0d293860d08ede75b6987c9548e67d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12a446bc9ab3038041a946b79e20397f9484853151134477d6bed2911ddae63438ac1b9f6640e72fea37091a77f2b92daf9aa5d2d90ddbf1813acd122d3b2a5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13cb2506dbeb4bf570812e5e33e18f76353c1d293881850b649723642a8c834a85a5e7b33493475f84bfcb94b54423a943c9989b8e564861294ff27f750be81"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:44:10.595124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:44:10.595450) ========= 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.pflgFVC3gx/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.EFIVBpfJuc/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005094 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:44:10.643802) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:44:10.644108) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:44:10.644426) ========= ========= Total master_lag: 0.18 =========