builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0281 starttime: 1475732684.28 results: success (0) buildid: 20161005205259 builduid: 95448df6f8c74b69ad01bf77d9260fac revision: ad3b71ecafeb0b0366750eb3a771224e05a194ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.281386) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.281902) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.282221) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.306888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.307293) ========= 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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-05 22:44:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.4M=0.001s 2016-10-05 22:44:44 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123803 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.453624) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.453998) ========= 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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.040833 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:44.515485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-05 22:44:44.515907) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ad3b71ecafeb0b0366750eb3a771224e05a194ee --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev ad3b71ecafeb0b0366750eb3a771224e05a194ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PJSkDEmP3u/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.XFJEDyPxeF/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-05 22:44:44,601 truncating revision to first 12 chars 2016-10-05 22:44:44,601 Setting DEBUG logging. 2016-10-05 22:44:44,601 attempt 1/10 2016-10-05 22:44:44,601 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ad3b71ecafeb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 22:44:46,600 unpacking tar archive at: fx-team-ad3b71ecafeb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.580315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-05 22:44:47.118621) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:47.119054) ========= script_repo_revision: ad3b71ecafeb0b0366750eb3a771224e05a194ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:47.119525) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:47.119875) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 22:44:47.138088) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-10-05 22:44:47.138441) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PJSkDEmP3u/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.XFJEDyPxeF/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:44:47 INFO - MultiFileLogger online at 20161005 22:44:47 in /builds/slave/test 22:44:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:44:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:44:47 INFO - {'append_to_log': False, 22:44:47 INFO - 'base_work_dir': '/builds/slave/test', 22:44:47 INFO - 'blob_upload_branch': 'Fx-Team', 22:44:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:44:47 INFO - 'branch': 'Fx-Team', 22:44:47 INFO - 'buildbot_json_path': 'buildprops.json', 22:44:47 INFO - 'config_files': ('talos/mac_config.py',), 22:44:47 INFO - 'default_actions': ('clobber', 22:44:47 INFO - 'read-buildbot-config', 22:44:47 INFO - 'download-and-extract', 22:44:47 INFO - 'populate-webroot', 22:44:47 INFO - 'create-virtualenv', 22:44:47 INFO - 'install', 22:44:47 INFO - 'run-tests'), 22:44:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:44:47 INFO - 'download_minidump_stackwalk': True, 22:44:47 INFO - 'download_symbols': 'ondemand', 22:44:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:44:47 INFO - 'tooltool.py': '/tools/tooltool.py', 22:44:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:44:47 INFO - '/tools/misc-python/virtualenv.py')}, 22:44:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:44:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:44:47 INFO - 'installer_path': 'installer.exe', 22:44:47 INFO - 'log_level': 'info', 22:44:47 INFO - 'log_name': 'talos', 22:44:47 INFO - 'log_to_console': True, 22:44:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:44:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:44:47 INFO - 'opt_config_files': (), 22:44:47 INFO - 'pip_index': False, 22:44:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:44:47 INFO - 'cmd': ('bash', 22:44:47 INFO - '-c', 22:44:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:44:47 INFO - 'enabled': True, 22:44:47 INFO - 'halt_on_failure': False, 22:44:47 INFO - 'name': 'check_screen_resolution'},), 22:44:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:44:47 INFO - 'cmd': ('bash', 22:44:47 INFO - '-c', 22:44:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:44:47 INFO - 'enabled': True, 22:44:47 INFO - 'halt_on_failure': False, 22:44:47 INFO - 'name': 'check_screen_resolution'},), 22:44:47 INFO - 'run_cmd_checks_enabled': True, 22:44:47 INFO - 'sps_profile': False, 22:44:47 INFO - 'sps_profile_interval': 0, 22:44:47 INFO - 'suite': 'dromaeojs', 22:44:47 INFO - 'system_bits': '32', 22:44:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:44:47 INFO - 'title': 't-yosemite-r7-0281', 22:44:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:44:47 INFO - 'use_talos_json': True, 22:44:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:44:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:44:47 INFO - 'work_dir': 'build'} 22:44:47 INFO - [mozharness: 2016-10-06 05:44:47.339809Z] Running clobber step. 22:44:47 INFO - Running pre-action listener: _resource_record_pre_action 22:44:47 INFO - Running main action method: clobber 22:44:47 INFO - rmtree: /builds/slave/test/build 22:44:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:44:49 INFO - Running post-action listener: _resource_record_post_action 22:44:49 INFO - [mozharness: 2016-10-06 05:44:49.546328Z] Finished clobber step (success) 22:44:49 INFO - [mozharness: 2016-10-06 05:44:49.546507Z] Running read-buildbot-config step. 22:44:49 INFO - Running pre-action listener: _resource_record_pre_action 22:44:49 INFO - Running main action method: read_buildbot_config 22:44:49 INFO - Using buildbot properties: 22:44:49 INFO - { 22:44:49 INFO - "project": "", 22:44:49 INFO - "product": "firefox", 22:44:49 INFO - "script_repo_revision": "production", 22:44:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:44:49 INFO - "repository": "", 22:44:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:44:49 INFO - "buildid": "20161005205259", 22:44:49 INFO - "pgo_build": "False", 22:44:49 INFO - "basedir": "/builds/slave/test", 22:44:49 INFO - "buildnumber": 11, 22:44:49 INFO - "slavename": "t-yosemite-r7-0281", 22:44:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:44:49 INFO - "platform": "yosemite_r7", 22:44:49 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:44:49 INFO - "branch": "fx-team", 22:44:49 INFO - "repo_path": "integration/fx-team", 22:44:49 INFO - "revision": "ad3b71ecafeb0b0366750eb3a771224e05a194ee", 22:44:49 INFO - "stage_platform": "macosx64", 22:44:49 INFO - "builduid": "95448df6f8c74b69ad01bf77d9260fac", 22:44:49 INFO - "slavebuilddir": "test" 22:44:49 INFO - } 22:44:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:44:49 INFO - Running post-action listener: _resource_record_post_action 22:44:49 INFO - [mozharness: 2016-10-06 05:44:49.552255Z] Finished read-buildbot-config step (success) 22:44:49 INFO - [mozharness: 2016-10-06 05:44:49.552453Z] Running download-and-extract step. 22:44:49 INFO - Running pre-action listener: _resource_record_pre_action 22:44:49 INFO - Running main action method: download_and_extract 22:44:49 INFO - mkdir: /builds/slave/test/build/tests 22:44: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')]} 22:44:49 INFO - trying https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:44:49 INFO - Downloading https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 22:44:50 INFO - Downloaded 1199 bytes. 22:44:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:44:50 INFO - Using the following test package requirements: 22:44:50 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:44:50 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:44:50 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:44:50 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'jsshell-mac.zip'], 22:44:50 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:44:50 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:44:50 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:44:50 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:44:50 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:44:50 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:44:50 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:44:50 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:44:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:44:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:44:50 INFO - Fetch https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:44:51 INFO - Http code: 200 22:44:51 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 22:44:51 INFO - x-amz-cf-id: Z-KBNXQ8lh36k1iq54nXcr_jK9wcAjyvKkp2lTcrTMWzI-rucMpA7A== 22:44:51 INFO - x-amz-version-id: 78Q1lo3t8BujKL9fGubSZv0odVkGhr5J 22:44:52 INFO - Expected file size: 16477060 22:44:52 INFO - Obtained file size: 16477060 22:44:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:44:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:44:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:44:58 INFO - Fetch https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:44:59 INFO - Http code: 200 22:44:59 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 22:44:59 INFO - x-amz-cf-id: ckcFezI04XcEoYbNQSO1SFS3LHMwBvJ-A71ZzRN1Rg-MVSUqv1dpVA== 22:44:59 INFO - x-amz-version-id: 78Q1lo3t8BujKL9fGubSZv0odVkGhr5J 22:45:00 INFO - Expected file size: 16477060 22:45:00 INFO - Obtained file size: 16477060 22:45:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:45:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:45:06 INFO - Fetch https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:45:06 INFO - Http code: 200 22:45:06 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 22:45:06 INFO - x-amz-cf-id: vYbIGq-Ra5y4Z4XV2UPTiooNbnxPL2xmO3VuVrbLr_c3a72T-APocw== 22:45:06 INFO - x-amz-version-id: lWSAZYQgzO8VyVuQpAIzkpCi_TofhDVr 22:45:07 INFO - Expected file size: 14409006 22:45:07 INFO - Obtained file size: 14409006 22:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:07 INFO - trying https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:45:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:45:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 22:45:14 INFO - Downloaded 111661292 bytes. 22:45:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:45:14 INFO - mkdir: /builds/slave/test/properties 22:45:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:45:14 INFO - Writing to file /builds/slave/test/properties/build_url 22:45:14 INFO - Contents: 22:45:14 INFO - build_url:https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:45:15 INFO - Running post-action listener: _resource_record_post_action 22:45:15 INFO - Running post-action listener: set_extra_try_arguments 22:45:15 INFO - [mozharness: 2016-10-06 05:45:15.152952Z] Finished download-and-extract step (success) 22:45:15 INFO - [mozharness: 2016-10-06 05:45:15.153082Z] Running populate-webroot step. 22:45:15 INFO - Running pre-action listener: _resource_record_pre_action 22:45:15 INFO - Running main action method: populate_webroot 22:45:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:45:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:45:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:45:15 INFO - '--tppagecycles', 22:45:15 INFO - '7'], 22:45:15 INFO - 'tests': ['tsvgm']}}, 22:45:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:45:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:45:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:45:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:45:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['damp', 'tps']}, 22:45:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'tests': ['damp', 'tps']}, 22:45:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['dromaeo_dom']}, 22:45:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:45:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['basic_compositor_video']}, 22:45:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:45:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['a11yr', 22:45:15 INFO - 'ts_paint', 22:45:15 INFO - 'tpaint', 22:45:15 INFO - 'sessionrestore', 22:45:15 INFO - 'sessionrestore_no_auto_restore', 22:45:15 INFO - 'tabpaint']}, 22:45:15 INFO - 'other-e10s': {'tests': ['a11yr', 22:45:15 INFO - 'ts_paint', 22:45:15 INFO - 'tpaint', 22:45:15 INFO - 'sessionrestore', 22:45:15 INFO - 'sessionrestore_no_auto_restore', 22:45:15 INFO - 'tabpaint']}, 22:45:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:45:15 INFO - 'ts_paint', 22:45:15 INFO - 'tpaint', 22:45:15 INFO - 'sessionrestore', 22:45:15 INFO - 'sessionrestore_no_auto_restore', 22:45:15 INFO - 'tabpaint']}, 22:45:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:45:15 INFO - 'ts_paint', 22:45:15 INFO - 'tpaint', 22:45:15 INFO - 'sessionrestore', 22:45:15 INFO - 'sessionrestore_no_auto_restore', 22:45:15 INFO - 'tabpaint']}, 22:45:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['a11yr', 22:45:15 INFO - 'ts_paint', 22:45:15 INFO - 'tpaint', 22:45:15 INFO - 'sessionrestore', 22:45:15 INFO - 'sessionrestore_no_auto_restore', 22:45:15 INFO - 'tabpaint']}, 22:45:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['a11yr', 22:45:15 INFO - 'ts_paint', 22:45:15 INFO - 'tpaint', 22:45:15 INFO - 'sessionrestore', 22:45:15 INFO - 'sessionrestore_no_auto_restore', 22:45:15 INFO - 'tabpaint']}, 22:45:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['tsvgx', 22:45:15 INFO - 'tsvgr_opacity', 22:45:15 INFO - 'tart', 22:45:15 INFO - 'tscrollx', 22:45:15 INFO - 'cart']}, 22:45:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:45:15 INFO - 'tsvgr_opacity', 22:45:15 INFO - 'tart', 22:45:15 INFO - 'tscrollx', 22:45:15 INFO - 'cart']}, 22:45:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'talos_options': ['--disable-e10s'], 22:45:15 INFO - 'tests': ['tp5o']}, 22:45:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'tests': ['tp5o']}, 22:45:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'talos_options': ['--disable-e10s', 22:45:15 INFO - '--xperf_path', 22:45:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:15 INFO - 'tests': ['tp5n']}, 22:45:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:15 INFO - 'talos_options': ['--xperf_path', 22:45:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:15 INFO - 'tests': ['tp5n']}}, 22:45:15 INFO - 'talos.zip': {'path': '', 22:45:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:45:15 INFO - Running post-action listener: _resource_record_post_action 22:45:15 INFO - [mozharness: 2016-10-06 05:45:15.172248Z] Finished populate-webroot step (success) 22:45:15 INFO - [mozharness: 2016-10-06 05:45:15.172329Z] Running create-virtualenv step. 22:45:15 INFO - Running pre-action listener: _resource_record_pre_action 22:45:15 INFO - Running main action method: create_virtualenv 22:45:15 INFO - Creating virtualenv /builds/slave/test/build/venv 22:45:15 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 22:45:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:45:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:45:15 INFO - Using real prefix '/tools/python27' 22:45:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:45:16 INFO - Installing distribute.............................................................................................................................................................................................done. 22:45:18 INFO - Installing pip.................done. 22:45:18 INFO - Return code: 0 22:45:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:45:19 INFO - Reading from file tmpfile_stdout 22:45:19 INFO - Output received: 22:45:19 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) 22:45:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:19 INFO - 'HOME': '/Users/cltbld', 22:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:19 INFO - 'LOGNAME': 'cltbld', 22:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:19 INFO - 'MOZ_NO_REMOTE': '1', 22:45:19 INFO - 'NO_EM_RESTART': '1', 22:45:19 INFO - 'PAGER': '/bin/cat', 22:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:19 INFO - 'PWD': '/builds/slave/test', 22:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:19 INFO - 'SHELL': '/bin/bash', 22:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:19 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:19 INFO - 'USER': 'cltbld', 22:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:19 INFO - 'XPC_FLAGS': '0x0', 22:45:19 INFO - 'XPC_SERVICE_NAME': '0', 22:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:19 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 22:45:19 INFO - Cleaning up... 22:45:19 INFO - Return code: 0 22:45:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:19 INFO - 'HOME': '/Users/cltbld', 22:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:19 INFO - 'LOGNAME': 'cltbld', 22:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:19 INFO - 'MOZ_NO_REMOTE': '1', 22:45:19 INFO - 'NO_EM_RESTART': '1', 22:45:19 INFO - 'PAGER': '/bin/cat', 22:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:19 INFO - 'PWD': '/builds/slave/test', 22:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:19 INFO - 'SHELL': '/bin/bash', 22:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:19 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:19 INFO - 'USER': 'cltbld', 22:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:19 INFO - 'XPC_FLAGS': '0x0', 22:45:19 INFO - 'XPC_SERVICE_NAME': '0', 22:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:19 INFO - Downloading/unpacking psutil>=3.1.1 22:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:45:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:23 INFO - Installing collected packages: psutil 22:45:23 INFO - Running setup.py install for psutil 22:45:23 INFO - building 'psutil._psutil_osx' extension 22:45:23 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 22:45:23 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 22:45:23 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 22:45:23 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 22:45:23 INFO - building 'psutil._psutil_posix' extension 22:45:23 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 22:45:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:45:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:45:23 INFO - ^ 22:45:24 INFO - 1 warning generated. 22:45:24 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 22:45:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:24 INFO - Successfully installed psutil 22:45:24 INFO - Cleaning up... 22:45:24 INFO - Return code: 0 22:45:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:24 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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:24 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 22:45:24 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 22:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:24 INFO - 'HOME': '/Users/cltbld', 22:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:24 INFO - 'LOGNAME': 'cltbld', 22:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:24 INFO - 'MOZ_NO_REMOTE': '1', 22:45:24 INFO - 'NO_EM_RESTART': '1', 22:45:24 INFO - 'PAGER': '/bin/cat', 22:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:24 INFO - 'PWD': '/builds/slave/test', 22:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:24 INFO - 'SHELL': '/bin/bash', 22:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:24 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:24 INFO - 'USER': 'cltbld', 22:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:24 INFO - 'XPC_FLAGS': '0x0', 22:45:24 INFO - 'XPC_SERVICE_NAME': '0', 22:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:45:24 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 22:45:24 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 22:45:24 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 22:45:24 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 22:45:24 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 22:45:24 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 22:45:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:45:27 INFO - Installing collected packages: mozsystemmonitor 22:45:27 INFO - Running setup.py install for mozsystemmonitor 22:45:27 INFO - Successfully installed mozsystemmonitor 22:45:27 INFO - Cleaning up... 22:45:27 INFO - Return code: 0 22:45:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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', '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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:45: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 jsonschema==2.5.1 22:45:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:27 INFO - 'HOME': '/Users/cltbld', 22:45:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:27 INFO - 'LOGNAME': 'cltbld', 22:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:27 INFO - 'MOZ_NO_REMOTE': '1', 22:45:27 INFO - 'NO_EM_RESTART': '1', 22:45:27 INFO - 'PAGER': '/bin/cat', 22:45:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:27 INFO - 'PWD': '/builds/slave/test', 22:45:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:27 INFO - 'SHELL': '/bin/bash', 22:45:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:27 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:27 INFO - 'USER': 'cltbld', 22:45:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:27 INFO - 'XPC_FLAGS': '0x0', 22:45:27 INFO - 'XPC_SERVICE_NAME': '0', 22:45:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:27 INFO - Downloading/unpacking jsonschema==2.5.1 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:45:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:30 INFO - Downloading functools32-3.2.3-2.tar.gz 22:45:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:45:30 INFO - warning: no files found matching '*.txt' 22:45:30 INFO - no previously-included directories found matching 'build' 22:45:30 INFO - no previously-included directories found matching 'dist' 22:45:30 INFO - no previously-included directories found matching '.git*' 22:45:30 INFO - Installing collected packages: jsonschema, functools32 22:45:30 INFO - Running setup.py install for functools32 22:45:30 INFO - warning: no files found matching '*.txt' 22:45:30 INFO - no previously-included directories found matching 'build' 22:45:30 INFO - no previously-included directories found matching 'dist' 22:45:30 INFO - no previously-included directories found matching '.git*' 22:45:30 INFO - Successfully installed jsonschema functools32 22:45:30 INFO - Cleaning up... 22:45:30 INFO - Return code: 0 22:45:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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', '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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:45: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 functools32==3.2.3-2 22:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:30 INFO - 'HOME': '/Users/cltbld', 22:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:30 INFO - 'LOGNAME': 'cltbld', 22:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:30 INFO - 'MOZ_NO_REMOTE': '1', 22:45:30 INFO - 'NO_EM_RESTART': '1', 22:45:30 INFO - 'PAGER': '/bin/cat', 22:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:30 INFO - 'PWD': '/builds/slave/test', 22:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:30 INFO - 'SHELL': '/bin/bash', 22:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:30 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:30 INFO - 'USER': 'cltbld', 22:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:30 INFO - 'XPC_FLAGS': '0x0', 22:45:30 INFO - 'XPC_SERVICE_NAME': '0', 22:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:45:31 INFO - Cleaning up... 22:45:31 INFO - Return code: 0 22:45:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:45:31 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')]} 22:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:31 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')]} 22:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:31 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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:31 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 22:45:31 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 22:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:31 INFO - 'HOME': '/Users/cltbld', 22:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:31 INFO - 'LOGNAME': 'cltbld', 22:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:31 INFO - 'MOZ_NO_REMOTE': '1', 22:45:31 INFO - 'NO_EM_RESTART': '1', 22:45:31 INFO - 'PAGER': '/bin/cat', 22:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:31 INFO - 'PWD': '/builds/slave/test', 22:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:31 INFO - 'SHELL': '/bin/bash', 22:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:31 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:31 INFO - 'USER': 'cltbld', 22:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:31 INFO - 'XPC_FLAGS': '0x0', 22:45:31 INFO - 'XPC_SERVICE_NAME': '0', 22:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:31 INFO - Downloading/unpacking blobuploader==1.2.4 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45:33 INFO - Downloading blobuploader-1.2.4.tar.gz 22:45:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:45:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:45:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45:34 INFO - Downloading docopt-0.6.1.tar.gz 22:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:45:35 INFO - Installing collected packages: blobuploader, requests, docopt 22:45:35 INFO - Running setup.py install for blobuploader 22:45:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:45:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:45:35 INFO - Running setup.py install for requests 22:45:35 INFO - Running setup.py install for docopt 22:45:35 INFO - Successfully installed blobuploader requests docopt 22:45:35 INFO - Cleaning up... 22:45:36 INFO - Return code: 0 22:45:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:36 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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:36 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 22:45:36 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 22:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:36 INFO - 'HOME': '/Users/cltbld', 22:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:36 INFO - 'LOGNAME': 'cltbld', 22:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:36 INFO - 'MOZ_NO_REMOTE': '1', 22:45:36 INFO - 'NO_EM_RESTART': '1', 22:45:36 INFO - 'PAGER': '/bin/cat', 22:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:36 INFO - 'PWD': '/builds/slave/test', 22:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:36 INFO - 'SHELL': '/bin/bash', 22:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:36 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:36 INFO - 'USER': 'cltbld', 22:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:36 INFO - 'XPC_FLAGS': '0x0', 22:45:36 INFO - 'XPC_SERVICE_NAME': '0', 22:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:45:36 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-sFSJSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:45:36 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-NSqJb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:45:36 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-XT0dRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:45:36 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-idKL_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:45:36 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-JBfqwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:45:36 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-byeEtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:45:36 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-GCqaCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-7amIry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-AY2SNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-XgtXOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-q7AAmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-4GW2RM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-6Ti2lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-cMbhD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-WEZtfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-8WT84a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:45:37 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Soqv0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:45:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:45:38 INFO - Running setup.py install for manifestparser 22:45:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:45:38 INFO - Running setup.py install for mozcrash 22:45:38 INFO - Running setup.py install for mozdebug 22:45:38 INFO - Running setup.py install for mozdevice 22:45:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:45:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:45:38 INFO - Running setup.py install for mozfile 22:45:38 INFO - Running setup.py install for mozhttpd 22:45:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:45:38 INFO - Running setup.py install for mozinfo 22:45:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:45:39 INFO - Running setup.py install for mozInstall 22:45:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:45:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:45:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:45:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:45:39 INFO - Running setup.py install for mozleak 22:45:39 INFO - Running setup.py install for mozlog 22:45:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:45:39 INFO - Running setup.py install for moznetwork 22:45:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:45:39 INFO - Running setup.py install for mozprocess 22:45:39 INFO - Running setup.py install for mozprofile 22:45:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:45:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:45:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:45:40 INFO - Running setup.py install for mozrunner 22:45:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:45:40 INFO - Running setup.py install for mozscreenshot 22:45:40 INFO - Running setup.py install for moztest 22:45:40 INFO - Running setup.py install for mozversion 22:45:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:45:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:45:40 INFO - Cleaning up... 22:45:40 INFO - Return code: 0 22:45:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:41 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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:41 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 22:45:41 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 22:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:41 INFO - 'HOME': '/Users/cltbld', 22:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:41 INFO - 'LOGNAME': 'cltbld', 22:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:41 INFO - 'MOZ_NO_REMOTE': '1', 22:45:41 INFO - 'NO_EM_RESTART': '1', 22:45:41 INFO - 'PAGER': '/bin/cat', 22:45:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:41 INFO - 'PWD': '/builds/slave/test', 22:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:41 INFO - 'SHELL': '/bin/bash', 22:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:41 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:41 INFO - 'USER': 'cltbld', 22:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:41 INFO - 'XPC_FLAGS': '0x0', 22:45:41 INFO - 'XPC_SERVICE_NAME': '0', 22:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:45:41 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-A1ga9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:45:41 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)) 22:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:45:41 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Rpm4Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:45:41 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-b7uWlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:45:41 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)) 22:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:45:41 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-I1yaWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-i0fGFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-ZaYOCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-0hLXd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FIXlZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-A5PTFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-A8UbJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-iNtz8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-u3TrGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FENBgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:45:42 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)) 22:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:45:42 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-7T4Q8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:43 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-pKJOtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:43 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)) 22:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:45:43 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-wHabPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:45:43 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)) 22:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:45:43 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-x92GWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:45:43 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)) 22:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:45:43 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)) 22:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:45:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:45:43 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 22:45:43 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 22:45:43 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 22:45:43 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 22:45:43 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 22:45:43 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 22:45:46 INFO - Downloading blessings-1.6.tar.gz 22:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:45:46 INFO - Installing collected packages: blessings 22:45:46 INFO - Running setup.py install for blessings 22:45:46 INFO - Successfully installed blessings 22:45:46 INFO - Cleaning up... 22:45:46 INFO - Return code: 0 22:45:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:45:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:45:46 INFO - Reading from file tmpfile_stdout 22:45:46 INFO - Current package versions: 22:45:46 INFO - blessings == 1.6 22:45:46 INFO - blobuploader == 1.2.4 22:45:46 INFO - docopt == 0.6.1 22:45:46 INFO - functools32 == 3.2.3-2 22:45:46 INFO - jsonschema == 2.5.1 22:45:46 INFO - manifestparser == 1.1 22:45:46 INFO - mozInstall == 1.12 22:45:46 INFO - mozcrash == 0.17 22:45:46 INFO - mozdebug == 0.1 22:45:46 INFO - mozdevice == 0.48 22:45:46 INFO - mozfile == 1.2 22:45:46 INFO - mozhttpd == 0.7 22:45:46 INFO - mozinfo == 0.9 22:45:46 INFO - mozleak == 0.1 22:45:46 INFO - mozlog == 3.4 22:45:46 INFO - moznetwork == 0.27 22:45:46 INFO - mozprocess == 0.23 22:45:46 INFO - mozprofile == 0.28 22:45:46 INFO - mozrunner == 6.12 22:45:46 INFO - mozscreenshot == 0.1 22:45:46 INFO - mozsystemmonitor == 0.3 22:45:46 INFO - moztest == 0.7 22:45:46 INFO - mozversion == 1.4 22:45:46 INFO - psutil == 3.1.1 22:45:46 INFO - requests == 1.2.3 22:45:46 INFO - wsgiref == 0.1.2 22:45:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:47 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')]} 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:47 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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:47 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 22:45:47 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 22:45:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:47 INFO - 'HOME': '/Users/cltbld', 22:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:47 INFO - 'LOGNAME': 'cltbld', 22:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:47 INFO - 'MOZ_NO_REMOTE': '1', 22:45:47 INFO - 'NO_EM_RESTART': '1', 22:45:47 INFO - 'PAGER': '/bin/cat', 22:45:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:47 INFO - 'PWD': '/builds/slave/test', 22:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:47 INFO - 'SHELL': '/bin/bash', 22:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:47 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:47 INFO - 'USER': 'cltbld', 22:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:47 INFO - 'XPC_FLAGS': '0x0', 22:45:47 INFO - 'XPC_SERVICE_NAME': '0', 22:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 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)) 22:45:47 INFO - Cleaning up... 22:45:47 INFO - Return code: 0 22:45:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:45:47 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')]} 22:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:47 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')]} 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:47 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 0x10afe5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc00cc9e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, '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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 22:45:47 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 22:45:47 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 22:45:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:47 INFO - 'HOME': '/Users/cltbld', 22:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:47 INFO - 'LOGNAME': 'cltbld', 22:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:47 INFO - 'MOZ_NO_REMOTE': '1', 22:45:47 INFO - 'NO_EM_RESTART': '1', 22:45:47 INFO - 'PAGER': '/bin/cat', 22:45:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:47 INFO - 'PWD': '/builds/slave/test', 22:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:47 INFO - 'SHELL': '/bin/bash', 22:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:45:47 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:47 INFO - 'USER': 'cltbld', 22:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:47 INFO - 'XPC_FLAGS': '0x0', 22:45:47 INFO - 'XPC_SERVICE_NAME': '0', 22:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:45:47 INFO - Cleaning up... 22:45:47 INFO - Return code: 0 22:45:47 INFO - Running post-action listener: _resource_record_post_action 22:45:47 INFO - Running post-action listener: _start_resource_monitoring 22:45:47 INFO - Starting resource monitoring. 22:45:47 INFO - [mozharness: 2016-10-06 05:45:47.759460Z] Finished create-virtualenv step (success) 22:45:47 INFO - [mozharness: 2016-10-06 05:45:47.760515Z] Running install step. 22:45:47 INFO - Running pre-action listener: _resource_record_pre_action 22:45:47 INFO - Running main action method: install 22:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:45:48 INFO - Reading from file tmpfile_stdout 22:45:48 INFO - Detecting whether we're running mozinstall >=1.0... 22:45:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:45:48 INFO - Reading from file tmpfile_stdout 22:45:48 INFO - Output received: 22:45:48 INFO - Usage: mozinstall [options] installer 22:45:48 INFO - Options: 22:45:48 INFO - -h, --help show this help message and exit 22:45:48 INFO - -d DEST, --destination=DEST 22:45:48 INFO - Directory to install application into. [default: 22:45:48 INFO - "/builds/slave/test"] 22:45:48 INFO - --app=APP Application being installed. [default: firefox] 22:45:48 INFO - mkdir: /builds/slave/test/build/application 22:45:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:46:22 INFO - Reading from file tmpfile_stdout 22:46:22 INFO - Output received: 22:46:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:46:22 INFO - Running post-action listener: _resource_record_post_action 22:46:22 INFO - [mozharness: 2016-10-06 05:46:22.545318Z] Finished install step (success) 22:46:22 INFO - [mozharness: 2016-10-06 05:46:22.545435Z] Running run-tests step. 22:46:22 INFO - Running pre-action listener: _resource_record_pre_action 22:46:22 INFO - Running main action method: run_tests 22:46:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:46:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:46:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:46:22 INFO - 2016-10-05 22:46:22.560 screenresolution[1636:7554] starting screenresolution argv=screenresolution get 22:46:22 INFO - 2016-10-05 22:46:22.562 screenresolution[1636:7554] Display 0: 1600x1200x32@60 22:46:22 INFO - 2016-10-05 22:46:22.568 screenresolution[1637:7556] starting screenresolution argv=screenresolution list 22:46:22 INFO - Available Modes on Display 0 22:46:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:46:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:46:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:46:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:46:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:46:22 INFO - Intel Iris: 22:46:22 INFO - Chipset Model: Intel Iris 22:46:22 INFO - Type: GPU 22:46:22 INFO - Bus: Built-In 22:46:22 INFO - VRAM (Dynamic, Max): 1536 MB 22:46:22 INFO - Vendor: Intel (0x8086) 22:46:22 INFO - Device ID: 0x0a2e 22:46:22 INFO - Revision ID: 0x0009 22:46:22 INFO - Displays: 22:46:22 INFO - Display: 22:46:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:46:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:46:22 INFO - Display Serial Number: 1600x1200 60 22:46:22 INFO - Main Display: Yes 22:46:22 INFO - Mirror: Off 22:46:22 INFO - Online: Yes 22:46:22 INFO - Rotation: Supported 22:46:22 INFO - Return code: 0 22:46:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:46:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:46:22 INFO - Python 2.7.3 22:46:22 INFO - Return code: 0 22:46:22 INFO - grabbing minidump binary from tooltool 22:46: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')]} 22:46:22 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 0x10adf9d30>, '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 0x10ac1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:46:22 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 22:46:22 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 22:46:22 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 22:46:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:46:22 INFO - Return code: 0 22:46:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:46:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:46:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:46:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:46:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:46:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--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 22:46:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0281 --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 22:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJSkDEmP3u/Render', 22:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:22 INFO - 'HOME': '/Users/cltbld', 22:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:22 INFO - 'LOGNAME': 'cltbld', 22:46:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:46:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:22 INFO - 'MOZ_NO_REMOTE': '1', 22:46:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:46:22 INFO - 'NO_EM_RESTART': '1', 22:46:22 INFO - 'PAGER': '/bin/cat', 22:46:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:22 INFO - 'PWD': '/builds/slave/test', 22:46:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:22 INFO - 'SHELL': '/bin/bash', 22:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XFJEDyPxeF/Listeners', 22:46:22 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 22:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:22 INFO - 'USER': 'cltbld', 22:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:22 INFO - 'XPC_FLAGS': '0x0', 22:46:22 INFO - 'XPC_SERVICE_NAME': '0', 22:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--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 22:46:23 INFO - mozversion application_buildid: 20161005205259 22:46:23 INFO - mozversion application_changeset: ad3b71ecafeb0b0366750eb3a771224e05a194ee 22:46:23 INFO - mozversion application_display_name: Nightly 22:46:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:46:23 INFO - mozversion application_name: Firefox 22:46:23 INFO - mozversion application_remotingname: firefox 22:46:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:46:23 INFO - mozversion application_vendor: Mozilla 22:46:23 INFO - mozversion application_version: 52.0a1 22:46:23 INFO - mozversion platform_buildid: 20161005205259 22:46:23 INFO - mozversion platform_changeset: ad3b71ecafeb0b0366750eb3a771224e05a194ee 22:46:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:46:23 INFO - mozversion platform_version: 52.0a1 22:46:23 INFO - using testdate: 1475732783 22:46:23 INFO - actual date: 1475732783 22:46:23 INFO - starting webserver on 'localhost:49268' 22:46:23 INFO - SUITE-START | Running 2 tests 22:46:23 INFO - TEST-START | dromaeo_css 22:46:23 INFO - operating with platform_type : mac_ 22:46:23 INFO - Initialising browser for dromaeo_css test... 22:46:23 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp1pUhcZ/profile http://localhost:49268/getInfo.html) 22:46:25 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 22:46:25 INFO - PROCESS | 1647 | colorDepth:24 22:46:25 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 22:46:25 INFO - PROCESS | 1647 | __metrics 22:46:25 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:46:25 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:46:26 INFO - TEST-INFO | 1647: exit 0 22:46:26 INFO - Browser initialized. 22:46:26 INFO - Running cycle 1/1 for dromaeo_css test... 22:46:26 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp1pUhcZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:38 INFO - PROCESS | 1649 | RSS: Main: 244211712 22:46:38 INFO - PROCESS | 1649 | 22:47:29 INFO - PROCESS | 1649 | 1475732849800 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] 22:49:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 22:49:44 INFO - PROCESS | 1649 | RSS: Main: 368705536 22:49:44 INFO - PROCESS | 1649 | 22:52:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 22:52:47 INFO - PROCESS | 1649 | RSS: Main: 371793920 22:52:47 INFO - PROCESS | 1649 | 22:55:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 22:55:50 INFO - PROCESS | 1649 | RSS: Main: 343396352 22:55:50 INFO - PROCESS | 1649 | 22:58:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 22:58:53 INFO - PROCESS | 1649 | RSS: Main: 357425152 22:58:53 INFO - PROCESS | 1649 | 23:01:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 23:01:57 INFO - PROCESS | 1649 | RSS: Main: 327458816 23:01:57 INFO - PROCESS | 1649 | 23:05:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 23:05:01 INFO - PROCESS | 1649 | RSS: Main: 432279552 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | __start_tp_report 23:05:01 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 23:05:01 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 23:05:01 INFO - PROCESS | 1649 | |i|pagename|runs| 23:05:01 INFO - PROCESS | 1649 | |0;dojo.html;3337.282484265883;3344.147242453174;3374.4938259261107;3400.4049291374004;3415.4193787056197;4795.400574928134;5446.645968012076;5605.859853503654;5718.885622287552;5804.9999999999945;5700.429957004298;5735.311762588485;5799.362070172288;5803;5921.289445266563;5218.973905130469;5221.399539053001;5287.656302340355;5297.231901374298;5326.680399176043;5216.165413533835;5332.120200166968;5339.172428273624;5354.544863686591;5365.865853353678;5315.734213289359;5333.706646134461;5352.116900711398;5352.197170424429;5386.542143917772;5114.411842638107;5202.635815492917;5256.737163141827;5257.211418287287;5300.575953923677;5124.820631277925;5149.5107964743665;5185.274061631375;5201.843944681666;5209.973950130263;5577.414371490976;5578.944210557883;5583.4974852263085;5593.888122237573;5619.466150715675;6133.1106989486725;6134.96932515339;6139.785107521216;6140;6150.954337762591;5092.567131793802;6222.471089957314;6259.499240060784;6270.122182894398;6292.276388215322;5851.473367396955;5866.061430171152;5866.061430171152;5867.237259156283;5912.733926973254;6174.5060395168275;6282.654454004982;6286.779962701375;6292.339304373067;6324.525660575475;6039.456448919531;6108.847278818065;6114.388610881663;6117.571769976059;6210.689465526705;6221.502279817604;6237.157983672237;6295.5907865988565;6297.716602752887;6314.936850631527;6251.374862513712;6255.812325630239;6264.436200741864;6276.592021518587;6284.4344009039405;6138.416850399205;6150.538709596798;6151.954167791486;6296.401841825111;6300.59046161998;4927.975360123177;4949.90100197994;4978.352814134212;4980.327655766425;5030.144875371196;4421.734695918255;4441.578050085237;4444.82220711168;4445.110977804452;4469.530699276529;5651;5744.195812586241;5779.393163717749;5784.334801497797;5798;6159.753609855646;6160.691965401712;6189.473894718909;6191.504679625619;6305.905411418832;5659.292588426447;5719.971400142973;5721.599488035798;5729.083346664514;5761.308642962871;5030.195168772979;5378.515933565998;5381.865453363697;5394.973025134849;5428.45715428454;6207.756218930313;6224.035274532454;6233.96883015582;6262.937370626418;6284.622922624657;6270.874582508233;6296.5277604178955;6303.936960630335;6315.778947736991;6316.873662526816;6253.8749225014335;6272.372762723691;6299.118123462627;6301.842453938688;6334.778282760081;6109.327973922954;6113.65874523782;6145.985912324595;6208.844778880383;6218.751249949952;6013.428724271187;6239.5008399327035;6282.654454005074;6307.432331090224;6314.526410519137;6057.333693293822;6159.938400615936;6166.691665416801;6168.599041062316;6169.660668663267;5862.237909071793;5952.851178720567;6128.938710612837;6145.477634400947;6160.845978850385;5959.404059594006;5998.550108741774;6009.939900601113;6025.939740602538;6036.456718895321;6155.784547540815;6175.752969881334;6185.8144255672405;6186.721597528032;6201.069839524108;5762.078067509178;5819.970900145472;5820.214271073521;5836.212111364912;6037.305709843425 23:05:01 INFO - PROCESS | 1649 | |1;ext.html;496.60271782573955;505.6713136461302;518.300294602287;540.2463563329156;558.2240685447229;30254;39543.209135817306;40616.18767624653;40751.38872916905;42957.57042429575;41825.37261941074;42048.528301509374;42313.78843105788;42704.35943460843;42759.78620106903;9526.190273826716;9726;9729.367591106591;9740.172085372717;9754.951225243864;8838.513881736502;9339.95330023349;9684.90315096847;9700.27247956406;9786.951065244664;9761.951190244039;9787.217022638208;9792.11870931616;9799.853002204973;9810.362326448794;9705.126538611523;9764.853527197098;9772.853407198898;9787.608495660164;9792.118709316124;9684.61261549537;9722.416655000652;9752.707418777449;9767.16979056781;9767.804643907082;40193.79903100495;40453.19093618111;40502.18995620071;40553;40595.391069133984;3213.775035747499;3432.6567343265724;3717.182219911615;3997.120633460634;4131.876043718693;28802;31153.532697009563;31229.219269518217;31269;31271.218219544466;3170.286685495768;3273.5908011498564;3305.008497450755;3313.3870234006786;3382.2728113455723;479843;481466;483427;483979;491722;57855.421445785;58209.70895145497;58212.70893645589;58275.417245827;58308;57135;57295.14057289144;57311.426885731446;57378.13932791012;57437.712811435675;15668.373265069498;15856.682866342608;15858.60353491172;15878;15881.523554293159;12445.62663120108;12459.252444853339;12463.75072498545;12465.314407707481;12524.874751252553;15271.770923436157;15381.384744610314;15818.60453488672;15898.52304430869;15907.0455772654;5516.641418307797;6331.366863313632;6336.619802811846;6380.904286435707;6421.646809425526;683.9555428897205;735.2316828913798;780.3873958942203;799.4403917257943;1142.1034487926938;22990.770092298862;27181.592276115873;28873;29473.705262947526;29508.114756557337;1010.782681723433;1021.6219998600566;1092.1372116028408;1098.7033500954858;1130.4969288666464;14431.711365772626;14890.404383824525;14989;15065;15421.306041228172;634203;638948;639247;639267;639576;53684.65788355322;53795;53818.192727109126;53981;53988;1213.3933033483258;1253.8119282107757;1357.9049466537247;1369.2674419185887;1388.201783974239;29094;30111;30428;30839.84580077175;30928.69071309258;47096.7645161772;47530.28704569503;47533.28700069433;47540.762296188295;47543;525059;526454;527453;527577;529167.0832916677;25027;25802.870985644953;25803.354916127246;26572.867135664197;26644.467110658064;43645;44355.55644443514;44511.61628778416;44887.266909963764;45179.54820451885;37401.62598373982;37415;37758.62241377661;38013.42979855249;38870.80564597159;35118.82440587781;36570.26859462849;37810.243795123395;37908.62091379051;38186;36035.63964360323;36665.900022999354;36868;36930.815345923096;37338.06654833651;28244;32157.517637235927;32948.1762955928;33611.66388336085;33630.831845840614;24733.62899556544;24999.375015624755;25222.117225897007;25298.36754081163;25452.872735636203 23:05:01 INFO - PROCESS | 1649 | |2;jquery.html;352.58571178864827;363.88719496955935;369.46796612877455;381.1557400358206;401.2616785115389;15035.849641503582;15220.391184352626;16366.754498682529;17392.913035434805;20788.688169677465;19307;19601.901990490063;20068.799312006875;20339.694904576405;20785.16859325625;5885.352611212763;6021.916055110079;6174.043023331368;6186.597871138384;6188.566800323979;4734.384530011111;5838.795642152526;6153.630782153085;6172.321044685081;6187.236637558297;5721.141828725683;6014.428629280212;6154.107654390111;6169.290531588857;6172.629642221481;6004;6084.452399284041;6087.239095113111;6087.908681369783;6091;5218.530332270076;5291.417944026154;5297.920531192035;5472.124460086367;5542.4457554244655;12969.610911672666;13213.009024323119;13239.271840048794;16317.59206019847;20717.92820717916;2120.236714782677;2229.799318061366;2369.1234243330077;2472.851628902272;2841.587969744396;9895.158911492532;10188.796224075551;10483.528241229164;12617.747645047139;14834.184119873402;1773;1831.3407173417693;1835.9357422490148;1883.161992913154;1962.9312974045984;102555.436114097;107577;111736;116031.41984290109;117025;9458.85811712825;11464.228271877035;11515.827262591069;11517.25137866036;11570.710732231593;11916.702082448008;14562;14572.198529081003;14580.562583122522;14614.85385146135;6999.650017499104;8325.333973282122;8492.915070849212;8498;8597.26923211534;8139.30815880645;8330.375221858385;8406.915930840736;8415.70545030921;8646.654133834701;3827.75119617224;4416.933745993812;4517.88705282364;4524.864254072383;4607.055553611502;1623.8132614749454;1901.6862217734197;1914.6170765846607;1921.8846869187894;1938.185961896007;708.624429052603;818.8321394114192;895.4537731983485;943.4386540008684;1056.2289528644133;20007.999600019943;21604.89197554002;21647.91760411973;21718;21868.562628747335;928.79102202004;932.3287233192091;962.1100482054072;965.0831709875646;987.5506644476745;10775.892241077645;11529.308241505536;11601.18791684574;11607.709807254887;11621.593244236412;589980;593307;601473;607846;613992.0905141152;123807.38096309641;124045;127935;129742;130411;952.5999080386255;961.7403301108661;989.9307048506679;990.6780296403698;991.1723710701644;13599.796003059764;19101.522461938563;19232.71150932709;19501.414957551297;19560.315388961888;52977.410677679734;53863;54065.72967135139;54442.45557544374;54809.72595136999;72758;77155;79808;79978.20348744173;81737.18262817296;7774.222577742181;7922.603869806602;8067.475614085066;8214.671413143407;8396.538190399586;9397.671081512115;12947.5468358607;13057.542985995444;13463.663408414868;13488.865111348761;10589.682309530726;11281.32312061279;11405.836869542723;11554.653360399201;11597.5360985563;10009.898911119917;10210.489475526343;10963.671089867317;11569.421528923689;11639.359835209145;10346.689599312032;10841.349519028883;11536.480858361227;11589.188756786943;11931.46308416106;8017.318527925192;8151.347892168494;8182.83634327322;8247.051589067094;8247.835043299221;11101.0564102049;11202.775944481227;11599.420028998686;11633.88366116328;11640.126990475917 23:05:01 INFO - PROCESS | 1649 | |3;mootools.html;1500.3323521033137;1530.288415886613;1726.395761483481;1836.0727832444613;1910.0640686063832;8152.429329946906;8450.577471126435;8528.189821966918;9100.27197824175;9137;8245;8341.33269338453;8342.45774024688;8721.215090641841;8975.281977441806;2392.533455976081;2432.4405386761055;2436.9512609747794;2442.4504486490487;2443.2670198940336;2390.8685022323766;2419.854808711474;2435.6833611630464;2440.926772196828;2446.522928029035;2416.2871952773885;2419.2621250518587;2437.31755108569;2445.3764290105996;2448.179859746987;2386.7016622922133;2414.0223209600053;2418.733939266679;2419.733829278778;2423.7939775119135;2347.94130146746;2379.4170428245016;2387.224152150558;2395.149721848736;2398.160643774665;8064.31453326468;8756.387052906299;8945.418547794372;9049.683261085831;9065.954670226673;5296.735163241822;5478.561715062825;5480.479354461319;5484.780608775644;5503.036968530531;3449.87925422609;3644.344018076745;3672.8714494992814;3705.5923848376656;3710.257948410329;1055.430067763407;1084.8969347911936;1090.5092708281304;1099.923005389623;1104.8895110488886;11971.760564788743;12076.516939322417;12095.637130886087;12096.697582560506;12104.39478026104;4537.682362234612;4703.789219067033;4777.904441911142;4794.089123066607;4867.245576935581;10236.232282578836;10237.795244095225;10242.436665983294;10250.231232657432;10268.794624107626;5425.376081750649;5431.321084864392;5435.565154787608;5440.863978400572;5444.482774136451;4425.601695847389;4601.44782626087;4783.736894470838;4939.950600494021;4979.800807967713;3825.8087095645105;3867.45855580219;3952.5256969163884;3980.3233450313514;4006.09862780872;1263.185245516655;1274.1208566089554;1275.4706796679275;1292.1730092740654;1297.5588299977865;840.0801122770556;852.3692467573951;900.7658008917598;907.4782000349825;944.1313991128178;5147.6911385317;5607.934492446422;5804;5996.550258730613;6128.693565321805;889.1286539191628;916.6012784438791;939.3893968920256;942.222666300305;994.4033579852031;3484.0244731475227;3851.7496362736333;3865.6714179294513;3909.9413508797393;4014.116894283253;10864.185186111074;11215.71960700989;11232.213745037932;11241.213115081866;11281.943590281997;11108.500117494732;11171.888281117084;11186.664400068012;11192.272502287326;11204.495797689124;7830.611663442591;8171.591420428955;8339.8332033359;8356.415050946496;8437;5842.737076831638;6099.085137229451;6104.328523862283;6118.908216376669;6169.383061693795;9691.466969316756;9804.313698041355;9876.308658393844;10169.389836609827;10261.486925653837;9813.558389872616;10106.949465252626;10259.640912568024;10430.634927777492;10438.11276041545;6528;7291.161516425467;7583.5070720403;7631.198724133886;7829.021372328459;8569.785755356166;8650.221480066573;8654.394192406471;8658.39391242607;8659.177378149066;7531.736389226351;7688.077430708351;7770.6784675433555;7776.105747838845;7802.414818888493;7310.853782924418;7365.8158546036775;7382.298681625237;7434.628268586657;7463.477556570988;7051.224365319708;7173.210946795953;7185.640717964186;7186.820329491805;7189.388901943394;3881.4954055972544;4812.831550895702;4818.277258411266;5081.23781432788;5091.770870310919;5025.547700706954;5075.5178258065425;5078.5175408336145;5104.106781313181;5277.02375060615 23:05:01 INFO - PROCESS | 1649 | |4;prototype.html;264.33124195784666;294.7097109347294;309.23618661905095;313.98269606475026;318.15212458797305;4714.669973101884;4772.4273087229585;5051.77267022984;5385.542228910543;5462.590305727066;4782.354382158407;5251.369835619721;5289.814856480014;5291.68249905006;5321.760520776565;3369.595648522181;3504.5093686883856;3543.096510389847;3890.396988466778;4059.7158198926013;3985.1033517458486;4106.7125301228925;4130.1739652069555;4138.668906487474;4138.9586104139025;3368.882089126881;3704.907377315562;3778.792166430845;4032.2741906456827;4109.753414795122;3390.643982381839;3858.614138586147;4070.473572410331;4076.8369265229353;4089.652379547742;3557.839897204606;3558.875439359636;3566.7681600696133;3585.5697316322207;3594.7843129412317;4204.963235294122;4810.39870016248;4955.529224723681;5209.228862186875;5272.92090618641;4636.09596128755;4641.95358046422;4665.2535594304745;4672.229082201433;4687.125149940017;5556.222128901957;5558.638688485235;5569.799929028051;5608.158776183564;5622.662640241558;3416.9829150854334;3877.5346958365176;3889.044326311995;3889.3388124018984;3907.7104555496903;4729.50340214276;5234.162533994542;5266.208330002392;5289.968456151052;5816.069428891357;3942.5071866016747;3942.9693707740535;3959.980200098981;3991.1219531702977;4010.47863777707;3837.6354246346555;4008.659263962596;4032.2741906457118;4044.757314561136;4046;4597.701149425344;4618.861434156981;4622.19111655459;4624.1676498230645;4626.4910859805395;4689.43726752785;5360.249565060894;5368.926214757064;5615.943840561624;5622.746976386073;1135.6706555098929;1323.1465704620466;1326.6219127548602;1346.7104572516955;1367.43934986655;800.9919900801034;846.9661213551512;901.4185850126631;943.4715760467949;955.8518429643416;4473.373727678128;4603.457841623027;4858.8785280367565;5051.646384753032;5318.2772059558465;4923.335349727812;5161.948380516147;5280.445553216934;5826.592138550261;5830.2129212555765;5225.320708307896;5260.815871444481;5263.105272103751;5288.788448462018;5298.311219541512;2289.9084036638346;2376.1089591403224;2552.655391522158;2566.922992310234;2579.9871000645253;4529.479109902403;4555.385023021916;4783.162946484351;4850.7866539411325;4857.562819346205;4528.682992190515;4643.744594047293;4868.3427737255015;4941.975290123526;4947.431045429822;4634.2585186370015;4689.60138388234;4762.547557981986;4941.481144479778;4958.504149585085;4045.91908161841;4170.70805043644;4467.262901621326;4799.736014519235;4808.63935204868;4039.636432721069;4123.97938010308;4742.7865746040825;4792.880177995438;4806.327114203944;4451.394370559857;4566.634669226521;4811.831585894477;4903.877403064952;4991.456066192898;3139.45059614562;3404.723228789204;3412.368711788327;3428.63999280072;3444.603870554818;4264.786760661892;4378.883384736871;4462.790918495366;4606.539346065367;4653.767311634472;3879.553851307136;3904.7114452231262;4042.7372220805555;4081.9795901020307;4596;4040.717149799486;4224.345226489899;4659.765162232079;4730.739809310383;4753.90660148161;4371.835941209122;5223.869403264949;5437.34751829783;5446.918296225639;5506.449355064461;5614.887702246014;5628.634138780966;5639.2649564961785;5646.322441307069;5667.8866422672145;4474.932876006797;4876.683015603974;5309.991101690666;5489.862753431196;5491.75287112073 23:05:01 INFO - PROCESS | 1649 | |5;yui.html;588.9714353819872;710.1549156503756;720.1543756341138;777.0093131257646;833.1793183714041;15170.54488365348;15781.60545986351;15894.364225430998;15944.601384965366;16321.673566528661;15229.543113706579;15895.205239738025;15999.120048397333;16349.182540872967;16369;829.9751007469786;857.4598003257938;859.042167982701;859.1408591408591;861.3066481482435;583.4602992232168;704.5420476690167;721.2715157690744;827.611022819277;853.9274161696263;907.2787134228304;908.7546362482126;908.913653202948;908.9500077495735;909.6134142989204;900.7432881628704;902.3773596218654;902.9729108126767;903.3450748207497;904.4076130134739;515.4613428966738;531.5747402078322;631.3307893632764;647.8801421736947;674.0058413839596;15436.614084647861;15470.535883923389;15484.612884677861;15499.922500387538;15501.302441390164;13467.730645387135;13712.794308085387;13748.65628359289;13887.652808679763;14214.644633884236;12445.886368838488;12930.935345323307;13060.738785224337;13390.19658820464;13612.455501779918;4463.4420697412825;4549.727016379028;4560.270356742905;4602.240630296031;4760.262159365304;119237.40381298125;120580.19129713062;121192;122414;122791.77208227893;9035;10673.146148308117;10714.46427678613;10855.837162442413;11298.7175320616;21360.14559417637;21610.56778864414;21610.78389216119;21644.56710865774;21669;7634;7664.80837979055;7668.110099049303;7703.268189521987;7954.642041108164;4087.6729861611593;4151.232022075866;4221.662267018631;4226.978865105655;4259.071952805459;3425.1265927188406;3527.470879368115;3527.6825085742407;3531.6291789362263;3533.717302615785;641.8735119864701;646.8544577470126;657.6514447342915;667.9599224046573;673.3233100733731;526.5682140644635;557.8103444828778;566.742132329789;606.0575804623852;653;8923.73228803137;8979.236764875059;9120.58957346921;9126.17864392208;9179.862302065474;552.740217041993;629.3454807000668;632.3170975346615;635.3360738028771;635.9650219237986;4074.8573799916862;4194.077302993336;4494.325851122292;4509.188346097734;4633.7683115844075;135420;167996;174313;177266;180839.49086618656;141684;145328;147135.2643236777;147650.52349476368;153946.46053539545;95524.52237738766;96653;123542;126394.93668354838;127360.72639273675;8668.39321247532;9270.422156627654;9732.12410883024;9788.265880059167;9905.455199964072;62885.96912246918;63461.68269158625;64094;64725.02912456411;65910.67044664746;85624.71562926436;89948;91236;96503.72725772802;99057;12410.007199424204;12640.620781376574;12703.555375561811;13067.869321306665;13271.270080145685;11762.470688819194;12941.029422793139;14071.366788494746;14418.279086045446;14534.418623254951;10880.401577913291;12393.566225182436;13690.794638080635;13971.02202845791;14173.463744214037;13130.439564284872;13323.533676321282;13372.799408009083;13409.798853017408;13676.111052781538;13222.801657975331;13405.32973351309;13434.865651343362;13443;13473.258970756702;10467.214958878267;10862.837057443987;11334.659960201207;11388.487518061542;11736.589219377282;11212;11215;11261;11494.655160345203;11558.42207889619 23:05:01 INFO - PROCESS | 1649 | __end_tp_report 23:05:01 INFO - PROCESS | 1649 | __start_cc_report 23:05:01 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1436 23:05:01 INFO - PROCESS | 1649 | __end_cc_report 23:05:01 INFO - PROCESS | 1649 | __startTimestamp1475733901007__endTimestamp 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | ------- Summary: start ------- 23:05:01 INFO - PROCESS | 1649 | Number of tests: 6 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5746.21 Median:5959.40 stddev:634.95 (10.7%) stddev-sans-first:608.12 23:05:01 INFO - PROCESS | 1649 | Values: 3337.3 3344.1 3374.5 3400.4 3415.4 4795.4 5446.6 5605.9 5718.9 5805.0 5700.4 5735.3 5799.4 5803.0 5921.3 5219.0 5221.4 5287.7 5297.2 5326.7 5216.2 5332.1 5339.2 5354.5 5365.9 5315.7 5333.7 5352.1 5352.2 5386.5 5114.4 5202.6 5256.7 5257.2 5300.6 5124.8 5149.5 5185.3 5201.8 5210.0 5577.4 5578.9 5583.5 5593.9 5619.5 6133.1 6135.0 6139.8 6140.0 6151.0 5092.6 6222.5 6259.5 6270.1 6292.3 5851.5 5866.1 5866.1 5867.2 5912.7 6174.5 6282.7 6286.8 6292.3 6324.5 6039.5 6108.8 6114.4 6117.6 6210.7 6221.5 6237.2 6295.6 6297.7 6314.9 6251.4 6255.8 6264.4 6276.6 6284.4 6138.4 6150.5 6152.0 6296.4 6300.6 4928.0 4949.9 4978.4 4980.3 5030.1 4421.7 4441.6 4444.8 4445.1 4469.5 5651.0 5744.2 5779.4 5784.3 5798.0 6159.8 6160.7 6189.5 6191.5 6305.9 5659.3 5720.0 5721.6 5729.1 5761.3 5030.2 5378.5 5381.9 5395.0 5428.5 6207.8 6224.0 6234.0 6262.9 6284.6 6270.9 6296.5 6303.9 6315.8 6316.9 6253.9 6272.4 6299.1 6301.8 6334.8 6109.3 6113.7 6146.0 6208.8 6218.8 6013.4 6239.5 6282.7 6307.4 6314.5 6057.3 6159.9 6166.7 6168.6 6169.7 5862.2 5952.9 6128.9 6145.5 6160.8 5959.4 5998.6 6009.9 6025.9 6036.5 6155.8 6175.8 6185.8 6186.7 6201.1 5762.1 5820.0 5820.2 5836.2 6037.3 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:67786.98 Median:40574.20 stddev:147936.62 (364.6%) stddev-sans-first:148265.35 23:05:01 INFO - PROCESS | 1649 | Values: 496.6 505.7 518.3 540.2 558.2 30254.0 39543.2 40616.2 40751.4 42957.6 41825.4 42048.5 42313.8 42704.4 42759.8 9526.2 9726.0 9729.4 9740.2 9755.0 8838.5 9340.0 9684.9 9700.3 9787.0 9762.0 9787.2 9792.1 9799.9 9810.4 9705.1 9764.9 9772.9 9787.6 9792.1 9684.6 9722.4 9752.7 9767.2 9767.8 40193.8 40453.2 40502.2 40553.0 40595.4 3213.8 3432.7 3717.2 3997.1 4131.9 28802.0 31153.5 31229.2 31269.0 31271.2 3170.3 3273.6 3305.0 3313.4 3382.3 479843.0 481466.0 483427.0 483979.0 491722.0 57855.4 58209.7 58212.7 58275.4 58308.0 57135.0 57295.1 57311.4 57378.1 57437.7 15668.4 15856.7 15858.6 15878.0 15881.5 12445.6 12459.3 12463.8 12465.3 12524.9 15271.8 15381.4 15818.6 15898.5 15907.0 5516.6 6331.4 6336.6 6380.9 6421.6 684.0 735.2 780.4 799.4 1142.1 22990.8 27181.6 28873.0 29473.7 29508.1 1010.8 1021.6 1092.1 1098.7 1130.5 14431.7 14890.4 14989.0 15065.0 15421.3 634203.0 638948.0 639247.0 639267.0 639576.0 53684.7 53795.0 53818.2 53981.0 53988.0 1213.4 1253.8 1357.9 1369.3 1388.2 29094.0 30111.0 30428.0 30839.8 30928.7 47096.8 47530.3 47533.3 47540.8 47543.0 525059.0 526454.0 527453.0 527577.0 529167.1 25027.0 25802.9 25803.4 26572.9 26644.5 43645.0 44355.6 44511.6 44887.3 45179.5 37401.6 37415.0 37758.6 38013.4 38870.8 35118.8 36570.3 37810.2 37908.6 38186.0 36035.6 36665.9 36868.0 36930.8 37338.1 28244.0 32157.5 32948.2 33611.7 33630.8 24733.6 24999.4 25222.1 25298.4 25452.9 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34584.93 Median:2420.99 stddev:100230.86 (4140.1%) stddev-sans-first:100479.08 23:05:01 INFO - PROCESS | 1649 | Values: 352.6 363.9 369.5 381.2 401.3 15035.8 15220.4 16366.8 17392.9 20788.7 19307.0 19601.9 20068.8 20339.7 20785.2 5885.4 6021.9 6174.0 6186.6 6188.6 4734.4 5838.8 6153.6 6172.3 6187.2 5721.1 6014.4 6154.1 6169.3 6172.6 6004.0 6084.5 6087.2 6087.9 6091.0 5218.5 5291.4 5297.9 5472.1 5542.4 12969.6 13213.0 13239.3 16317.6 20717.9 2120.2 2229.8 2369.1 2472.9 2841.6 9895.2 10188.8 10483.5 12617.7 14834.2 1773.0 1831.3 1835.9 1883.2 1962.9 102555.4 107577.0 111736.0 116031.4 117025.0 9458.9 11464.2 11515.8 11517.3 11570.7 11916.7 14562.0 14572.2 14580.6 14614.9 6999.7 8325.3 8492.9 8498.0 8597.3 8139.3 8330.4 8406.9 8415.7 8646.7 3827.8 4416.9 4517.9 4524.9 4607.1 1623.8 1901.7 1914.6 1921.9 1938.2 708.6 818.8 895.5 943.4 1056.2 20008.0 21604.9 21647.9 21718.0 21868.6 928.8 932.3 962.1 965.1 987.6 10775.9 11529.3 11601.2 11607.7 11621.6 589980.0 593307.0 601473.0 607846.0 613992.1 123807.4 124045.0 127935.0 129742.0 130411.0 952.6 961.7 989.9 990.7 991.2 13599.8 19101.5 19232.7 19501.4 19560.3 52977.4 53863.0 54065.7 54442.5 54809.7 72758.0 77155.0 79808.0 79978.2 81737.2 7774.2 7922.6 8067.5 8214.7 8396.5 9397.7 12947.5 13057.5 13463.7 13488.9 10589.7 11281.3 11405.8 11554.7 11597.5 10009.9 10210.5 10963.7 11569.4 11639.4 10346.7 10841.3 11536.5 11589.2 11931.5 8017.3 8151.3 8182.8 8247.1 8247.8 11101.1 11202.8 11599.4 11633.9 11640.1 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5796.86 Median:4842.76 stddev:3277.07 (67.7%) stddev-sans-first:3270.35 23:05:01 INFO - PROCESS | 1649 | Values: 1500.3 1530.3 1726.4 1836.1 1910.1 8152.4 8450.6 8528.2 9100.3 9137.0 8245.0 8341.3 8342.5 8721.2 8975.3 2392.5 2432.4 2437.0 2442.5 2443.3 2390.9 2419.9 2435.7 2440.9 2446.5 2416.3 2419.3 2437.3 2445.4 2448.2 2386.7 2414.0 2418.7 2419.7 2423.8 2347.9 2379.4 2387.2 2395.1 2398.2 8064.3 8756.4 8945.4 9049.7 9066.0 5296.7 5478.6 5480.5 5484.8 5503.0 3449.9 3644.3 3672.9 3705.6 3710.3 1055.4 1084.9 1090.5 1099.9 1104.9 11971.8 12076.5 12095.6 12096.7 12104.4 4537.7 4703.8 4777.9 4794.1 4867.2 10236.2 10237.8 10242.4 10250.2 10268.8 5425.4 5431.3 5435.6 5440.9 5444.5 4425.6 4601.4 4783.7 4940.0 4979.8 3825.8 3867.5 3952.5 3980.3 4006.1 1263.2 1274.1 1275.5 1292.2 1297.6 840.1 852.4 900.8 907.5 944.1 5147.7 5607.9 5804.0 5996.6 6128.7 889.1 916.6 939.4 942.2 994.4 3484.0 3851.7 3865.7 3909.9 4014.1 10864.2 11215.7 11232.2 11241.2 11281.9 11108.5 11171.9 11186.7 11192.3 11204.5 7830.6 8171.6 8339.8 8356.4 8437.0 5842.7 6099.1 6104.3 6118.9 6169.4 9691.5 9804.3 9876.3 10169.4 10261.5 9813.6 10106.9 10259.6 10430.6 10438.1 6528.0 7291.2 7583.5 7631.2 7829.0 8569.8 8650.2 8654.4 8658.4 8659.2 7531.7 7688.1 7770.7 7776.1 7802.4 7310.9 7365.8 7382.3 7434.6 7463.5 7051.2 7173.2 7185.6 7186.8 7189.4 3881.5 4812.8 4818.3 5081.2 5091.8 5025.5 5075.5 5078.5 5104.1 5277.0 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4230.66 Median:4630.37 stddev:1259.20 (27.2%) stddev-sans-first:1227.04 23:05:01 INFO - PROCESS | 1649 | Values: 264.3 294.7 309.2 314.0 318.2 4714.7 4772.4 5051.8 5385.5 5462.6 4782.4 5251.4 5289.8 5291.7 5321.8 3369.6 3504.5 3543.1 3890.4 4059.7 3985.1 4106.7 4130.2 4138.7 4139.0 3368.9 3704.9 3778.8 4032.3 4109.8 3390.6 3858.6 4070.5 4076.8 4089.7 3557.8 3558.9 3566.8 3585.6 3594.8 4205.0 4810.4 4955.5 5209.2 5272.9 4636.1 4642.0 4665.3 4672.2 4687.1 5556.2 5558.6 5569.8 5608.2 5622.7 3417.0 3877.5 3889.0 3889.3 3907.7 4729.5 5234.2 5266.2 5290.0 5816.1 3942.5 3943.0 3960.0 3991.1 4010.5 3837.6 4008.7 4032.3 4044.8 4046.0 4597.7 4618.9 4622.2 4624.2 4626.5 4689.4 5360.2 5368.9 5615.9 5622.7 1135.7 1323.1 1326.6 1346.7 1367.4 801.0 847.0 901.4 943.5 955.9 4473.4 4603.5 4858.9 5051.6 5318.3 4923.3 5161.9 5280.4 5826.6 5830.2 5225.3 5260.8 5263.1 5288.8 5298.3 2289.9 2376.1 2552.7 2566.9 2580.0 4529.5 4555.4 4783.2 4850.8 4857.6 4528.7 4643.7 4868.3 4942.0 4947.4 4634.3 4689.6 4762.5 4941.5 4958.5 4045.9 4170.7 4467.3 4799.7 4808.6 4039.6 4124.0 4742.8 4792.9 4806.3 4451.4 4566.6 4811.8 4903.9 4991.5 3139.5 3404.7 3412.4 3428.6 3444.6 4264.8 4378.9 4462.8 4606.5 4653.8 3879.6 3904.7 4042.7 4082.0 4596.0 4040.7 4224.3 4659.8 4730.7 4753.9 4371.8 5223.9 5437.3 5446.9 5506.4 5614.9 5628.6 5639.3 5646.3 5667.9 4474.9 4876.7 5310.0 5489.9 5491.8 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:26405.24 Median:3532.67 stddev:43739.89 (1238.2%) stddev-sans-first:43819.64 23:05:01 INFO - PROCESS | 1649 | Values: 589.0 710.2 720.2 777.0 833.2 15170.5 15781.6 15894.4 15944.6 16321.7 15229.5 15895.2 15999.1 16349.2 16369.0 830.0 857.5 859.0 859.1 861.3 583.5 704.5 721.3 827.6 853.9 907.3 908.8 908.9 909.0 909.6 900.7 902.4 903.0 903.3 904.4 515.5 531.6 631.3 647.9 674.0 15436.6 15470.5 15484.6 15499.9 15501.3 13467.7 13712.8 13748.7 13887.7 14214.6 12445.9 12930.9 13060.7 13390.2 13612.5 4463.4 4549.7 4560.3 4602.2 4760.3 119237.4 120580.2 121192.0 122414.0 122791.8 9035.0 10673.1 10714.5 10855.8 11298.7 21360.1 21610.6 21610.8 21644.6 21669.0 7634.0 7664.8 7668.1 7703.3 7954.6 4087.7 4151.2 4221.7 4227.0 4259.1 3425.1 3527.5 3527.7 3531.6 3533.7 641.9 646.9 657.7 668.0 673.3 526.6 557.8 566.7 606.1 653.0 8923.7 8979.2 9120.6 9126.2 9179.9 552.7 629.3 632.3 635.3 636.0 4074.9 4194.1 4494.3 4509.2 4633.8 135420.0 167996.0 174313.0 177266.0 180839.5 141684.0 145328.0 147135.3 147650.5 153946.5 95524.5 96653.0 123542.0 126394.9 127360.7 8668.4 9270.4 9732.1 9788.3 9905.5 62886.0 63461.7 64094.0 64725.0 65910.7 85624.7 89948.0 91236.0 96503.7 99057.0 12410.0 12640.6 12703.6 13067.9 13271.3 11762.5 12941.0 14071.4 14418.3 14534.4 10880.4 12393.6 13690.8 13971.0 14173.5 13130.4 13323.5 13372.8 13409.8 13676.1 13222.8 13405.3 13434.9 13443.0 13473.3 10467.2 10862.8 11334.7 11388.5 11736.6 11212.0 11215.0 11261.0 11494.7 11558.4 23:05:01 INFO - PROCESS | 1649 | -------- Summary: end -------- 23:05:01 INFO - PROCESS | 1649 | 23:05:01 INFO - TEST-INFO | 1649: exit 0 23:05:01 INFO - TEST-OK | dromaeo_css | took 1118550ms 23:05:01 INFO - TEST-START | kraken 23:05:01 INFO - operating with platform_type : mac_ 23:05:01 INFO - Initialising browser for kraken test... 23:05:01 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp6ozyNi/profile http://localhost:49268/getInfo.html) 23:05:04 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 23:05:04 INFO - PROCESS | 1659 | colorDepth:24 23:05:04 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 23:05:04 INFO - PROCESS | 1659 | __metrics 23:05:04 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:05:04 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:05:04 INFO - TEST-INFO | 1659: exit 0 23:05:04 INFO - Browser initialized. 23:05:04 INFO - Running cycle 1/1 for kraken test... 23:05:04 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp6ozyNi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:05:16 INFO - PROCESS | 1660 | RSS: Main: 244035584 23:05:16 INFO - PROCESS | 1660 | 23:05:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 23:05:20 INFO - PROCESS | 1660 | RSS: Main: 295202816 23:05:20 INFO - PROCESS | 1660 | 23:05:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 23:05:23 INFO - PROCESS | 1660 | RSS: Main: 293761024 23:05:23 INFO - PROCESS | 1660 | 23:05:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 23:05:28 INFO - PROCESS | 1660 | RSS: Main: 385036288 23:05:28 INFO - PROCESS | 1660 | 23:05:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 23:05:30 INFO - PROCESS | 1660 | RSS: Main: 289370112 23:05:30 INFO - PROCESS | 1660 | 23:05:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:33 INFO - PROCESS | 1660 | RSS: Main: 290971648 23:05:33 INFO - PROCESS | 1660 | 23:05:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:36 INFO - PROCESS | 1660 | RSS: Main: 329129984 23:05:36 INFO - PROCESS | 1660 | 23:05:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:40 INFO - PROCESS | 1660 | RSS: Main: 328347648 23:05:40 INFO - PROCESS | 1660 | 23:05:42 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 23:05:43 INFO - PROCESS | 1660 | RSS: Main: 327012352 23:05:43 INFO - PROCESS | 1660 | 23:05:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:45 INFO - PROCESS | 1660 | RSS: Main: 302444544 23:05:45 INFO - PROCESS | 1660 | 23:05:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:48 INFO - PROCESS | 1660 | RSS: Main: 306987008 23:05:48 INFO - PROCESS | 1660 | 23:05:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:05:51 INFO - PROCESS | 1660 | RSS: Main: 308051968 23:05:51 INFO - PROCESS | 1660 | 23:05:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:05:55 INFO - PROCESS | 1660 | RSS: Main: 319778816 23:05:55 INFO - PROCESS | 1660 | 23:05:58 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:05:58 INFO - PROCESS | 1660 | RSS: Main: 319479808 23:05:58 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 23:06:00 INFO - PROCESS | 1660 | RSS: Main: 363364352 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | __start_tp_report 23:06:00 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 23:06:00 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 23:06:00 INFO - PROCESS | 1660 | |i|pagename|runs| 23:06:00 INFO - PROCESS | 1660 | |0;ai-astar;113;112;114;113;113;113;113;112;112;114 23:06:00 INFO - PROCESS | 1660 | |1;audio-beat-detection;171;146;143;149;117;115;118;112;146;111 23:06:00 INFO - PROCESS | 1660 | |2;audio-dft;180;184;239;260;208;184;205;182;181;183 23:06:00 INFO - PROCESS | 1660 | |3;audio-fft;80;60;60;90;64;62;74;69;83;77 23:06:00 INFO - PROCESS | 1660 | |4;audio-oscillator;90;71;92;70;68;69;92;54;68;99 23:06:00 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;90;87;91;86;90;86;90;87;89;87 23:06:00 INFO - PROCESS | 1660 | |6;imaging-darkroom;95;94;96;94;97;95;95;102;94;95 23:06:00 INFO - PROCESS | 1660 | |7;imaging-desaturate;84;85;86;85;86;85;86;86;104;71 23:06:00 INFO - PROCESS | 1660 | |8;json-parse-financial;56;55;52;52;67;84;43;45;72;75 23:06:00 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;66;80;102;112;81;70;70;70;70;71 23:06:00 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;88;89;87;87;86;87;82;76;99;101 23:06:00 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;144;146;169;160;158;131;148;123;201;161 23:06:00 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;218;171;172;173;174;172;171;172;172;169 23:06:00 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;71;70;70;71;69;68;68;69;73;84 23:06:00 INFO - PROCESS | 1660 | __end_tp_report 23:06:00 INFO - PROCESS | 1660 | __start_cc_report 23:06:00 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2069 23:06:00 INFO - PROCESS | 1660 | __end_cc_report 23:06:00 INFO - PROCESS | 1660 | __startTimestamp1475733960706__endTimestamp 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | ------- Summary: start ------- 23:06:00 INFO - PROCESS | 1660 | Number of tests: 14 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 23:06:00 INFO - PROCESS | 1660 | Values: 113.0 112.0 114.0 113.0 113.0 113.0 113.0 112.0 112.0 114.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:144.50 stddev:20.73 (14.3%) stddev-sans-first:16.76 23:06:00 INFO - PROCESS | 1660 | Values: 171.0 146.0 143.0 149.0 117.0 115.0 118.0 112.0 146.0 111.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:200.60 Median:194.50 stddev:28.07 (14.4%) stddev-sans-first:28.77 23:06:00 INFO - PROCESS | 1660 | Values: 180.0 184.0 239.0 260.0 208.0 184.0 205.0 182.0 181.0 183.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:71.90 Median:75.50 stddev:10.54 (14.0%) stddev-sans-first:10.76 23:06:00 INFO - PROCESS | 1660 | Values: 80.0 60.0 60.0 90.0 64.0 62.0 74.0 69.0 83.0 77.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:80.50 stddev:14.69 (18.2%) stddev-sans-first:14.84 23:06:00 INFO - PROCESS | 1660 | Values: 90.0 71.0 92.0 70.0 68.0 69.0 92.0 54.0 68.0 99.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.50 stddev:1.89 (2.1%) stddev-sans-first:1.90 23:06:00 INFO - PROCESS | 1660 | Values: 90.0 87.0 91.0 86.0 90.0 86.0 90.0 87.0 89.0 87.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:95.00 stddev:2.41 (2.5%) stddev-sans-first:2.54 23:06:00 INFO - PROCESS | 1660 | Values: 95.0 94.0 96.0 94.0 97.0 95.0 95.0 102.0 94.0 95.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:85.50 stddev:7.86 (9.2%) stddev-sans-first:8.31 23:06:00 INFO - PROCESS | 1660 | Values: 84.0 85.0 86.0 85.0 86.0 85.0 86.0 86.0 104.0 71.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:61.50 stddev:13.65 (22.2%) stddev-sans-first:14.40 23:06:00 INFO - PROCESS | 1660 | Values: 56.0 55.0 52.0 52.0 67.0 84.0 43.0 45.0 72.0 75.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:70.00 stddev:15.56 (22.2%) stddev-sans-first:15.76 23:06:00 INFO - PROCESS | 1660 | Values: 66.0 80.0 102.0 112.0 81.0 70.0 70.0 70.0 70.0 71.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:87.00 stddev:7.28 (8.4%) stddev-sans-first:7.73 23:06:00 INFO - PROCESS | 1660 | Values: 88.0 89.0 87.0 87.0 86.0 87.0 82.0 76.0 99.0 101.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:159.00 stddev:21.67 (13.6%) stddev-sans-first:22.67 23:06:00 INFO - PROCESS | 1660 | Values: 144.0 146.0 169.0 160.0 158.0 131.0 148.0 123.0 201.0 161.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:172.00 stddev:14.68 (8.5%) stddev-sans-first:1.39 23:06:00 INFO - PROCESS | 1660 | Values: 218.0 171.0 172.0 173.0 174.0 172.0 171.0 172.0 172.0 169.0 23:06:00 INFO - PROCESS | 1660 | 23:06:00 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:70.50 stddev:4.72 (6.7%) stddev-sans-first:5.00 23:06:00 INFO - PROCESS | 1660 | Values: 71.0 70.0 70.0 71.0 69.0 68.0 68.0 69.0 73.0 84.0 23:06:00 INFO - PROCESS | 1660 | -------- Summary: end -------- 23:06:00 INFO - PROCESS | 1660 | 23:06:01 INFO - TEST-INFO | 1660: exit 0 23:06:01 INFO - TEST-OK | kraken | took 59925ms 23:06:01 INFO - SUITE-END | took 1178s 23:06:01 INFO - Completed test suite (00:19:38) 23:06:01 INFO - javascript benchmark 23:06:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.282484265883, 3344.147242453174, 3374.4938259261107, 3400.4049291374004, 3415.4193787056197, 4795.400574928134, 5446.645968012076, 5605.859853503654, 5718.885622287552, 5804.9999999999945, 5700.429957004298, 5735.311762588485, 5799.362070172288, 5803.0, 5921.289445266563, 5218.973905130469, 5221.399539053001, 5287.656302340355, 5297.231901374298, 5326.680399176043, 5216.165413533835, 5332.120200166968, 5339.172428273624, 5354.544863686591, 5365.865853353678, 5315.734213289359, 5333.706646134461, 5352.116900711398, 5352.197170424429, 5386.542143917772, 5114.411842638107, 5202.635815492917, 5256.737163141827, 5257.211418287287, 5300.575953923677, 5124.820631277925, 5149.5107964743665, 5185.274061631375, 5201.843944681666, 5209.973950130263, 5577.414371490976, 5578.944210557883, 5583.4974852263085, 5593.888122237573, 5619.466150715675, 6133.1106989486725, 6134.96932515339, 6139.785107521216, 6140.0, 6150.954337762591, 5092.567131793802, 6222.471089957314, 6259.499240060784, 6270.122182894398, 6292.276388215322, 5851.473367396955, 5866.061430171152, 5866.061430171152, 5867.237259156283, 5912.733926973254, 6174.5060395168275, 6282.654454004982, 6286.779962701375, 6292.339304373067, 6324.525660575475, 6039.456448919531, 6108.847278818065, 6114.388610881663, 6117.571769976059, 6210.689465526705, 6221.502279817604, 6237.157983672237, 6295.5907865988565, 6297.716602752887, 6314.936850631527, 6251.374862513712, 6255.812325630239, 6264.436200741864, 6276.592021518587, 6284.4344009039405, 6138.416850399205, 6150.538709596798, 6151.954167791486, 6296.401841825111, 6300.59046161998, 4927.975360123177, 4949.90100197994, 4978.352814134212, 4980.327655766425, 5030.144875371196, 4421.734695918255, 4441.578050085237, 4444.82220711168, 4445.110977804452, 4469.530699276529, 5651.0, 5744.195812586241, 5779.393163717749, 5784.334801497797, 5798.0, 6159.753609855646, 6160.691965401712, 6189.473894718909, 6191.504679625619, 6305.905411418832, 5659.292588426447, 5719.971400142973, 5721.599488035798, 5729.083346664514, 5761.308642962871, 5030.195168772979, 5378.515933565998, 5381.865453363697, 5394.973025134849, 5428.45715428454, 6207.756218930313, 6224.035274532454, 6233.96883015582, 6262.937370626418, 6284.622922624657, 6270.874582508233, 6296.5277604178955, 6303.936960630335, 6315.778947736991, 6316.873662526816, 6253.8749225014335, 6272.372762723691, 6299.118123462627, 6301.842453938688, 6334.778282760081, 6109.327973922954, 6113.65874523782, 6145.985912324595, 6208.844778880383, 6218.751249949952, 6013.428724271187, 6239.5008399327035, 6282.654454005074, 6307.432331090224, 6314.526410519137, 6057.333693293822, 6159.938400615936, 6166.691665416801, 6168.599041062316, 6169.660668663267, 5862.237909071793, 5952.851178720567, 6128.938710612837, 6145.477634400947, 6160.845978850385, 5959.404059594006, 5998.550108741774, 6009.939900601113, 6025.939740602538, 6036.456718895321, 6155.784547540815, 6175.752969881334, 6185.8144255672405, 6186.721597528032, 6201.069839524108, 5762.078067509178, 5819.970900145472, 5820.214271073521, 5836.212111364912, 6037.305709843425], "value": 5705.959935662893, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.60271782573955, 505.6713136461302, 518.300294602287, 540.2463563329156, 558.2240685447229, 30254.0, 39543.209135817306, 40616.18767624653, 40751.38872916905, 42957.57042429575, 41825.37261941074, 42048.528301509374, 42313.78843105788, 42704.35943460843, 42759.78620106903, 9526.190273826716, 9726.0, 9729.367591106591, 9740.172085372717, 9754.951225243864, 8838.513881736502, 9339.95330023349, 9684.90315096847, 9700.27247956406, 9786.951065244664, 9761.951190244039, 9787.217022638208, 9792.11870931616, 9799.853002204973, 9810.362326448794, 9705.126538611523, 9764.853527197098, 9772.853407198898, 9787.608495660164, 9792.118709316124, 9684.61261549537, 9722.416655000652, 9752.707418777449, 9767.16979056781, 9767.804643907082, 40193.79903100495, 40453.19093618111, 40502.18995620071, 40553.0, 40595.391069133984, 3213.775035747499, 3432.6567343265724, 3717.182219911615, 3997.120633460634, 4131.876043718693, 28802.0, 31153.532697009563, 31229.219269518217, 31269.0, 31271.218219544466, 3170.286685495768, 3273.5908011498564, 3305.008497450755, 3313.3870234006786, 3382.2728113455723, 479843.0, 481466.0, 483427.0, 483979.0, 491722.0, 57855.421445785, 58209.70895145497, 58212.70893645589, 58275.417245827, 58308.0, 57135.0, 57295.14057289144, 57311.426885731446, 57378.13932791012, 57437.712811435675, 15668.373265069498, 15856.682866342608, 15858.60353491172, 15878.0, 15881.523554293159, 12445.62663120108, 12459.252444853339, 12463.75072498545, 12465.314407707481, 12524.874751252553, 15271.770923436157, 15381.384744610314, 15818.60453488672, 15898.52304430869, 15907.0455772654, 5516.641418307797, 6331.366863313632, 6336.619802811846, 6380.904286435707, 6421.646809425526, 683.9555428897205, 735.2316828913798, 780.3873958942203, 799.4403917257943, 1142.1034487926938, 22990.770092298862, 27181.592276115873, 28873.0, 29473.705262947526, 29508.114756557337, 1010.782681723433, 1021.6219998600566, 1092.1372116028408, 1098.7033500954858, 1130.4969288666464, 14431.711365772626, 14890.404383824525, 14989.0, 15065.0, 15421.306041228172, 634203.0, 638948.0, 639247.0, 639267.0, 639576.0, 53684.65788355322, 53795.0, 53818.192727109126, 53981.0, 53988.0, 1213.3933033483258, 1253.8119282107757, 1357.9049466537247, 1369.2674419185887, 1388.201783974239, 29094.0, 30111.0, 30428.0, 30839.84580077175, 30928.69071309258, 47096.7645161772, 47530.28704569503, 47533.28700069433, 47540.762296188295, 47543.0, 525059.0, 526454.0, 527453.0, 527577.0, 529167.0832916677, 25027.0, 25802.870985644953, 25803.354916127246, 26572.867135664197, 26644.467110658064, 43645.0, 44355.55644443514, 44511.61628778416, 44887.266909963764, 45179.54820451885, 37401.62598373982, 37415.0, 37758.62241377661, 38013.42979855249, 38870.80564597159, 35118.82440587781, 36570.26859462849, 37810.243795123395, 37908.62091379051, 38186.0, 36035.63964360323, 36665.900022999354, 36868.0, 36930.815345923096, 37338.06654833651, 28244.0, 32157.517637235927, 32948.1762955928, 33611.66388336085, 33630.831845840614, 24733.62899556544, 24999.375015624755, 25222.117225897007, 25298.36754081163, 25452.872735636203], "value": 19351.074334430636, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.58571178864827, 363.88719496955935, 369.46796612877455, 381.1557400358206, 401.2616785115389, 15035.849641503582, 15220.391184352626, 16366.754498682529, 17392.913035434805, 20788.688169677465, 19307.0, 19601.901990490063, 20068.799312006875, 20339.694904576405, 20785.16859325625, 5885.352611212763, 6021.916055110079, 6174.043023331368, 6186.597871138384, 6188.566800323979, 4734.384530011111, 5838.795642152526, 6153.630782153085, 6172.321044685081, 6187.236637558297, 5721.141828725683, 6014.428629280212, 6154.107654390111, 6169.290531588857, 6172.629642221481, 6004.0, 6084.452399284041, 6087.239095113111, 6087.908681369783, 6091.0, 5218.530332270076, 5291.417944026154, 5297.920531192035, 5472.124460086367, 5542.4457554244655, 12969.610911672666, 13213.009024323119, 13239.271840048794, 16317.59206019847, 20717.92820717916, 2120.236714782677, 2229.799318061366, 2369.1234243330077, 2472.851628902272, 2841.587969744396, 9895.158911492532, 10188.796224075551, 10483.528241229164, 12617.747645047139, 14834.184119873402, 1773.0, 1831.3407173417693, 1835.9357422490148, 1883.161992913154, 1962.9312974045984, 102555.436114097, 107577.0, 111736.0, 116031.41984290109, 117025.0, 9458.85811712825, 11464.228271877035, 11515.827262591069, 11517.25137866036, 11570.710732231593, 11916.702082448008, 14562.0, 14572.198529081003, 14580.562583122522, 14614.85385146135, 6999.650017499104, 8325.333973282122, 8492.915070849212, 8498.0, 8597.26923211534, 8139.30815880645, 8330.375221858385, 8406.915930840736, 8415.70545030921, 8646.654133834701, 3827.75119617224, 4416.933745993812, 4517.88705282364, 4524.864254072383, 4607.055553611502, 1623.8132614749454, 1901.6862217734197, 1914.6170765846607, 1921.8846869187894, 1938.185961896007, 708.624429052603, 818.8321394114192, 895.4537731983485, 943.4386540008684, 1056.2289528644133, 20007.999600019943, 21604.89197554002, 21647.91760411973, 21718.0, 21868.562628747335, 928.79102202004, 932.3287233192091, 962.1100482054072, 965.0831709875646, 987.5506644476745, 10775.892241077645, 11529.308241505536, 11601.18791684574, 11607.709807254887, 11621.593244236412, 589980.0, 593307.0, 601473.0, 607846.0, 613992.0905141152, 123807.38096309641, 124045.0, 127935.0, 129742.0, 130411.0, 952.5999080386255, 961.7403301108661, 989.9307048506679, 990.6780296403698, 991.1723710701644, 13599.796003059764, 19101.522461938563, 19232.71150932709, 19501.414957551297, 19560.315388961888, 52977.410677679734, 53863.0, 54065.72967135139, 54442.45557544374, 54809.72595136999, 72758.0, 77155.0, 79808.0, 79978.20348744173, 81737.18262817296, 7774.222577742181, 7922.603869806602, 8067.475614085066, 8214.671413143407, 8396.538190399586, 9397.671081512115, 12947.5468358607, 13057.542985995444, 13463.663408414868, 13488.865111348761, 10589.682309530726, 11281.32312061279, 11405.836869542723, 11554.653360399201, 11597.5360985563, 10009.898911119917, 10210.489475526343, 10963.671089867317, 11569.421528923689, 11639.359835209145, 10346.689599312032, 10841.349519028883, 11536.480858361227, 11589.188756786943, 11931.46308416106, 8017.318527925192, 8151.347892168494, 8182.83634327322, 8247.051589067094, 8247.835043299221, 11101.0564102049, 11202.775944481227, 11599.420028998686, 11633.88366116328, 11640.126990475917], "value": 9394.351658366331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.3323521033137, 1530.288415886613, 1726.395761483481, 1836.0727832444613, 1910.0640686063832, 8152.429329946906, 8450.577471126435, 8528.189821966918, 9100.27197824175, 9137.0, 8245.0, 8341.33269338453, 8342.45774024688, 8721.215090641841, 8975.281977441806, 2392.533455976081, 2432.4405386761055, 2436.9512609747794, 2442.4504486490487, 2443.2670198940336, 2390.8685022323766, 2419.854808711474, 2435.6833611630464, 2440.926772196828, 2446.522928029035, 2416.2871952773885, 2419.2621250518587, 2437.31755108569, 2445.3764290105996, 2448.179859746987, 2386.7016622922133, 2414.0223209600053, 2418.733939266679, 2419.733829278778, 2423.7939775119135, 2347.94130146746, 2379.4170428245016, 2387.224152150558, 2395.149721848736, 2398.160643774665, 8064.31453326468, 8756.387052906299, 8945.418547794372, 9049.683261085831, 9065.954670226673, 5296.735163241822, 5478.561715062825, 5480.479354461319, 5484.780608775644, 5503.036968530531, 3449.87925422609, 3644.344018076745, 3672.8714494992814, 3705.5923848376656, 3710.257948410329, 1055.430067763407, 1084.8969347911936, 1090.5092708281304, 1099.923005389623, 1104.8895110488886, 11971.760564788743, 12076.516939322417, 12095.637130886087, 12096.697582560506, 12104.39478026104, 4537.682362234612, 4703.789219067033, 4777.904441911142, 4794.089123066607, 4867.245576935581, 10236.232282578836, 10237.795244095225, 10242.436665983294, 10250.231232657432, 10268.794624107626, 5425.376081750649, 5431.321084864392, 5435.565154787608, 5440.863978400572, 5444.482774136451, 4425.601695847389, 4601.44782626087, 4783.736894470838, 4939.950600494021, 4979.800807967713, 3825.8087095645105, 3867.45855580219, 3952.5256969163884, 3980.3233450313514, 4006.09862780872, 1263.185245516655, 1274.1208566089554, 1275.4706796679275, 1292.1730092740654, 1297.5588299977865, 840.0801122770556, 852.3692467573951, 900.7658008917598, 907.4782000349825, 944.1313991128178, 5147.6911385317, 5607.934492446422, 5804.0, 5996.550258730613, 6128.693565321805, 889.1286539191628, 916.6012784438791, 939.3893968920256, 942.222666300305, 994.4033579852031, 3484.0244731475227, 3851.7496362736333, 3865.6714179294513, 3909.9413508797393, 4014.116894283253, 10864.185186111074, 11215.71960700989, 11232.213745037932, 11241.213115081866, 11281.943590281997, 11108.500117494732, 11171.888281117084, 11186.664400068012, 11192.272502287326, 11204.495797689124, 7830.611663442591, 8171.591420428955, 8339.8332033359, 8356.415050946496, 8437.0, 5842.737076831638, 6099.085137229451, 6104.328523862283, 6118.908216376669, 6169.383061693795, 9691.466969316756, 9804.313698041355, 9876.308658393844, 10169.389836609827, 10261.486925653837, 9813.558389872616, 10106.949465252626, 10259.640912568024, 10430.634927777492, 10438.11276041545, 6528.0, 7291.161516425467, 7583.5070720403, 7631.198724133886, 7829.021372328459, 8569.785755356166, 8650.221480066573, 8654.394192406471, 8658.39391242607, 8659.177378149066, 7531.736389226351, 7688.077430708351, 7770.6784675433555, 7776.105747838845, 7802.414818888493, 7310.853782924418, 7365.8158546036775, 7382.298681625237, 7434.628268586657, 7463.477556570988, 7051.224365319708, 7173.210946795953, 7185.640717964186, 7186.820329491805, 7189.388901943394, 3881.4954055972544, 4812.831550895702, 4818.277258411266, 5081.23781432788, 5091.770870310919, 5025.547700706954, 5075.5178258065425, 5078.5175408336145, 5104.106781313181, 5277.02375060615], "value": 4652.36092653651, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.33124195784666, 294.7097109347294, 309.23618661905095, 313.98269606475026, 318.15212458797305, 4714.669973101884, 4772.4273087229585, 5051.77267022984, 5385.542228910543, 5462.590305727066, 4782.354382158407, 5251.369835619721, 5289.814856480014, 5291.68249905006, 5321.760520776565, 3369.595648522181, 3504.5093686883856, 3543.096510389847, 3890.396988466778, 4059.7158198926013, 3985.1033517458486, 4106.7125301228925, 4130.1739652069555, 4138.668906487474, 4138.9586104139025, 3368.882089126881, 3704.907377315562, 3778.792166430845, 4032.2741906456827, 4109.753414795122, 3390.643982381839, 3858.614138586147, 4070.473572410331, 4076.8369265229353, 4089.652379547742, 3557.839897204606, 3558.875439359636, 3566.7681600696133, 3585.5697316322207, 3594.7843129412317, 4204.963235294122, 4810.39870016248, 4955.529224723681, 5209.228862186875, 5272.92090618641, 4636.09596128755, 4641.95358046422, 4665.2535594304745, 4672.229082201433, 4687.125149940017, 5556.222128901957, 5558.638688485235, 5569.799929028051, 5608.158776183564, 5622.662640241558, 3416.9829150854334, 3877.5346958365176, 3889.044326311995, 3889.3388124018984, 3907.7104555496903, 4729.50340214276, 5234.162533994542, 5266.208330002392, 5289.968456151052, 5816.069428891357, 3942.5071866016747, 3942.9693707740535, 3959.980200098981, 3991.1219531702977, 4010.47863777707, 3837.6354246346555, 4008.659263962596, 4032.2741906457118, 4044.757314561136, 4046.0, 4597.701149425344, 4618.861434156981, 4622.19111655459, 4624.1676498230645, 4626.4910859805395, 4689.43726752785, 5360.249565060894, 5368.926214757064, 5615.943840561624, 5622.746976386073, 1135.6706555098929, 1323.1465704620466, 1326.6219127548602, 1346.7104572516955, 1367.43934986655, 800.9919900801034, 846.9661213551512, 901.4185850126631, 943.4715760467949, 955.8518429643416, 4473.373727678128, 4603.457841623027, 4858.8785280367565, 5051.646384753032, 5318.2772059558465, 4923.335349727812, 5161.948380516147, 5280.445553216934, 5826.592138550261, 5830.2129212555765, 5225.320708307896, 5260.815871444481, 5263.105272103751, 5288.788448462018, 5298.311219541512, 2289.9084036638346, 2376.1089591403224, 2552.655391522158, 2566.922992310234, 2579.9871000645253, 4529.479109902403, 4555.385023021916, 4783.162946484351, 4850.7866539411325, 4857.562819346205, 4528.682992190515, 4643.744594047293, 4868.3427737255015, 4941.975290123526, 4947.431045429822, 4634.2585186370015, 4689.60138388234, 4762.547557981986, 4941.481144479778, 4958.504149585085, 4045.91908161841, 4170.70805043644, 4467.262901621326, 4799.736014519235, 4808.63935204868, 4039.636432721069, 4123.97938010308, 4742.7865746040825, 4792.880177995438, 4806.327114203944, 4451.394370559857, 4566.634669226521, 4811.831585894477, 4903.877403064952, 4991.456066192898, 3139.45059614562, 3404.723228789204, 3412.368711788327, 3428.63999280072, 3444.603870554818, 4264.786760661892, 4378.883384736871, 4462.790918495366, 4606.539346065367, 4653.767311634472, 3879.553851307136, 3904.7114452231262, 4042.7372220805555, 4081.9795901020307, 4596.0, 4040.717149799486, 4224.345226489899, 4659.765162232079, 4730.739809310383, 4753.90660148161, 4371.835941209122, 5223.869403264949, 5437.34751829783, 5446.918296225639, 5506.449355064461, 5614.887702246014, 5628.634138780966, 5639.2649564961785, 5646.322441307069, 5667.8866422672145, 4474.932876006797, 4876.683015603974, 5309.991101690666, 5489.862753431196, 5491.75287112073], "value": 3837.902162821837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.9714353819872, 710.1549156503756, 720.1543756341138, 777.0093131257646, 833.1793183714041, 15170.54488365348, 15781.60545986351, 15894.364225430998, 15944.601384965366, 16321.673566528661, 15229.543113706579, 15895.205239738025, 15999.120048397333, 16349.182540872967, 16369.0, 829.9751007469786, 857.4598003257938, 859.042167982701, 859.1408591408591, 861.3066481482435, 583.4602992232168, 704.5420476690167, 721.2715157690744, 827.611022819277, 853.9274161696263, 907.2787134228304, 908.7546362482126, 908.913653202948, 908.9500077495735, 909.6134142989204, 900.7432881628704, 902.3773596218654, 902.9729108126767, 903.3450748207497, 904.4076130134739, 515.4613428966738, 531.5747402078322, 631.3307893632764, 647.8801421736947, 674.0058413839596, 15436.614084647861, 15470.535883923389, 15484.612884677861, 15499.922500387538, 15501.302441390164, 13467.730645387135, 13712.794308085387, 13748.65628359289, 13887.652808679763, 14214.644633884236, 12445.886368838488, 12930.935345323307, 13060.738785224337, 13390.19658820464, 13612.455501779918, 4463.4420697412825, 4549.727016379028, 4560.270356742905, 4602.240630296031, 4760.262159365304, 119237.40381298125, 120580.19129713062, 121192.0, 122414.0, 122791.77208227893, 9035.0, 10673.146148308117, 10714.46427678613, 10855.837162442413, 11298.7175320616, 21360.14559417637, 21610.56778864414, 21610.78389216119, 21644.56710865774, 21669.0, 7634.0, 7664.80837979055, 7668.110099049303, 7703.268189521987, 7954.642041108164, 4087.6729861611593, 4151.232022075866, 4221.662267018631, 4226.978865105655, 4259.071952805459, 3425.1265927188406, 3527.470879368115, 3527.6825085742407, 3531.6291789362263, 3533.717302615785, 641.8735119864701, 646.8544577470126, 657.6514447342915, 667.9599224046573, 673.3233100733731, 526.5682140644635, 557.8103444828778, 566.742132329789, 606.0575804623852, 653.0, 8923.73228803137, 8979.236764875059, 9120.58957346921, 9126.17864392208, 9179.862302065474, 552.740217041993, 629.3454807000668, 632.3170975346615, 635.3360738028771, 635.9650219237986, 4074.8573799916862, 4194.077302993336, 4494.325851122292, 4509.188346097734, 4633.7683115844075, 135420.0, 167996.0, 174313.0, 177266.0, 180839.49086618656, 141684.0, 145328.0, 147135.2643236777, 147650.52349476368, 153946.46053539545, 95524.52237738766, 96653.0, 123542.0, 126394.93668354838, 127360.72639273675, 8668.39321247532, 9270.422156627654, 9732.12410883024, 9788.265880059167, 9905.455199964072, 62885.96912246918, 63461.68269158625, 64094.0, 64725.02912456411, 65910.67044664746, 85624.71562926436, 89948.0, 91236.0, 96503.72725772802, 99057.0, 12410.007199424204, 12640.620781376574, 12703.555375561811, 13067.869321306665, 13271.270080145685, 11762.470688819194, 12941.029422793139, 14071.366788494746, 14418.279086045446, 14534.418623254951, 10880.401577913291, 12393.566225182436, 13690.794638080635, 13971.02202845791, 14173.463744214037, 13130.439564284872, 13323.533676321282, 13372.799408009083, 13409.798853017408, 13676.111052781538, 13222.801657975331, 13405.32973351309, 13434.865651343362, 13443.0, 13473.258970756702, 10467.214958878267, 10862.837057443987, 11334.659960201207, 11388.487518061542, 11736.589219377282, 11212.0, 11215.0, 11261.0, 11494.655160345203, 11558.42207889619], "value": 7863.699837130403, "unit": "score"}], "name": "dromaeo_css", "value": 7253.6570530869185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0, 112.0, 112.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 146.0, 143.0, 149.0, 117.0, 115.0, 118.0, 112.0, 146.0, 111.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 184.0, 239.0, 260.0, 208.0, 184.0, 205.0, 182.0, 181.0, 183.0], "value": 200.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 90.0, 64.0, 62.0, 74.0, 69.0, 83.0, 77.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 92.0, 70.0, 68.0, 69.0, 92.0, 54.0, 68.0, 99.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 86.0, 90.0, 86.0, 90.0, 87.0, 89.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 97.0, 95.0, 95.0, 102.0, 94.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 85.0, 86.0, 85.0, 86.0, 86.0, 104.0, 71.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 52.0, 52.0, 67.0, 84.0, 43.0, 45.0, 72.0, 75.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 80.0, 102.0, 112.0, 81.0, 70.0, 70.0, 70.0, 70.0, 71.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 87.0, 86.0, 87.0, 82.0, 76.0, 99.0, 101.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 146.0, 169.0, 160.0, 158.0, 131.0, 148.0, 123.0, 201.0, 161.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 171.0, 172.0, 173.0, 174.0, 172.0, 171.0, 172.0, 172.0, 169.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 71.0, 69.0, 68.0, 68.0, 69.0, 73.0, 84.0], "value": 71.3, "unit": "score"}], "name": "kraken", "value": 1494.6, "alertThreshold": 2.0}]} 23:06:01 INFO - Return code: 0 23:06:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:06:02 INFO - # TBPL SUCCESS # 23:06:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:06:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:06:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:06:02 INFO - 2016-10-05 23:06:02.037 screenresolution[1664:10070] starting screenresolution argv=screenresolution get 23:06:02 INFO - 2016-10-05 23:06:02.040 screenresolution[1664:10070] Display 0: 1600x1200x32@60 23:06:02 INFO - 2016-10-05 23:06:02.046 screenresolution[1665:10073] starting screenresolution argv=screenresolution list 23:06:02 INFO - Available Modes on Display 0 23:06:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:06:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:06:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:06:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:06:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:06:02 INFO - Intel Iris: 23:06:02 INFO - Chipset Model: Intel Iris 23:06:02 INFO - Type: GPU 23:06:02 INFO - Bus: Built-In 23:06:02 INFO - VRAM (Dynamic, Max): 1536 MB 23:06:02 INFO - Vendor: Intel (0x8086) 23:06:02 INFO - Device ID: 0x0a2e 23:06:02 INFO - Revision ID: 0x0009 23:06:02 INFO - Displays: 23:06:02 INFO - Display: 23:06:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:06:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:06:02 INFO - Display Serial Number: 1600x1200 60 23:06:02 INFO - Main Display: Yes 23:06:02 INFO - Mirror: Off 23:06:02 INFO - Online: Yes 23:06:02 INFO - Rotation: Supported 23:06:02 INFO - Return code: 0 23:06:02 INFO - Running post-action listener: _resource_record_post_action 23:06:02 INFO - [mozharness: 2016-10-06 06:06:02.284568Z] Finished run-tests step (success) 23:06:02 INFO - Running post-run listener: _resource_record_post_run 23:06:02 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 37311488; Write bytes: 708237824; Read time: 240; Write time: 1949 23:06:02 INFO - TinderboxPrint: CPU usage
53.5% 23:06:02 INFO - TinderboxPrint: I/O read bytes / time
37,311,488 / 240 23:06:02 INFO - TinderboxPrint: I/O write bytes / time
708,237,824 / 1,949 23:06:02 INFO - TinderboxPrint: CPU idle
2,259.4 (46.5%) 23:06:02 INFO - TinderboxPrint: CPU system
966.8 (19.9%) 23:06:02 INFO - TinderboxPrint: CPU user
1,631.4 (33.6%) 23:06:02 INFO - TinderboxPrint: Swap in / out
445,087,744 / 0 23:06:02 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391789056; Write bytes: 385239040; Read time: 29445; Write time: 765 23:06:02 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36357120; Write bytes: 298660352; Read time: 227; Write time: 1126 23:06:02 INFO - Running post-run listener: _upload_blobber_files 23:06:02 INFO - Blob upload gear active. 23:06:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:06:02 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 23:06:02 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:06:02 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:06:03 INFO - (blobuploader) - INFO - Open directory for files ... 23:06:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:06:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:06:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:04 INFO - (blobuploader) - INFO - Done attempting. 23:06:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:06:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:06:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:05 INFO - (blobuploader) - INFO - Done attempting. 23:06:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:06:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:06:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:08 INFO - (blobuploader) - INFO - Done attempting. 23:06:08 INFO - (blobuploader) - INFO - Iteration through files over. 23:06:08 INFO - Return code: 0 23:06:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:06:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:06:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be05fb5a0cb4be586cc763718eb08ba81fe0b21d26e89aaf283b3986a6c6128bc407ad526837a0045b6e0a7b2f2ba60b8a3c41dc4e510af3159b4dcf80b93f0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccf3b5442a6edc7ca2ebaade46f5bc43919aa5c2958720b7cf2599423d35a7db0f2e530d035fb20d8bef0b72df2f7a44022f42b5ffa9ffcdde954a85b64a61b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/81f561557e9abb398ff42ae3cdd0b8cbb28bcd95c6099e2d9b2105e8714ada4c67d4fe5febb453070528392b79ec83445549cd5ba44ebdd49a0cb06668e67f99"} 23:06:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:06:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:06:08 INFO - Contents: 23:06:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be05fb5a0cb4be586cc763718eb08ba81fe0b21d26e89aaf283b3986a6c6128bc407ad526837a0045b6e0a7b2f2ba60b8a3c41dc4e510af3159b4dcf80b93f0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccf3b5442a6edc7ca2ebaade46f5bc43919aa5c2958720b7cf2599423d35a7db0f2e530d035fb20d8bef0b72df2f7a44022f42b5ffa9ffcdde954a85b64a61b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/81f561557e9abb398ff42ae3cdd0b8cbb28bcd95c6099e2d9b2105e8714ada4c67d4fe5febb453070528392b79ec83445549cd5ba44ebdd49a0cb06668e67f99"} 23:06:08 INFO - Running post-run listener: copy_logs_to_upload_dir 23:06:08 INFO - Copying logs to upload dir... 23:06:08 INFO - mkdir: /builds/slave/test/build/upload/logs 23:06:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.010833 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-10-05 23:06:08.187495) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 23:06:08.191234) ========= 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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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/Fx-Team/sha512/be05fb5a0cb4be586cc763718eb08ba81fe0b21d26e89aaf283b3986a6c6128bc407ad526837a0045b6e0a7b2f2ba60b8a3c41dc4e510af3159b4dcf80b93f0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccf3b5442a6edc7ca2ebaade46f5bc43919aa5c2958720b7cf2599423d35a7db0f2e530d035fb20d8bef0b72df2f7a44022f42b5ffa9ffcdde954a85b64a61b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/81f561557e9abb398ff42ae3cdd0b8cbb28bcd95c6099e2d9b2105e8714ada4c67d4fe5febb453070528392b79ec83445549cd5ba44ebdd49a0cb06668e67f99"} build_url:https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/ciGanXUdTIqty43qWIbiAQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be05fb5a0cb4be586cc763718eb08ba81fe0b21d26e89aaf283b3986a6c6128bc407ad526837a0045b6e0a7b2f2ba60b8a3c41dc4e510af3159b4dcf80b93f0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccf3b5442a6edc7ca2ebaade46f5bc43919aa5c2958720b7cf2599423d35a7db0f2e530d035fb20d8bef0b72df2f7a44022f42b5ffa9ffcdde954a85b64a61b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/81f561557e9abb398ff42ae3cdd0b8cbb28bcd95c6099e2d9b2105e8714ada4c67d4fe5febb453070528392b79ec83445549cd5ba44ebdd49a0cb06668e67f99"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 23:06:08.223853) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 23:06:08.224252) ========= 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.PJSkDEmP3u/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.XFJEDyPxeF/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.005117 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 23:06:08.273006) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 23:06:08.273354) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 23:06:08.273716) ========= ========= Total master_lag: 0.17 =========