builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0242 starttime: 1484681537.54 results: success (0) buildid: 20170117082323 builduid: 2e25c7f9115c4702a00748e9cf4bbbc4 revision: 412036d99f78afc1e68c1d7088a3ce1fa275405e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.544441) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.544955) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.545239) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.573021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.573447) ========= 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.qzepLvQhch/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.YXVFVKgAJT/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 --2017-01-17 11:32:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2017-01-17 11:32:17 (18.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115378 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.712420) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.712745) ========= 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.qzepLvQhch/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.YXVFVKgAJT/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.030997 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:17.766850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-17 11:32:17.767212) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 412036d99f78afc1e68c1d7088a3ce1fa275405e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 412036d99f78afc1e68c1d7088a3ce1fa275405e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qzepLvQhch/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.YXVFVKgAJT/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 2017-01-17 11:32:17,866 truncating revision to first 12 chars 2017-01-17 11:32:17,866 Setting DEBUG logging. 2017-01-17 11:32:17,866 attempt 1/10 2017-01-17 11:32:17,866 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/412036d99f78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-17 11:32:18,917 unpacking tar archive at: mozilla-aurora-412036d99f78/testing/mozharness/ program finished with exit code 0 elapsedTime=1.321278 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-17 11:32:19.113952) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:19.114357) ========= script_repo_revision: 412036d99f78afc1e68c1d7088a3ce1fa275405e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:19.114854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:19.115161) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 11:32:19.135117) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-17 11:32:19.135500) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qzepLvQhch/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.YXVFVKgAJT/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:32:19 INFO - MultiFileLogger online at 20170117 11:32:19 in /builds/slave/test 11:32:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:32:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:32:19 INFO - {'append_to_log': False, 11:32:19 INFO - 'base_work_dir': '/builds/slave/test', 11:32:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:32:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:32:19 INFO - 'branch': 'Mozilla-Aurora', 11:32:19 INFO - 'buildbot_json_path': 'buildprops.json', 11:32:19 INFO - 'config_files': ('talos/mac_config.py',), 11:32:19 INFO - 'default_actions': ('clobber', 11:32:19 INFO - 'read-buildbot-config', 11:32:19 INFO - 'download-and-extract', 11:32:19 INFO - 'populate-webroot', 11:32:19 INFO - 'create-virtualenv', 11:32:19 INFO - 'install', 11:32:19 INFO - 'run-tests'), 11:32:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:32:19 INFO - 'download_minidump_stackwalk': True, 11:32:19 INFO - 'download_symbols': 'ondemand', 11:32:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:32:19 INFO - 'tooltool.py': '/tools/tooltool.py', 11:32:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:32:19 INFO - '/tools/misc-python/virtualenv.py')}, 11:32:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:32:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:32:19 INFO - 'installer_path': 'installer.exe', 11:32:19 INFO - 'log_level': 'info', 11:32:19 INFO - 'log_name': 'talos', 11:32:19 INFO - 'log_to_console': True, 11:32:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:32:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:32:19 INFO - 'opt_config_files': (), 11:32:19 INFO - 'pip_index': False, 11:32:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:32:19 INFO - 'cmd': ('bash', 11:32:19 INFO - '-c', 11:32:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:32:19 INFO - 'enabled': True, 11:32:19 INFO - 'halt_on_failure': False, 11:32:19 INFO - 'name': 'check_screen_resolution'},), 11:32:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:32:19 INFO - 'cmd': ('bash', 11:32:19 INFO - '-c', 11:32:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:32:19 INFO - 'enabled': True, 11:32:19 INFO - 'halt_on_failure': False, 11:32:19 INFO - 'name': 'check_screen_resolution'},), 11:32:19 INFO - 'run_cmd_checks_enabled': True, 11:32:19 INFO - 'sps_profile': False, 11:32:19 INFO - 'sps_profile_interval': 0, 11:32:19 INFO - 'suite': 'dromaeojs', 11:32:19 INFO - 'system_bits': '32', 11:32:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:32:19 INFO - 'title': 't-yosemite-r7-0242', 11:32:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:32:19 INFO - 'use_talos_json': True, 11:32:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:32:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:32:19 INFO - 'work_dir': 'build'} 11:32:19 INFO - [mozharness: 2017-01-17 19:32:19.341712Z] Running clobber step. 11:32:19 INFO - Running pre-action listener: _resource_record_pre_action 11:32:19 INFO - Running main action method: clobber 11:32:19 INFO - rmtree: /builds/slave/test/build 11:32:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:32:21 INFO - Running post-action listener: _resource_record_post_action 11:32:21 INFO - [mozharness: 2017-01-17 19:32:21.810217Z] Finished clobber step (success) 11:32:21 INFO - [mozharness: 2017-01-17 19:32:21.810338Z] Running read-buildbot-config step. 11:32:21 INFO - Running pre-action listener: _resource_record_pre_action 11:32:21 INFO - Running main action method: read_buildbot_config 11:32:21 INFO - Using buildbot properties: 11:32:21 INFO - { 11:32:21 INFO - "project": "", 11:32:21 INFO - "product": "firefox", 11:32:21 INFO - "script_repo_revision": "production", 11:32:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:32:21 INFO - "repository": "", 11:32:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:32:21 INFO - "buildid": "20170117082323", 11:32:21 INFO - "pgo_build": "False", 11:32:21 INFO - "basedir": "/builds/slave/test", 11:32:21 INFO - "buildnumber": 23, 11:32:21 INFO - "slavename": "t-yosemite-r7-0242", 11:32:21 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:32:21 INFO - "platform": "yosemite_r7", 11:32:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:32:21 INFO - "branch": "mozilla-aurora", 11:32:21 INFO - "repo_path": "releases/mozilla-aurora", 11:32:21 INFO - "revision": "412036d99f78afc1e68c1d7088a3ce1fa275405e", 11:32:21 INFO - "stage_platform": "macosx64", 11:32:21 INFO - "builduid": "2e25c7f9115c4702a00748e9cf4bbbc4", 11:32:21 INFO - "slavebuilddir": "test" 11:32:21 INFO - } 11:32:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:32:21 INFO - Running post-action listener: _resource_record_post_action 11:32:21 INFO - [mozharness: 2017-01-17 19:32:21.813617Z] Finished read-buildbot-config step (success) 11:32:21 INFO - [mozharness: 2017-01-17 19:32:21.813722Z] Running download-and-extract step. 11:32:21 INFO - Running pre-action listener: _resource_record_pre_action 11:32:21 INFO - Running main action method: download_and_extract 11:32:21 INFO - mkdir: /builds/slave/test/build/tests 11:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:21 INFO - trying https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:32:21 INFO - Downloading https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:32:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 11:32:22 INFO - Downloaded 1199 bytes. 11:32:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:32:22 INFO - Using the following test package requirements: 11:32:22 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:32:22 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:32:22 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:32:22 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'jsshell-mac.zip'], 11:32:22 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:32:22 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:32:22 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:32:22 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:32:22 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:32:22 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:32:22 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:32:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:32:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:32:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:32:22 INFO - Fetch https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:32:23 INFO - Http code: 200 11:32:23 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 11:32:23 INFO - x-amz-cf-id: 0SDOCBcvezFet61qS2ep1r2dqtbyOiAdPYocItMICfW-Qf42v8U3MQ== 11:32:23 INFO - x-amz-version-id: VYOR8RBIMFktvxymlAoS0RQLxTaPCbv6 11:32:23 INFO - Expected file size: 16275942 11:32:23 INFO - Obtained file size: 16275942 11:32:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 11:32:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:32:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:32:30 INFO - Fetch https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:32:30 INFO - Http code: 200 11:32:30 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 11:32:30 INFO - x-amz-cf-id: ciY2kfbCAX95wQsTDXouZ55QidtTedAIf3JcPHp4VjMRIhmoDZjnMw== 11:32:30 INFO - x-amz-version-id: VYOR8RBIMFktvxymlAoS0RQLxTaPCbv6 11:32:31 INFO - Expected file size: 16275942 11:32:31 INFO - Obtained file size: 16275942 11:32:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:32:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:32:37 INFO - Fetch https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:32:38 INFO - Http code: 200 11:32:38 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 11:32:38 INFO - x-amz-cf-id: vnIjn-3o1TQW60IAFuRQp2-sQw45IAqevmChNQihIhk_bwEFHkLVGA== 11:32:38 INFO - x-amz-version-id: rgbCORHLzPxY68Xy5c_qP0e4y7ibqEEk 11:32:38 INFO - Expected file size: 14409038 11:32:38 INFO - Obtained file size: 14409038 11:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:38 INFO - trying https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:32:38 INFO - Downloading https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:32:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 11:32:41 INFO - Downloaded 110006420 bytes. 11:32:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:32:41 INFO - mkdir: /builds/slave/test/properties 11:32:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:32:41 INFO - Writing to file /builds/slave/test/properties/build_url 11:32:41 INFO - Contents: 11:32:41 INFO - build_url:https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:32:42 INFO - Running post-action listener: _resource_record_post_action 11:32:42 INFO - Running post-action listener: set_extra_try_arguments 11:32:42 INFO - [mozharness: 2017-01-17 19:32:42.655126Z] Finished download-and-extract step (success) 11:32:42 INFO - [mozharness: 2017-01-17 19:32:42.655242Z] Running populate-webroot step. 11:32:42 INFO - Running pre-action listener: _resource_record_pre_action 11:32:42 INFO - Running main action method: populate_webroot 11:32:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:32:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:32:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:32:42 INFO - '--tppagecycles', 11:32:42 INFO - '7'], 11:32:42 INFO - 'tests': ['tsvgm']}}, 11:32:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:32:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:32:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:32:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:32:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:32:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:32:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['damp', 'tps']}, 11:32:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'tests': ['damp', 'tps']}, 11:32:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['dromaeo_dom']}, 11:32:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:32:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['basic_compositor_video']}, 11:32:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:32:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['a11yr', 11:32:42 INFO - 'ts_paint', 11:32:42 INFO - 'tpaint', 11:32:42 INFO - 'sessionrestore', 11:32:42 INFO - 'sessionrestore_no_auto_restore', 11:32:42 INFO - 'tabpaint']}, 11:32:42 INFO - 'other-e10s': {'tests': ['a11yr', 11:32:42 INFO - 'ts_paint', 11:32:42 INFO - 'tpaint', 11:32:42 INFO - 'sessionrestore', 11:32:42 INFO - 'sessionrestore_no_auto_restore', 11:32:42 INFO - 'tabpaint']}, 11:32:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:32:42 INFO - 'ts_paint', 11:32:42 INFO - 'tpaint', 11:32:42 INFO - 'sessionrestore', 11:32:42 INFO - 'sessionrestore_no_auto_restore', 11:32:42 INFO - 'tabpaint']}, 11:32:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:32:42 INFO - 'ts_paint', 11:32:42 INFO - 'tpaint', 11:32:42 INFO - 'sessionrestore', 11:32:42 INFO - 'sessionrestore_no_auto_restore', 11:32:42 INFO - 'tabpaint']}, 11:32:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['a11yr', 11:32:42 INFO - 'ts_paint', 11:32:42 INFO - 'tpaint', 11:32:42 INFO - 'sessionrestore', 11:32:42 INFO - 'sessionrestore_no_auto_restore', 11:32:42 INFO - 'tabpaint']}, 11:32:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['a11yr', 11:32:42 INFO - 'ts_paint', 11:32:42 INFO - 'tpaint', 11:32:42 INFO - 'sessionrestore', 11:32:42 INFO - 'sessionrestore_no_auto_restore', 11:32:42 INFO - 'tabpaint']}, 11:32:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['tsvgx', 11:32:42 INFO - 'tsvgr_opacity', 11:32:42 INFO - 'tart', 11:32:42 INFO - 'tscrollx', 11:32:42 INFO - 'cart']}, 11:32:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:32:42 INFO - 'tsvgr_opacity', 11:32:42 INFO - 'tart', 11:32:42 INFO - 'tscrollx', 11:32:42 INFO - 'cart']}, 11:32:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'talos_options': ['--disable-e10s'], 11:32:42 INFO - 'tests': ['tp5o']}, 11:32:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'tests': ['tp5o']}, 11:32:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'talos_options': ['--disable-e10s', 11:32:42 INFO - '--xperf_path', 11:32:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:32:42 INFO - 'tests': ['tp5n']}, 11:32:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:42 INFO - 'talos_options': ['--xperf_path', 11:32:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:32:42 INFO - 'tests': ['tp5n']}}, 11:32:42 INFO - 'talos.zip': {'path': '', 11:32:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:32:42 INFO - Running post-action listener: _resource_record_post_action 11:32:42 INFO - [mozharness: 2017-01-17 19:32:42.674445Z] Finished populate-webroot step (success) 11:32:42 INFO - [mozharness: 2017-01-17 19:32:42.674555Z] Running create-virtualenv step. 11:32:42 INFO - Running pre-action listener: _resource_record_pre_action 11:32:42 INFO - Running main action method: create_virtualenv 11:32:42 INFO - Creating virtualenv /builds/slave/test/build/venv 11:32:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:32:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:32:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:32:42 INFO - Using real prefix '/tools/python27' 11:32:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:32:43 INFO - Installing distribute.............................................................................................................................................................................................done. 11:32:46 INFO - Installing pip.................done. 11:32:46 INFO - Return code: 0 11:32:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:32:46 INFO - Reading from file tmpfile_stdout 11:32:46 INFO - Output received: 11:32:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:32:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:32: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')]} 11:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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')]} 11:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:32: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', 'pip>=1.5'] in /builds/slave/test/build 11:32: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 pip>=1.5 11:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:47 INFO - 'HOME': '/Users/cltbld', 11:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:47 INFO - 'LOGNAME': 'cltbld', 11:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:47 INFO - 'MOZ_NO_REMOTE': '1', 11:32:47 INFO - 'NO_EM_RESTART': '1', 11:32:47 INFO - 'PAGER': '/bin/cat', 11:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:47 INFO - 'PWD': '/builds/slave/test', 11:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:47 INFO - 'SHELL': '/bin/bash', 11:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:32:47 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:47 INFO - 'USER': 'cltbld', 11:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:47 INFO - 'XPC_FLAGS': '0x0', 11:32:47 INFO - 'XPC_SERVICE_NAME': '0', 11:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:47 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 11:32:47 INFO - Cleaning up... 11:32:47 INFO - Return code: 0 11:32:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:32: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')]} 11:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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')]} 11:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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', '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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:32: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:32: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 psutil>=3.1.1 11:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:47 INFO - 'HOME': '/Users/cltbld', 11:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:47 INFO - 'LOGNAME': 'cltbld', 11:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:47 INFO - 'MOZ_NO_REMOTE': '1', 11:32:47 INFO - 'NO_EM_RESTART': '1', 11:32:47 INFO - 'PAGER': '/bin/cat', 11:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:47 INFO - 'PWD': '/builds/slave/test', 11:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:47 INFO - 'SHELL': '/bin/bash', 11:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:32:47 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:47 INFO - 'USER': 'cltbld', 11:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:47 INFO - 'XPC_FLAGS': '0x0', 11:32:47 INFO - 'XPC_SERVICE_NAME': '0', 11:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:47 INFO - Downloading/unpacking psutil>=3.1.1 11:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:32:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:51 INFO - Installing collected packages: psutil 11:32:51 INFO - Running setup.py install for psutil 11:32:51 INFO - building 'psutil._psutil_osx' extension 11:32:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:32:52 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 11:32:52 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 11:32:52 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 11:32:52 INFO - building 'psutil._psutil_posix' extension 11:32:52 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 11:32:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:32:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:32:52 INFO - ^ 11:32:52 INFO - 1 warning generated. 11:32:52 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 11:32:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:52 INFO - Successfully installed psutil 11:32:52 INFO - Cleaning up... 11:32:52 INFO - Return code: 0 11:32:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:32:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:52 INFO - 'HOME': '/Users/cltbld', 11:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:52 INFO - 'LOGNAME': 'cltbld', 11:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:52 INFO - 'MOZ_NO_REMOTE': '1', 11:32:52 INFO - 'NO_EM_RESTART': '1', 11:32:52 INFO - 'PAGER': '/bin/cat', 11:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:52 INFO - 'PWD': '/builds/slave/test', 11:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:52 INFO - 'SHELL': '/bin/bash', 11:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:32:52 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:52 INFO - 'USER': 'cltbld', 11:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:52 INFO - 'XPC_FLAGS': '0x0', 11:32:52 INFO - 'XPC_SERVICE_NAME': '0', 11:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:32:55 INFO - Installing collected packages: mozsystemmonitor 11:32:55 INFO - Running setup.py install for mozsystemmonitor 11:32:56 INFO - Successfully installed mozsystemmonitor 11:32:56 INFO - Cleaning up... 11:32:56 INFO - Return code: 0 11:32:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:56 INFO - 'HOME': '/Users/cltbld', 11:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:56 INFO - 'LOGNAME': 'cltbld', 11:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:56 INFO - 'MOZ_NO_REMOTE': '1', 11:32:56 INFO - 'NO_EM_RESTART': '1', 11:32:56 INFO - 'PAGER': '/bin/cat', 11:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:56 INFO - 'PWD': '/builds/slave/test', 11:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:56 INFO - 'SHELL': '/bin/bash', 11:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:32:56 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:56 INFO - 'USER': 'cltbld', 11:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:56 INFO - 'XPC_FLAGS': '0x0', 11:32:56 INFO - 'XPC_SERVICE_NAME': '0', 11:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:56 INFO - Downloading/unpacking jsonschema==2.5.1 11:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:32:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:59 INFO - Downloading functools32-3.2.3-2.tar.gz 11:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:32:59 INFO - warning: no files found matching '*.txt' 11:32:59 INFO - no previously-included directories found matching 'build' 11:32:59 INFO - no previously-included directories found matching 'dist' 11:32:59 INFO - no previously-included directories found matching '.git*' 11:32:59 INFO - Installing collected packages: jsonschema, functools32 11:32:59 INFO - Running setup.py install for functools32 11:32:59 INFO - warning: no files found matching '*.txt' 11:32:59 INFO - no previously-included directories found matching 'build' 11:32:59 INFO - no previously-included directories found matching 'dist' 11:32:59 INFO - no previously-included directories found matching '.git*' 11:32:59 INFO - Successfully installed jsonschema functools32 11:32:59 INFO - Cleaning up... 11:33:00 INFO - Return code: 0 11:33:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:33:00 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')]} 11:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:00 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')]} 11:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:00 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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:33:00 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 11:33:00 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 11:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:00 INFO - 'HOME': '/Users/cltbld', 11:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:00 INFO - 'LOGNAME': 'cltbld', 11:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:00 INFO - 'MOZ_NO_REMOTE': '1', 11:33:00 INFO - 'NO_EM_RESTART': '1', 11:33:00 INFO - 'PAGER': '/bin/cat', 11:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:00 INFO - 'PWD': '/builds/slave/test', 11:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:00 INFO - 'SHELL': '/bin/bash', 11:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:33:00 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:00 INFO - 'USER': 'cltbld', 11:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:00 INFO - 'XPC_FLAGS': '0x0', 11:33:00 INFO - 'XPC_SERVICE_NAME': '0', 11:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:33:00 INFO - Cleaning up... 11:33:00 INFO - Return code: 0 11:33:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:33:00 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')]} 11:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:00 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')]} 11:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:00 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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:33:00 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 11:33:00 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 11:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:00 INFO - 'HOME': '/Users/cltbld', 11:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:00 INFO - 'LOGNAME': 'cltbld', 11:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:00 INFO - 'MOZ_NO_REMOTE': '1', 11:33:00 INFO - 'NO_EM_RESTART': '1', 11:33:00 INFO - 'PAGER': '/bin/cat', 11:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:00 INFO - 'PWD': '/builds/slave/test', 11:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:00 INFO - 'SHELL': '/bin/bash', 11:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:33:00 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:00 INFO - 'USER': 'cltbld', 11:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:00 INFO - 'XPC_FLAGS': '0x0', 11:33:00 INFO - 'XPC_SERVICE_NAME': '0', 11:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:33:00 INFO - Downloading/unpacking blobuploader==1.2.4 11:33:00 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 11:33:00 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 11:33:00 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 11:33:00 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 11:33:00 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 11:33:00 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 11:33:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:33:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:33:03 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 11:33:03 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 11:33:03 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 11:33:03 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 11:33:03 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 11:33:03 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 11:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:33:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:33:03 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 11:33:03 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 11:33:03 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 11:33:03 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 11:33:03 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 11:33:03 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 11:33:04 INFO - Downloading docopt-0.6.1.tar.gz 11:33:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:33:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:33:04 INFO - Running setup.py install for blobuploader 11:33:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:33:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:33:04 INFO - Running setup.py install for requests 11:33:05 INFO - Running setup.py install for docopt 11:33:05 INFO - Successfully installed blobuploader requests docopt 11:33:05 INFO - Cleaning up... 11:33:05 INFO - Return code: 0 11:33:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:05 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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:33:05 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 11:33:05 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 11:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:05 INFO - 'HOME': '/Users/cltbld', 11:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:05 INFO - 'LOGNAME': 'cltbld', 11:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:05 INFO - 'MOZ_NO_REMOTE': '1', 11:33:05 INFO - 'NO_EM_RESTART': '1', 11:33:05 INFO - 'PAGER': '/bin/cat', 11:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:05 INFO - 'PWD': '/builds/slave/test', 11:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:05 INFO - 'SHELL': '/bin/bash', 11:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:33:05 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:05 INFO - 'USER': 'cltbld', 11:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:05 INFO - 'XPC_FLAGS': '0x0', 11:33:05 INFO - 'XPC_SERVICE_NAME': '0', 11:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:33:05 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-EUjst7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:33:05 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-t24MOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:33:05 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-6pYu3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Wh1xCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-jqV3wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-bOc1RJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-MN26E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-atZkcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-yjlQXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-YRg7ZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-jmbuCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-rgMJ8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:33:06 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-eygaXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:33:07 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-6XeTI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:33:07 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-tNw08i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:33:07 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-1LM2D2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:33:07 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-EEuD6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:33:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:33:07 INFO - Running setup.py install for manifestparser 11:33:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:33:07 INFO - Running setup.py install for mozcrash 11:33:07 INFO - Running setup.py install for mozdebug 11:33:07 INFO - Running setup.py install for mozdevice 11:33:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Running setup.py install for mozfile 11:33:08 INFO - Running setup.py install for mozhttpd 11:33:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Running setup.py install for mozinfo 11:33:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Running setup.py install for mozInstall 11:33:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Running setup.py install for mozleak 11:33:08 INFO - Running setup.py install for mozlog 11:33:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:33:08 INFO - Running setup.py install for moznetwork 11:33:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:33:09 INFO - Running setup.py install for mozprocess 11:33:09 INFO - Running setup.py install for mozprofile 11:33:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:33:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:33:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:33:09 INFO - Running setup.py install for mozrunner 11:33:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:33:09 INFO - Running setup.py install for mozscreenshot 11:33:09 INFO - Running setup.py install for moztest 11:33:09 INFO - Running setup.py install for mozversion 11:33:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:33:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:33:10 INFO - Cleaning up... 11:33:10 INFO - Return code: 0 11:33:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:33:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:33:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:10 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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:33:10 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 11:33:10 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 11:33:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:33:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:10 INFO - 'HOME': '/Users/cltbld', 11:33:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:10 INFO - 'LOGNAME': 'cltbld', 11:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:10 INFO - 'MOZ_NO_REMOTE': '1', 11:33:10 INFO - 'NO_EM_RESTART': '1', 11:33:10 INFO - 'PAGER': '/bin/cat', 11:33:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:10 INFO - 'PWD': '/builds/slave/test', 11:33:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:10 INFO - 'SHELL': '/bin/bash', 11:33:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:33:10 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:33:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:10 INFO - 'USER': 'cltbld', 11:33:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:10 INFO - 'XPC_FLAGS': '0x0', 11:33:10 INFO - 'XPC_SERVICE_NAME': '0', 11:33:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:33:10 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-J0rPr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:33:10 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)) 11:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:33:10 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-7BcKlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:33:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:33:10 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-MYF9tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:33:10 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)) 11:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:33:10 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-0OHT6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:33:10 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)) 11:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:33:10 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-hSP0id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:33:10 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)) 11:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:33:10 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-vJGlrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:33:10 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)) 11:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:33:10 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-9Zq3dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-pgIemb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-LVICyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-6YwI3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-_eJl3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-BNmsun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-GjQTkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-tYiUgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-F_nKnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:33:11 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:33:11 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-DzDInq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 11:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:33:12 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-tdHVLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:33:12 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)) 11:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:33:12 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)) 11:33:12 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)) 11:33:12 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)) 11:33:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:33:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:33:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:33:15 INFO - Downloading blessings-1.6.tar.gz 11:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:33:15 INFO - Installing collected packages: blessings 11:33:15 INFO - Running setup.py install for blessings 11:33:15 INFO - Successfully installed blessings 11:33:15 INFO - Cleaning up... 11:33:15 INFO - Return code: 0 11:33:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:33:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:33:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:33:15 INFO - Reading from file tmpfile_stdout 11:33:15 INFO - Current package versions: 11:33:15 INFO - blessings == 1.6 11:33:15 INFO - blobuploader == 1.2.4 11:33:15 INFO - docopt == 0.6.1 11:33:15 INFO - functools32 == 3.2.3-2 11:33:15 INFO - jsonschema == 2.5.1 11:33:15 INFO - manifestparser == 1.1 11:33:15 INFO - mozInstall == 1.12 11:33:15 INFO - mozcrash == 1.0 11:33:15 INFO - mozdebug == 0.1 11:33:15 INFO - mozdevice == 0.48 11:33:15 INFO - mozfile == 1.2 11:33:15 INFO - mozhttpd == 0.7 11:33:15 INFO - mozinfo == 0.9 11:33:15 INFO - mozleak == 0.1 11:33:15 INFO - mozlog == 3.4 11:33:15 INFO - moznetwork == 0.27 11:33:15 INFO - mozprocess == 0.23 11:33:15 INFO - mozprofile == 0.28 11:33:15 INFO - mozrunner == 6.13 11:33:15 INFO - mozscreenshot == 0.1 11:33:15 INFO - mozsystemmonitor == 0.3 11:33:15 INFO - moztest == 0.8 11:33:15 INFO - mozversion == 1.4 11:33:15 INFO - psutil == 3.1.1 11:33:15 INFO - requests == 1.2.3 11:33:15 INFO - wsgiref == 0.1.2 11:33:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:33:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:33:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:16 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')]} 11:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:33:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:16 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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:33:16 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 11:33:16 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 11:33:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:33:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:16 INFO - 'HOME': '/Users/cltbld', 11:33:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:16 INFO - 'LOGNAME': 'cltbld', 11:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:16 INFO - 'MOZ_NO_REMOTE': '1', 11:33:16 INFO - 'NO_EM_RESTART': '1', 11:33:16 INFO - 'PAGER': '/bin/cat', 11:33:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:16 INFO - 'PWD': '/builds/slave/test', 11:33:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:16 INFO - 'SHELL': '/bin/bash', 11:33:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:33:16 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:33:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:16 INFO - 'USER': 'cltbld', 11:33:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:16 INFO - 'XPC_FLAGS': '0x0', 11:33:16 INFO - 'XPC_SERVICE_NAME': '0', 11:33:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 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)) 11:33:16 INFO - Cleaning up... 11:33:16 INFO - Return code: 0 11:33:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:33:16 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')]} 11:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:33:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:16 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')]} 11:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:33:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:33:16 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 0x10ce61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a9ca4470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, '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.qzepLvQhch/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.YXVFVKgAJT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 11:33:16 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 11:33:16 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 11:33:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:33:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:16 INFO - 'HOME': '/Users/cltbld', 11:33:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:16 INFO - 'LOGNAME': 'cltbld', 11:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:16 INFO - 'MOZ_NO_REMOTE': '1', 11:33:16 INFO - 'NO_EM_RESTART': '1', 11:33:16 INFO - 'PAGER': '/bin/cat', 11:33:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:16 INFO - 'PWD': '/builds/slave/test', 11:33:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:16 INFO - 'SHELL': '/bin/bash', 11:33:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:33:16 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:33:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:16 INFO - 'USER': 'cltbld', 11:33:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:16 INFO - 'XPC_FLAGS': '0x0', 11:33:16 INFO - 'XPC_SERVICE_NAME': '0', 11:33:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:33:16 INFO - Cleaning up... 11:33:16 INFO - Return code: 0 11:33:16 INFO - Running post-action listener: _resource_record_post_action 11:33:16 INFO - Running post-action listener: _start_resource_monitoring 11:33:16 INFO - Starting resource monitoring. 11:33:16 INFO - [mozharness: 2017-01-17 19:33:16.687275Z] Finished create-virtualenv step (success) 11:33:16 INFO - [mozharness: 2017-01-17 19:33:16.688245Z] Running install step. 11:33:16 INFO - Running pre-action listener: _resource_record_pre_action 11:33:16 INFO - Running main action method: install 11:33:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:33:16 INFO - Reading from file tmpfile_stdout 11:33:16 INFO - Detecting whether we're running mozinstall >=1.0... 11:33:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:33:17 INFO - Reading from file tmpfile_stdout 11:33:17 INFO - Output received: 11:33:17 INFO - Usage: mozinstall [options] installer 11:33:17 INFO - Options: 11:33:17 INFO - -h, --help show this help message and exit 11:33:17 INFO - -d DEST, --destination=DEST 11:33:17 INFO - Directory to install application into. [default: 11:33:17 INFO - "/builds/slave/test"] 11:33:17 INFO - --app=APP Application being installed. [default: firefox] 11:33:17 INFO - mkdir: /builds/slave/test/build/application 11:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 11:33:49 INFO - Reading from file tmpfile_stdout 11:33:49 INFO - Output received: 11:33:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:33:49 INFO - Running post-action listener: _resource_record_post_action 11:33:49 INFO - [mozharness: 2017-01-17 19:33:49.104710Z] Finished install step (success) 11:33:49 INFO - [mozharness: 2017-01-17 19:33:49.104867Z] Running run-tests step. 11:33:49 INFO - Running pre-action listener: _resource_record_pre_action 11:33:49 INFO - Running main action method: run_tests 11:33:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:33:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:33:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:33:49 INFO - 2017-01-17 11:33:49.119 screenresolution[1628:7551] starting screenresolution argv=screenresolution get 11:33:49 INFO - 2017-01-17 11:33:49.121 screenresolution[1628:7551] Display 0: 1600x1200x32@60 11:33:49 INFO - 2017-01-17 11:33:49.127 screenresolution[1629:7553] starting screenresolution argv=screenresolution list 11:33:49 INFO - Available Modes on Display 0 11:33:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:33:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:33:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:33:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:33:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:33:49 INFO - Intel Iris: 11:33:49 INFO - Chipset Model: Intel Iris 11:33:49 INFO - Type: GPU 11:33:49 INFO - Bus: Built-In 11:33:49 INFO - VRAM (Dynamic, Max): 1536 MB 11:33:49 INFO - Vendor: Intel (0x8086) 11:33:49 INFO - Device ID: 0x0a2e 11:33:49 INFO - Revision ID: 0x0009 11:33:49 INFO - Displays: 11:33:49 INFO - Display: 11:33:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:33:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:33:49 INFO - Display Serial Number: 1600x1200 60 11:33:49 INFO - Main Display: Yes 11:33:49 INFO - Mirror: Off 11:33:49 INFO - Online: Yes 11:33:49 INFO - Rotation: Supported 11:33:49 INFO - Return code: 0 11:33:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:33:49 INFO - Python 2.7.3 11:33:49 INFO - Return code: 0 11:33:49 INFO - grabbing minidump binary from tooltool 11:33: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')]} 11:33:49 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75d30>, '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 0x10cadbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc6db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:33:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:33:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:33:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:33:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:33:49 INFO - Return code: 0 11:33:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:33:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:33:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:33:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:33:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:33:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0242', '--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 11:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0242 --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 11:33:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qzepLvQhch/Render', 11:33:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:49 INFO - 'HOME': '/Users/cltbld', 11:33:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:49 INFO - 'LOGNAME': 'cltbld', 11:33:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:33:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:49 INFO - 'MOZ_NO_REMOTE': '1', 11:33:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:33:49 INFO - 'NO_EM_RESTART': '1', 11:33:49 INFO - 'PAGER': '/bin/cat', 11:33:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:49 INFO - 'PWD': '/builds/slave/test', 11:33:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:33:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:49 INFO - 'SHELL': '/bin/bash', 11:33:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YXVFVKgAJT/Listeners', 11:33:49 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 11:33:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:49 INFO - 'USER': 'cltbld', 11:33:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:49 INFO - 'XPC_FLAGS': '0x0', 11:33:49 INFO - 'XPC_SERVICE_NAME': '0', 11:33:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0242', '--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 11:33:49 INFO - mozversion application_buildid: 20170117082323 11:33:49 INFO - mozversion application_changeset: 412036d99f78afc1e68c1d7088a3ce1fa275405e 11:33:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:33:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:33:49 INFO - mozversion application_name: Firefox 11:33:49 INFO - mozversion application_remotingname: firefox-dev 11:33:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:33:49 INFO - mozversion application_vendor: Mozilla 11:33:49 INFO - mozversion application_version: 52.0a2 11:33:49 INFO - mozversion platform_buildid: 20170117082323 11:33:49 INFO - mozversion platform_changeset: 412036d99f78afc1e68c1d7088a3ce1fa275405e 11:33:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:33:49 INFO - mozversion platform_version: 52.0a2 11:33:49 INFO - using testdate: 1484681629 11:33:49 INFO - actual date: 1484681629 11:33:49 INFO - starting webserver on 'localhost:49242' 11:33:49 INFO - SUITE-START | Running 2 tests 11:33:49 INFO - TEST-START | dromaeo_css 11:33:49 INFO - operating with platform_type : mac_ 11:33:49 INFO - Initialising browser for dromaeo_css test... 11:33:49 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpXJBiu_/profile http://localhost:49242/getInfo.html) 11:33:52 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 11:33:52 INFO - PROCESS | 1639 | colorDepth:24 11:33:52 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 11:33:52 INFO - PROCESS | 1639 | __metrics 11:33:52 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:33:52 INFO - PROCESS | 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:33:53 INFO - TEST-INFO | 1639: exit 0 11:33:53 INFO - Browser initialized. 11:33:53 INFO - Running cycle 1/1 for dromaeo_css test... 11:33:53 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpXJBiu_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:34:05 INFO - PROCESS | 1641 | RSS: Main: 222138368 11:34:05 INFO - PROCESS | 1641 | 11:37:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 11:37:11 INFO - PROCESS | 1641 | RSS: Main: 303128576 11:37:11 INFO - PROCESS | 1641 | 11:40:14 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 11:40:15 INFO - PROCESS | 1641 | RSS: Main: 345993216 11:40:15 INFO - PROCESS | 1641 | 11:43:17 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 11:43:18 INFO - PROCESS | 1641 | RSS: Main: 319070208 11:43:18 INFO - PROCESS | 1641 | 11:46:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 11:46:21 INFO - PROCESS | 1641 | RSS: Main: 319979520 11:46:21 INFO - PROCESS | 1641 | 11:49:24 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 11:49:25 INFO - PROCESS | 1641 | RSS: Main: 308494336 11:49:25 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 11:52:28 INFO - PROCESS | 1641 | RSS: Main: 409595904 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | __start_tp_report 11:52:28 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 11:52:28 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 11:52:28 INFO - PROCESS | 1641 | |i|pagename|runs| 11:52:28 INFO - PROCESS | 1641 | |0;dojo.html;2789.609454676345;2793.2458236276198;3346.3641908037484;3350.6314305426413;3357.043242675838;5625.437456254373;5630.380658127613;5651.40660230676;5685.516731077858;5706.429357064297;5534.169874518824;5635.943640563593;5646.01194790912;5663.546916246701;5682.6306290091115;5106.897862042757;5108.846734597968;5136.126858434075;5197.922031169535;5237.952409518111;5051.6716413433005;5115.258287548303;5130.153524668425;5206.401263854648;5255.290535777659;5178.792848286064;5210.192420174879;5216.895662086756;5221.347331583552;5231.319928409302;5162.1224391853475;5185.533302002837;5193.532582067594;5196.92204616931;5210.921836172461;5126.873126873127;5158;5169.689818610896;5180.766865491024;5212.921806172911;5689.9146512802345;5694.345150307726;5696.800611978602;5699.344575373843;5707.172459993278;6104.041878991925;6122.846928826815;6124.142620033199;6128.724207410677;6133.815985520441;6118.010103775246;6151.96924015377;6158.168647232611;6166.414190651881;6170.413810687977;5628.465295796934;5688.374278829326;5702.486776190163;5721.113227449752;5723.971380143114;6113.537117903944;6180.505559555315;6209.285932117774;6209.751609935597;6217.813465596039;4890.926636100461;5878;5956.761729530857;5958.672273025025;5973.104034394789;6173.41352571505;6175.608931156025;6180.6291622502795;6188.938110618927;6222.657753823492;5208.921866172011;6105.084237364342;6153.692315384213;6177.907331390033;6184.721687524072;5972.134040564094;6021.217241758543;6045.274567051982;6077.4226448487325;6123.081537769282;4884.462709101996;4889.584385327216;4893.559579637849;4899.681520701143;4932.087563800708;4386.122775444924;4390.714603550759;4408.977955110204;4410.536893626123;4456.17560751262;5545.833624991256;5572.749226284827;5575.080111781592;5608.691521966331;5675.858103547444;6134.069235337226;6205.193324867739;6221.129041934133;6224.719887605069;6228.034654628449;5624.606277560613;5693.231413759172;5710.543156547466;5713.857153571194;5724.799632012943;5219.530242278213;5339.81310654133;5342.813001544927;5355.357357117093;5362.383325917492;6148.938510614837;6234.594751341239;6270.9339412299105;6290.119383286252;6299.149614802126;6227.626342419469;6238.781642642486;6241.656708881055;6244.625322480666;6255.812325630239;6250.656213908097;6258.624482531062;6287.717052732546;6314.273858506147;6322.968385158045;5891.882162356643;5915.260592425947;5937.139114828412;6028.638281703112;6120.020796672511;4361.890952726208;5331.840044798662;6084.817455476343;6246.46905013079;6260.342664020394;5062.797488100435;5982.940170598238;6029.60807547508;6056.545759068;6062.848428789315;5869;5899.085641725539;5970;6011.338752737282;6064.211652485149;5796.710164491843;5840.678762668094;5855.2973643163095;5860.970695146497;5907.438793314628;5820.476157145878;6002.639841609517;6008.579399441997;6008.909866352095;6022.277326720821;5694.914576281442;5758.683272419889;5761.251037365116;5774.624649397776;5775.8556036099435 11:52:28 INFO - PROCESS | 1641 | |1;ext.html;504.52070532993656;507.36325910981725;527.1512864288493;527.765144510693;574.2075935209413;24200.87899560502;37717.62282377175;38876.80561597191;39061.41407878884;40550.797246013804;39494.81515554531;39900;39929.40105898414;39943;40004.199916001664;7862.37101031919;8353.624086916103;9344.486053267068;9356.578953947088;9363.95318023409;8345.040320363145;8485.830283394329;8512.063672995966;8514.787130321729;10938.890611093906;10958.780824383508;11069.225154239206;11164.330140191574;11228.101751859831;11242.66272011837;11165.888341116606;11219.439028048564;11244.831327530093;11246.381449020342;11248.437578121062;11095.55617775288;11161.274517156358;11165.944170279097;11184.776304473946;11191.552337906474;37320.626793731986;37624.43563346525;37631.81184094062;37643;37659.81170094132;3336.132605522582;3739.9626003739886;3745.5879853216125;3755.248950209969;3816.9427458588143;27499.900003999817;29210;29451.70548294511;29468.705312947026;29898.551521726975;2583.8966441342322;3187.6015498062743;3190.6490286068515;3206.486962086055;3235.6117265928237;487842;488401;488540;488724.99999999645;489064;54251.45748542543;54520;54531.72734136304;54599;54660.453395466335;57654;57729.13406298909;57773.8445231093;57776.71111644415;57816.421835781104;14953.850461495464;15136.470223542343;15178.241087945558;15226.695466090616;15233.781297496174;11830.763384732429;11834.88165118355;11835.934765871112;11847.111466639864;11860.991815695763;12778.105532612628;13116.475340986253;13136.540221092217;14167.929160354339;14715.19066451334;5732.885342293214;6279.874402511924;6487.54587178893;6542.574732642363;6555.410013098844;833.3957880536684;923.8013827027221;968.8304546704298;1047.3506426015767;1065.0999905080196;27807;28136.43727125446;28315.451609355256;28450.85774571114;29704.257393565334;920.5397301349325;957.9952100239454;999.120773719137;1032.1330082730542;1106.551846502168;14440.133591984304;14567.781483277759;14581.708365832625;14683.513982873192;14803.481878134213;664002;671483;671971.2802872007;672395;673117;44375.11249774986;44954.32568511475;49974.750126249135;51035.23447148222;51131.233031504555;1314.9145305555107;1335.1455068756004;1361.5234667866564;1361.9046666733234;1365.2218235605808;28604.85697571499;28846.567301490075;29361.706382935896;29776.702232977394;30050;46492.53507464882;46942.82642933817;47007.76496117497;47039.76480117578;47073;577531;579906;580308;582590;582855;24375.26874193777;24754.752452475965;24877.12930047512;25673.229803105936;25761.742382575936;42560.36159457672;42570.14859702851;42995;43222.351664724425;43395.13209735851;34992.65007349996;35349.469757953135;35446.46830297496;35496.645033549335;35783;35335.293294134484;35800.8209958959;35819.82090089637;36374.8181259092;36537.81731091328;34476.96569102931;34834.47748283829;34938.47592286067;34986.47520287248;35545.644543554234;30143.547846781876;31329.686703132676;31582.684173158894;31749;32016.35967280595;23900.04399824057;24210.394740131636;24218.878905605357;24362.02551897904;24776.50446991014 11:52:28 INFO - PROCESS | 1641 | |2;jquery.html;345.63880744621866;346.2676439330815;366.1761037665255;371.6692143991848;388.7065265724378;14743.189124598157;15154.166520841349;15904.284307206175;20354;20849.895750521227;19147.42557723267;19665.01674916252;20130.49673758157;20189.394318170478;20359.38921832344;5666.376698563155;5964.493018093445;5974.074018527135;5977.043673012319;5982.341942386356;5536.307961504812;5861.179434879099;5889.146073819294;5911.88176236475;5914.615549989258;4123.546409894909;4364.607185353318;5864.413558644144;5866.501347385477;6134;6236.937630623681;6412.39082287182;6463.741450342028;6505.707243174022;6511.9348806512035;5612.41069687681;5662.348829884575;5663.830085097454;5668.489835914746;5678.4605462480995;25082.498350033078;25441;25444.74555254461;25587.360315992064;25732.356691082685;2329.126577533425;2490.601503759408;2955.763538916903;3005.6994300569986;3126.1403114143563;14556;14858;15065.548033559011;15319.770203446957;16071.678566428724;1786.657892900232;1810.6107186954869;1843.5759775251768;1849.1493912800129;1851.4168037068414;86022.70965935515;107499;108067.91932080657;109867;120583.79416205814;11240.775184496264;11415.258008229439;11461.223510355909;11482.253653512495;11646.301221926711;14735.968482206357;14795.334209960578;14802.889783266299;14820.92589537067;14838.40646374133;6790.558613690095;8027.719029833927;8291.502509849428;8441.620127094295;8482.957585212158;8096.51420914747;8107.270345668918;8115.431919765678;8218.383621228431;8243.917560824315;4535.7732113394195;4539.886502837389;4554.863354099382;4559.4072770539615;4637.837675681335;1868.7570615820127;1904.1241029126757;1921.5484361175113;1934.2553117050038;1949.2008276606523;683.4327508168209;688.958249329216;763.1224092293907;864.7319331007408;898.3352319283814;19729;21195;22249.55500889973;22281.66577501339;23156.88421557915;868.3660927522817;870.6996086350199;899.3569597737649;913.4930113786779;937.0676177203726;10819;11125.666230013112;11160.16298777595;11172.553097876156;11253.831192532118;598345;606412;618683;618882.9055854692;623707.0517179262;135606;135774.32112839373;140815.29592351973;141017.5898241025;141224;990.5740531571494;997.3866072365588;1008.4705529596873;1010.7169992402286;1014.0265345268625;13667.726645466835;18665.53336166607;19831.504212394804;19896.50258743543;19906;55748.72125639346;55792;57409.13886291793;57430.42569574251;58206.4179358201;79357;80953.78569321583;82340;82859;83007;6778.559393639398;7861.2531809478005;7949.3243074339325;8258.380621453294;8309.00291964968;7782.844343113219;12039.318128869396;12611.495540178657;12673.683157921127;13320.800187996994;8184.7953801155445;10570.788584228427;11068.225224234226;11224.775504490028;11507.597234096766;10732.785344293226;10949.507272172612;11054.889451105386;11318.94340528292;11399.715007124556;11515.424228788695;11537.307761534335;11612.477438515118;11697.707557310794;11749.353785541878;8159.632816523152;8233.547154906299;8376.790580235544;8392.118827523022;8453.283703248937;9008.819823603622;11137.554497819996;11373.601923932623;11480.425978701198;11520.596779112691 11:52:28 INFO - PROCESS | 1641 | |3;mootools.html;1453.4767483705864;1473.2928194466656;1628.2510045379124;1785.571462848915;1811.32980797105;7881.369490440766;8161.428699991003;8173.632186551605;8180.181981801817;8186.38602104843;7306.853862922751;7510.28652278034;7517;7540.245975402457;7540.660670269837;2087.3529205946165;2248.910043598254;2320.9419764505856;2325.767423257669;2327.627579587266;2348.6829278047503;2349.2482405630203;2352.517733864562;2355.646653002055;2356.1282325539487;1847.612001479691;2275.055851821492;2324.7562554033593;2333.556624241397;2333.9416514587188;2251.425886398974;2257.6162052451123;2307.40007598024;2308.8499247548857;2312.4681323295567;2283.5204607032542;2311.3412677386864;2334.241371554235;2335.49786795838;2341.824363172752;8057.315128214109;8091.919080809234;8145.959270203671;8156.062052863937;8183.468074575134;4945.035718034985;5102.591792656579;5104.64267501275;5106.106431374495;5116.386033675983;3228.1284053305712;3404.063882432351;3455.740819438552;3488.232588830453;3529.1530032791943;996.3922007575374;1028;1059.655611926127;1059.6662051453768;1060.596973150198;8975.955120224422;9097.454152750855;10534.367937923673;10538.631147909786;10586.576536938435;4345.152695224464;4530.70550414222;4598.724076555417;4600.95399046012;4668.5564871337165;9110.772230694285;9114.86327705085;9116.08839116083;9126.81746365069;9137.543122843832;5028.547430731252;5128.512791284822;5132.563732082815;5135.871603209875;5142.177251639794;4579.862604121881;4691.108689349081;4717.985633088902;4728.810847566127;4734.597559207506;3526.612072672004;3590.605033446319;3591.5331006968927;3751.793651349202;3831.233753249361;1212.4362171590253;1213.7633161533415;1217.5312504685583;1227.4844565282604;1234.9012079033655;854.9658013679383;860.9139086091466;874.4228808986165;885.9778505537284;893.0533634347612;5727.541796656257;5778.711064446761;5926.407359264039;5988.820335389945;6031.185789918392;902.6524787956683;929.2101713543569;948.5257371314343;957.7414098193449;995.2784231432298;3805.1438426354403;3901.7463864848696;3983.0241590810438;3984.8007599619905;3989.601039896045;8984.281257499384;9753.609855605839;9864.704058878246;9879.703608891745;9891.159251463707;9443.811123777487;9837.704868853945;9861.75345616351;9869;9887.307888447884;7480.925190748132;7718.189590092959;7719.266669666373;7736;7758.107817600935;5602.187682785891;5605.215269862141;5663.348714897675;5786.161006654096;5846.766129354778;9170.679026234317;9192.770180745267;9209.986901440701;9212.032736562564;9238.214751745909;7650.693972241048;9069.63721451161;9163.816723665355;9172.633094676139;9405.905940940776;6912.066870972354;6940.305969403019;6958.930410695828;7020.824479388056;7027.61348125858;6953.26990665993;7106.324899134574;7120.857582848417;7132.643367831692;7530.5858177798555;6333.65164915934;6884.277150899084;6932.826679333057;6937.236903940663;6972.895406569007;6724.193096828216;6795;6800.9319906802275;6813.69338379784;6824.38580527735;6414.454771344302;6544.901826472702;6550.082988381535;6556.18047744032;6559.344065593403;4733.431988161443;4807;4817.0606731686985;4856.344393506973;4861.343718598086;4772.785224664829;4784.856454306377;4814.325994360723;4911.287863259879;4949 11:52:28 INFO - PROCESS | 1641 | |4;prototype.html;255.65741905846167;272.63603089875016;300.7533822265742;304.45350595680753;305.669876533344;4877.829275975333;5001.699558114889;5049.469805670402;5067.315912351836;5126.512981266783;4497.5158197794735;4933.679310844793;4938.48145944676;4958.950410495894;4965.6772309799835;3422.6235114137426;3443.0703709998347;3888.6111388861173;3899.3760998240286;3899.40124548935;3737.2338670572603;3866.28473732359;3869.496965394495;3878.8448462061624;3899.7270191086636;3246.0099669600763;3547.71618270539;3820.6370394812448;3867.7872717000555;3868.6324799144177;3167.9208019799457;3176.745860331168;3569.1434055826676;3856.382978723391;3873.6320049595524;3386.86452541898;3390.0677313738674;3393.9490907636405;3397.643247459006;3407.71034462071;4467.632154957878;4614.561616646413;4899.7550122494085;4902.901941961177;4982.427020892607;4342.934855977162;4390.53899340571;4406.735595864258;4413.161499315152;4420.734755914656;4423.447069116361;5246.685198888079;5251.68489890604;5288.81489147878;5334.946650533522;3705.907352316187;3708.684761795251;3711.5917249102577;3725.1432170600915;3729.738918275722;5080.390353157644;5420.539254163401;5507.917381239285;5533.418991005967;5539.9169012464845;3288.67113288674;3692.7784332939573;3705.7035437164964;3715.2569486102348;3722.553293604785;3505.859765609398;3724.366857634209;3760.9623903761158;3780.5408051633;3792.4540554581554;4070.653994410449;4338.674599405057;4369.410129632459;4388.758618276025;4412.411531848532;4529.26335772982;5220.869478262998;5432.809851655252;5439.728013599304;5466.808661696821;1104.7017305327518;1134.723436134349;1154.5554961339944;1156.4911438966828;1213.6298428979246;696.7839969609437;770.2682451670935;817.852786498424;845.3237410071918;848.6859861851154;3865.028835293964;4654.906901861944;4687.718736875799;4689.601383882408;4701.717896926195;4435.401220835211;4707.435107787088;5028.396592408934;5119.6160287978555;5132.408133892435;4137.296659567881;4295.441592593005;5015.523525265094;5086.601184674244;5191.662541934762;2369.407648087978;2435.391152211947;2435.6103023516152;2587.5676863748017;2707.6615423072117;4818.734969576707;4870.223972335805;5003.574696150795;5086.16078347076;5439.918401223985;4637.886907142329;4690.413698287714;4936.234883593049;5317.8936421271355;5319.946800532022;5198.32421785173;5334.839954801362;5357.276767636397;5360.919586206132;5371.274877891435;4394.538573449741;4879.365682461257;5075.822346217865;5117.744112794271;5274.7098909560345;4334.306510958231;5377.112776392008;5425.430329815313;5430.8099216527235;5449.003048323327;4984.700917944935;5473.616846820684;5636.85907852307;5647.548196144381;5670.234518339971;3327.6006879174656;3601.3517566838223;3709.182500574753;3714.7399682022;3714.981425092857;5392.595555333287;5425.593080519056;5435.918461223164;5467.480589344006;5475.808346708005;4412.735235885857;5265.710385928811;5281.498257665516;5312.256284120149;5339.839804805862;4399.5820397062225;4405.757683327448;5014.7994080236385;5278.445763194809;5280.128778751463;4962.379702537183;5129.333186685707;5144.151215049475;5268.418474947325;5307.7442087113595;4552.499225085304;5216.321878155815;5217.478252174752;5223.843284701465;5230.712310822789;4810.382033248121;4905.852824415273;4999.47505511916;5263.157894736823;5265.131253343308 11:52:28 INFO - PROCESS | 1641 | |5;yui.html;657.5594351784304;689.7275576147421;698.8526593857193;754.8377098923734;756.9886451703222;13836.239006854632;14067.226302553368;14438;14492.710145797078;14640.633984150401;12657.367131643427;12926.806097908515;13073.215607063583;13075.28085955272;13122.803157952614;654.3227759269157;659.8119535932249;670.286145255304;825.6160885188386;830.9709160179365;806.1776987472774;823.4853216739538;829.0093338460542;838.6226198210799;841.4656692999928;890.4657205676607;895.4403497813867;896.6951236579562;897.1342654338562;899.0739538275586;680.9421199198049;705.3898377903129;836.3100442135262;862.6549380247889;887.6050157679836;625.1685258606088;625.5183508698323;637.3721883944311;649.1949982022259;650.4243744286341;13958.790618140736;14067;14071.859281407158;14074.71850562983;14078.788818167735;12598;12778.977681785434;12972.416241269166;12976.35118244084;13095.148815326973;11520;11723.824142637868;11826.467808948619;12066;12104.697382565419;4327.480702315711;4392.758398288093;4469.08383781324;4541.659375546814;4548.931766023512;88648.11351886463;90473;91430;91508.6273705895;93185.06814931899;9843;10246.282760206714;10327.896721032694;10390.636327728644;10491.108255798343;19832.60334793296;19947.700784488243;20131;20134.395968120978;20182;7492.325690687755;7516.436267279976;7531.359834414026;7607.771766847003;7679.961600192075;4052.230076285496;4113.362428823537;4153.335466325373;4160.895977600524;4197.076643138505;3237.838108094586;3344.6989770920736;3392.728581713457;3395.5076513905346;3400.982995085058;636.7771280051945;637.0921436952325;639.0510092512615;645.5577929118568;646.4537465841356;527.8970600732897;545.3183520599251;600.4625859855493;629.646260539843;636.949034093749;8817.779555511164;9054.86417703735;9238.90761092394;9373.859392109123;9446;592.3158751641861;639.517164540778;642.1235014205575;673.5217995223446;676.5872817581271;4397.846075387346;4489.9551004490195;4516.977415112903;4531.501534831166;4568.977155114203;89270;105520;105542.47228763961;110361;112388;67990;76867;83045;98297;101685.49157254166;90508.54745726229;91623.5418822915;92283;93359.53320233355;93913;8719.869201961848;8751.868721969304;8789.824203516022;8819.162179593184;8821.470711757314;46627.30059049185;51166;53008.469915301896;54954.725226373615;56733;78543.60728196322;80828.36272367876;81922.59038704769;83676;84458.57770711106;9902.950485247527;11641.417929103342;11758.11814113962;11800.82298765502;11931.105167112328;11397.487113079766;11589.24669896454;12219.87780122223;12725.363731813557;12755.553555625509;11729.120315976506;11825.704357391134;11976.401179940794;12015.699607509881;12189.939050304693;11306.321620702784;11449.427528623703;11916.642500724645;12326.81509777336;12456.564020259248;11300.265482743594;11670.299782013106;12547.560835371083;12761.425735841724;12792.808107878576;10565.788684226427;10795.838062429228;11211.66365009051;11407.315561066363;11421.428928553705;11068.882900563112;11155.832662509907;11209.663710088711;11335.489902954221;11388 11:52:28 INFO - PROCESS | 1641 | __end_tp_report 11:52:28 INFO - PROCESS | 1641 | __start_cc_report 11:52:28 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1905 11:52:28 INFO - PROCESS | 1641 | __end_cc_report 11:52:28 INFO - PROCESS | 1641 | __startTimestamp1484682748551__endTimestamp 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | ------- Summary: start ------- 11:52:28 INFO - PROCESS | 1641 | Number of tests: 6 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5636.50 Median:5761.25 stddev:652.58 (11.3%) stddev-sans-first:615.18 11:52:28 INFO - PROCESS | 1641 | Values: 2789.6 2793.2 3346.4 3350.6 3357.0 5625.4 5630.4 5651.4 5685.5 5706.4 5534.2 5635.9 5646.0 5663.5 5682.6 5106.9 5108.8 5136.1 5197.9 5238.0 5051.7 5115.3 5130.2 5206.4 5255.3 5178.8 5210.2 5216.9 5221.3 5231.3 5162.1 5185.5 5193.5 5196.9 5210.9 5126.9 5158.0 5169.7 5180.8 5212.9 5689.9 5694.3 5696.8 5699.3 5707.2 6104.0 6122.8 6124.1 6128.7 6133.8 6118.0 6152.0 6158.2 6166.4 6170.4 5628.5 5688.4 5702.5 5721.1 5724.0 6113.5 6180.5 6209.3 6209.8 6217.8 4890.9 5878.0 5956.8 5958.7 5973.1 6173.4 6175.6 6180.6 6188.9 6222.7 5208.9 6105.1 6153.7 6177.9 6184.7 5972.1 6021.2 6045.3 6077.4 6123.1 4884.5 4889.6 4893.6 4899.7 4932.1 4386.1 4390.7 4409.0 4410.5 4456.2 5545.8 5572.7 5575.1 5608.7 5675.9 6134.1 6205.2 6221.1 6224.7 6228.0 5624.6 5693.2 5710.5 5713.9 5724.8 5219.5 5339.8 5342.8 5355.4 5362.4 6148.9 6234.6 6270.9 6290.1 6299.1 6227.6 6238.8 6241.7 6244.6 6255.8 6250.7 6258.6 6287.7 6314.3 6323.0 5891.9 5915.3 5937.1 6028.6 6120.0 4361.9 5331.8 6084.8 6246.5 6260.3 5062.8 5982.9 6029.6 6056.5 6062.8 5869.0 5899.1 5970.0 6011.3 6064.2 5796.7 5840.7 5855.3 5861.0 5907.4 5820.5 6002.6 6008.6 6008.9 6022.3 5694.9 5758.7 5761.3 5774.6 5775.9 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:69475.72 Median:35521.14 stddev:156660.92 (441.0%) stddev-sans-first:157014.81 11:52:28 INFO - PROCESS | 1641 | Values: 504.5 507.4 527.2 527.8 574.2 24200.9 37717.6 38876.8 39061.4 40550.8 39494.8 39900.0 39929.4 39943.0 40004.2 7862.4 8353.6 9344.5 9356.6 9364.0 8345.0 8485.8 8512.1 8514.8 10938.9 10958.8 11069.2 11164.3 11228.1 11242.7 11165.9 11219.4 11244.8 11246.4 11248.4 11095.6 11161.3 11165.9 11184.8 11191.6 37320.6 37624.4 37631.8 37643.0 37659.8 3336.1 3740.0 3745.6 3755.2 3816.9 27499.9 29210.0 29451.7 29468.7 29898.6 2583.9 3187.6 3190.6 3206.5 3235.6 487842.0 488401.0 488540.0 488725.0 489064.0 54251.5 54520.0 54531.7 54599.0 54660.5 57654.0 57729.1 57773.8 57776.7 57816.4 14953.9 15136.5 15178.2 15226.7 15233.8 11830.8 11834.9 11835.9 11847.1 11861.0 12778.1 13116.5 13136.5 14167.9 14715.2 5732.9 6279.9 6487.5 6542.6 6555.4 833.4 923.8 968.8 1047.4 1065.1 27807.0 28136.4 28315.5 28450.9 29704.3 920.5 958.0 999.1 1032.1 1106.6 14440.1 14567.8 14581.7 14683.5 14803.5 664002.0 671483.0 671971.3 672395.0 673117.0 44375.1 44954.3 49974.8 51035.2 51131.2 1314.9 1335.1 1361.5 1361.9 1365.2 28604.9 28846.6 29361.7 29776.7 30050.0 46492.5 46942.8 47007.8 47039.8 47073.0 577531.0 579906.0 580308.0 582590.0 582855.0 24375.3 24754.8 24877.1 25673.2 25761.7 42560.4 42570.1 42995.0 43222.4 43395.1 34992.7 35349.5 35446.5 35496.6 35783.0 35335.3 35800.8 35819.8 36374.8 36537.8 34477.0 34834.5 34938.5 34986.5 35545.6 30143.5 31329.7 31582.7 31749.0 32016.4 23900.0 24210.4 24218.9 24362.0 24776.5 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:35663.37 Median:14344.06 stddev:102341.63 (713.5%) stddev-sans-first:102594.36 11:52:28 INFO - PROCESS | 1641 | Values: 345.6 346.3 366.2 371.7 388.7 14743.2 15154.2 15904.3 20354.0 20849.9 19147.4 19665.0 20130.5 20189.4 20359.4 5666.4 5964.5 5974.1 5977.0 5982.3 5536.3 5861.2 5889.1 5911.9 5914.6 4123.5 4364.6 5864.4 5866.5 6134.0 6236.9 6412.4 6463.7 6505.7 6511.9 5612.4 5662.3 5663.8 5668.5 5678.5 25082.5 25441.0 25444.7 25587.4 25732.4 2329.1 2490.6 2955.8 3005.7 3126.1 14556.0 14858.0 15065.5 15319.8 16071.7 1786.7 1810.6 1843.6 1849.1 1851.4 86022.7 107499.0 108067.9 109867.0 120583.8 11240.8 11415.3 11461.2 11482.3 11646.3 14736.0 14795.3 14802.9 14820.9 14838.4 6790.6 8027.7 8291.5 8441.6 8483.0 8096.5 8107.3 8115.4 8218.4 8243.9 4535.8 4539.9 4554.9 4559.4 4637.8 1868.8 1904.1 1921.5 1934.3 1949.2 683.4 689.0 763.1 864.7 898.3 19729.0 21195.0 22249.6 22281.7 23156.9 868.4 870.7 899.4 913.5 937.1 10819.0 11125.7 11160.2 11172.6 11253.8 598345.0 606412.0 618683.0 618882.9 623707.1 135606.0 135774.3 140815.3 141017.6 141224.0 990.6 997.4 1008.5 1010.7 1014.0 13667.7 18665.5 19831.5 19896.5 19906.0 55748.7 55792.0 57409.1 57430.4 58206.4 79357.0 80953.8 82340.0 82859.0 83007.0 6778.6 7861.3 7949.3 8258.4 8309.0 7782.8 12039.3 12611.5 12673.7 13320.8 8184.8 10570.8 11068.2 11224.8 11507.6 10732.8 10949.5 11054.9 11318.9 11399.7 11515.4 11537.3 11612.5 11697.7 11749.4 8159.6 8233.5 8376.8 8392.1 8453.3 9008.8 11137.6 11373.6 11480.4 11520.6 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5309.74 Median:5753.13 stddev:2825.41 (49.1%) stddev-sans-first:2818.47 11:52:28 INFO - PROCESS | 1641 | Values: 1453.5 1473.3 1628.3 1785.6 1811.3 7881.4 8161.4 8173.6 8180.2 8186.4 7306.9 7510.3 7517.0 7540.2 7540.7 2087.4 2248.9 2320.9 2325.8 2327.6 2348.7 2349.2 2352.5 2355.6 2356.1 1847.6 2275.1 2324.8 2333.6 2333.9 2251.4 2257.6 2307.4 2308.8 2312.5 2283.5 2311.3 2334.2 2335.5 2341.8 8057.3 8091.9 8146.0 8156.1 8183.5 4945.0 5102.6 5104.6 5106.1 5116.4 3228.1 3404.1 3455.7 3488.2 3529.2 996.4 1028.0 1059.7 1059.7 1060.6 8976.0 9097.5 10534.4 10538.6 10586.6 4345.2 4530.7 4598.7 4601.0 4668.6 9110.8 9114.9 9116.1 9126.8 9137.5 5028.5 5128.5 5132.6 5135.9 5142.2 4579.9 4691.1 4718.0 4728.8 4734.6 3526.6 3590.6 3591.5 3751.8 3831.2 1212.4 1213.8 1217.5 1227.5 1234.9 855.0 860.9 874.4 886.0 893.1 5727.5 5778.7 5926.4 5988.8 6031.2 902.7 929.2 948.5 957.7 995.3 3805.1 3901.7 3983.0 3984.8 3989.6 8984.3 9753.6 9864.7 9879.7 9891.2 9443.8 9837.7 9861.8 9869.0 9887.3 7480.9 7718.2 7719.3 7736.0 7758.1 5602.2 5605.2 5663.3 5786.2 5846.8 9170.7 9192.8 9210.0 9212.0 9238.2 7650.7 9069.6 9163.8 9172.6 9405.9 6912.1 6940.3 6958.9 7020.8 7027.6 6953.3 7106.3 7120.9 7132.6 7530.6 6333.7 6884.3 6932.8 6937.2 6972.9 6724.2 6795.0 6800.9 6813.7 6824.4 6414.5 6544.9 6550.1 6556.2 6559.3 4733.4 4807.0 4817.1 4856.3 4861.3 4772.8 4784.9 4814.3 4911.3 4949.0 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4237.42 Median:4704.58 stddev:1300.99 (27.7%) stddev-sans-first:1269.84 11:52:28 INFO - PROCESS | 1641 | Values: 255.7 272.6 300.8 304.5 305.7 4877.8 5001.7 5049.5 5067.3 5126.5 4497.5 4933.7 4938.5 4959.0 4965.7 3422.6 3443.1 3888.6 3899.4 3899.4 3737.2 3866.3 3869.5 3878.8 3899.7 3246.0 3547.7 3820.6 3867.8 3868.6 3167.9 3176.7 3569.1 3856.4 3873.6 3386.9 3390.1 3393.9 3397.6 3407.7 4467.6 4614.6 4899.8 4902.9 4982.4 4342.9 4390.5 4406.7 4413.2 4420.7 4423.4 5246.7 5251.7 5288.8 5334.9 3705.9 3708.7 3711.6 3725.1 3729.7 5080.4 5420.5 5507.9 5533.4 5539.9 3288.7 3692.8 3705.7 3715.3 3722.6 3505.9 3724.4 3761.0 3780.5 3792.5 4070.7 4338.7 4369.4 4388.8 4412.4 4529.3 5220.9 5432.8 5439.7 5466.8 1104.7 1134.7 1154.6 1156.5 1213.6 696.8 770.3 817.9 845.3 848.7 3865.0 4654.9 4687.7 4689.6 4701.7 4435.4 4707.4 5028.4 5119.6 5132.4 4137.3 4295.4 5015.5 5086.6 5191.7 2369.4 2435.4 2435.6 2587.6 2707.7 4818.7 4870.2 5003.6 5086.2 5439.9 4637.9 4690.4 4936.2 5317.9 5319.9 5198.3 5334.8 5357.3 5360.9 5371.3 4394.5 4879.4 5075.8 5117.7 5274.7 4334.3 5377.1 5425.4 5430.8 5449.0 4984.7 5473.6 5636.9 5647.5 5670.2 3327.6 3601.4 3709.2 3714.7 3715.0 5392.6 5425.6 5435.9 5467.5 5475.8 4412.7 5265.7 5281.5 5312.3 5339.8 4399.6 4405.8 5014.8 5278.4 5280.1 4962.4 5129.3 5144.2 5268.4 5307.7 4552.5 5216.3 5217.5 5223.8 5230.7 4810.4 4905.9 4999.5 5263.2 5265.1 11:52:28 INFO - PROCESS | 1641 | 11:52:28 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20407.64 Median:4545.30 stddev:30101.51 (662.3%) stddev-sans-first:30149.42 11:52:28 INFO - PROCESS | 1641 | Values: 657.6 689.7 698.9 754.8 757.0 13836.2 14067.2 14438.0 14492.7 14640.6 12657.4 12926.8 13073.2 13075.3 13122.8 654.3 659.8 670.3 825.6 831.0 806.2 823.5 829.0 838.6 841.5 890.5 895.4 896.7 897.1 899.1 680.9 705.4 836.3 862.7 887.6 625.2 625.5 637.4 649.2 650.4 13958.8 14067.0 14071.9 14074.7 14078.8 12598.0 12779.0 12972.4 12976.4 13095.1 11520.0 11723.8 11826.5 12066.0 12104.7 4327.5 4392.8 4469.1 4541.7 4548.9 88648.1 90473.0 91430.0 91508.6 93185.1 9843.0 10246.3 10327.9 10390.6 10491.1 19832.6 19947.7 20131.0 20134.4 20182.0 7492.3 7516.4 7531.4 7607.8 7680.0 4052.2 4113.4 4153.3 4160.9 4197.1 3237.8 3344.7 3392.7 3395.5 3401.0 636.8 637.1 639.1 645.6 646.5 527.9 545.3 600.5 629.6 636.9 8817.8 9054.9 9238.9 9373.9 9446.0 592.3 639.5 642.1 673.5 676.6 4397.8 4490.0 4517.0 4531.5 4569.0 89270.0 105520.0 105542.5 110361.0 112388.0 67990.0 76867.0 83045.0 98297.0 101685.5 90508.5 91623.5 92283.0 93359.5 93913.0 8719.9 8751.9 8789.8 8819.2 8821.5 46627.3 51166.0 53008.5 54954.7 56733.0 78543.6 80828.4 81922.6 83676.0 84458.6 9903.0 11641.4 11758.1 11800.8 11931.1 11397.5 11589.2 12219.9 12725.4 12755.6 11729.1 11825.7 11976.4 12015.7 12189.9 11306.3 11449.4 11916.6 12326.8 12456.6 11300.3 11670.3 12547.6 12761.4 12792.8 10565.8 10795.8 11211.7 11407.3 11421.4 11068.9 11155.8 11209.7 11335.5 11388.0 11:52:28 INFO - PROCESS | 1641 | -------- Summary: end -------- 11:52:28 INFO - PROCESS | 1641 | 11:52:29 INFO - TEST-INFO | 1641: exit 0 11:52:29 INFO - TEST-OK | dromaeo_css | took 1119504ms 11:52:29 INFO - TEST-START | kraken 11:52:29 INFO - operating with platform_type : mac_ 11:52:29 INFO - Initialising browser for kraken test... 11:52:29 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpiWH_iu/profile http://localhost:49242/getInfo.html) 11:52:31 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 11:52:31 INFO - PROCESS | 1650 | colorDepth:24 11:52:31 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 11:52:31 INFO - PROCESS | 1650 | __metrics 11:52:32 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:52:32 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:52:32 INFO - TEST-INFO | 1650: exit 0 11:52:32 INFO - Browser initialized. 11:52:32 INFO - Running cycle 1/1 for kraken test... 11:52:32 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpiWH_iu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:52:45 INFO - PROCESS | 1651 | RSS: Main: 222052352 11:52:45 INFO - PROCESS | 1651 | 11:52:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 11:52:48 INFO - PROCESS | 1651 | RSS: Main: 272605184 11:52:48 INFO - PROCESS | 1651 | 11:52:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 11:52:52 INFO - PROCESS | 1651 | RSS: Main: 284631040 11:52:52 INFO - PROCESS | 1651 | 11:52:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 11:52:55 INFO - PROCESS | 1651 | RSS: Main: 382832640 11:52:55 INFO - PROCESS | 1651 | 11:52:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 11:52:58 INFO - PROCESS | 1651 | RSS: Main: 282775552 11:52:58 INFO - PROCESS | 1651 | 11:53:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:53:00 INFO - PROCESS | 1651 | RSS: Main: 285200384 11:53:00 INFO - PROCESS | 1651 | 11:53:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 11:53:04 INFO - PROCESS | 1651 | RSS: Main: 325206016 11:53:04 INFO - PROCESS | 1651 | 11:53:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 11:53:07 INFO - PROCESS | 1651 | RSS: Main: 327450624 11:53:07 INFO - PROCESS | 1651 | 11:53:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 11:53:11 INFO - PROCESS | 1651 | RSS: Main: 324620288 11:53:11 INFO - PROCESS | 1651 | 11:53:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:53:13 INFO - PROCESS | 1651 | RSS: Main: 299462656 11:53:13 INFO - PROCESS | 1651 | 11:53:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:53:15 INFO - PROCESS | 1651 | RSS: Main: 299966464 11:53:15 INFO - PROCESS | 1651 | 11:53:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:53:18 INFO - PROCESS | 1651 | RSS: Main: 303435776 11:53:18 INFO - PROCESS | 1651 | 11:53:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:53:22 INFO - PROCESS | 1651 | RSS: Main: 324321280 11:53:22 INFO - PROCESS | 1651 | 11:53:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:53:25 INFO - PROCESS | 1651 | RSS: Main: 317906944 11:53:25 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 11:53:27 INFO - PROCESS | 1651 | RSS: Main: 361791488 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | __start_tp_report 11:53:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:53:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:53:27 INFO - PROCESS | 1651 | |i|pagename|runs| 11:53:27 INFO - PROCESS | 1651 | |0;ai-astar;118;113;115;113;116;115;114;115;116;116 11:53:27 INFO - PROCESS | 1651 | |1;audio-beat-detection;144;110;108;146;111;110;114;108;139;109 11:53:27 INFO - PROCESS | 1651 | |2;audio-dft;181;185;182;183;182;183;180;182;182;185 11:53:27 INFO - PROCESS | 1651 | |3;audio-fft;80;58;58;89;61;59;60;60;99;80 11:53:27 INFO - PROCESS | 1651 | |4;audio-oscillator;88;71;95;69;67;69;90;69;69;91 11:53:27 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;117;130;101;85;87;85;89;85;87;84 11:53:27 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;96;97;96;95;96;97;95;96;96 11:53:27 INFO - PROCESS | 1651 | |7;imaging-desaturate;86;85;80;79;80;81;82;84;80;81 11:53:27 INFO - PROCESS | 1651 | |8;json-parse-financial;55;55;55;54;56;58;58;56;56;56 11:53:27 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;65;72;71;76;70;70;69;71;71;72 11:53:27 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;83;82;83;82;83;83;83;85;88;85 11:53:27 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;139;139;136;141;162;148;160;157;176;147 11:53:27 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;163;166;162;165;164;166;166;164;165;161 11:53:27 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;67;68;68;67;66;65;68;80;84 11:53:27 INFO - PROCESS | 1651 | __end_tp_report 11:53:27 INFO - PROCESS | 1651 | __start_cc_report 11:53:27 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1940 11:53:27 INFO - PROCESS | 1651 | __end_cc_report 11:53:27 INFO - PROCESS | 1651 | __startTimestamp1484682807836__endTimestamp 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:53:27 INFO - PROCESS | 1651 | Number of tests: 14 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:1.52 (1.3%) stddev-sans-first:1.20 11:53:27 INFO - PROCESS | 1651 | Values: 118.0 113.0 115.0 113.0 116.0 115.0 114.0 115.0 116.0 116.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:112.50 stddev:16.12 (14.3%) stddev-sans-first:14.55 11:53:27 INFO - PROCESS | 1651 | Values: 144.0 110.0 108.0 146.0 111.0 110.0 114.0 108.0 139.0 109.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:182.50 Median:182.50 stddev:1.58 (0.9%) stddev-sans-first:1.58 11:53:27 INFO - PROCESS | 1651 | Values: 181.0 185.0 182.0 183.0 182.0 183.0 180.0 182.0 182.0 185.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:70.40 Median:70.50 stddev:15.24 (21.6%) stddev-sans-first:15.76 11:53:27 INFO - PROCESS | 1651 | Values: 80.0 58.0 58.0 89.0 61.0 59.0 60.0 60.0 99.0 80.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.50 stddev:11.53 (14.5%) stddev-sans-first:11.62 11:53:27 INFO - PROCESS | 1651 | Values: 88.0 71.0 95.0 69.0 67.0 69.0 90.0 69.0 69.0 91.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:85.00 stddev:16.09 (18.9%) stddev-sans-first:14.97 11:53:27 INFO - PROCESS | 1651 | Values: 117.0 130.0 101.0 85.0 87.0 85.0 89.0 85.0 87.0 84.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 11:53:27 INFO - PROCESS | 1651 | Values: 96.0 96.0 97.0 96.0 95.0 96.0 97.0 95.0 96.0 96.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:81.50 stddev:2.39 (2.9%) stddev-sans-first:2.00 11:53:27 INFO - PROCESS | 1651 | Values: 86.0 85.0 80.0 79.0 80.0 81.0 82.0 84.0 80.0 81.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.29 (2.3%) stddev-sans-first:1.32 11:53:27 INFO - PROCESS | 1651 | Values: 55.0 55.0 55.0 54.0 56.0 58.0 58.0 56.0 56.0 56.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:2.75 (3.9%) stddev-sans-first:2.00 11:53:27 INFO - PROCESS | 1651 | Values: 65.0 72.0 71.0 76.0 70.0 70.0 69.0 71.0 71.0 72.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.00 stddev:1.83 (2.2%) stddev-sans-first:1.92 11:53:27 INFO - PROCESS | 1651 | Values: 83.0 82.0 83.0 82.0 83.0 83.0 83.0 85.0 88.0 85.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:152.50 stddev:12.90 (8.5%) stddev-sans-first:13.00 11:53:27 INFO - PROCESS | 1651 | Values: 139.0 139.0 136.0 141.0 162.0 148.0 160.0 157.0 176.0 147.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:165.00 stddev:1.75 (1.1%) stddev-sans-first:1.80 11:53:27 INFO - PROCESS | 1651 | Values: 163.0 166.0 162.0 165.0 164.0 166.0 166.0 164.0 165.0 161.0 11:53:27 INFO - PROCESS | 1651 | 11:53:27 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:68.00 stddev:6.42 (9.4%) stddev-sans-first:6.76 11:53:27 INFO - PROCESS | 1651 | Values: 68.0 67.0 68.0 68.0 67.0 66.0 65.0 68.0 80.0 84.0 11:53:27 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:53:27 INFO - PROCESS | 1651 | 11:53:28 INFO - TEST-INFO | 1651: exit 0 11:53:28 INFO - TEST-OK | kraken | took 59761ms 11:53:28 INFO - SUITE-END | took 1179s 11:53:29 INFO - Completed test suite (00:19:39) 11:53:29 INFO - javascript benchmark 11:53:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2789.609454676345, 2793.2458236276198, 3346.3641908037484, 3350.6314305426413, 3357.043242675838, 5625.437456254373, 5630.380658127613, 5651.40660230676, 5685.516731077858, 5706.429357064297, 5534.169874518824, 5635.943640563593, 5646.01194790912, 5663.546916246701, 5682.6306290091115, 5106.897862042757, 5108.846734597968, 5136.126858434075, 5197.922031169535, 5237.952409518111, 5051.6716413433005, 5115.258287548303, 5130.153524668425, 5206.401263854648, 5255.290535777659, 5178.792848286064, 5210.192420174879, 5216.895662086756, 5221.347331583552, 5231.319928409302, 5162.1224391853475, 5185.533302002837, 5193.532582067594, 5196.92204616931, 5210.921836172461, 5126.873126873127, 5158.0, 5169.689818610896, 5180.766865491024, 5212.921806172911, 5689.9146512802345, 5694.345150307726, 5696.800611978602, 5699.344575373843, 5707.172459993278, 6104.041878991925, 6122.846928826815, 6124.142620033199, 6128.724207410677, 6133.815985520441, 6118.010103775246, 6151.96924015377, 6158.168647232611, 6166.414190651881, 6170.413810687977, 5628.465295796934, 5688.374278829326, 5702.486776190163, 5721.113227449752, 5723.971380143114, 6113.537117903944, 6180.505559555315, 6209.285932117774, 6209.751609935597, 6217.813465596039, 4890.926636100461, 5878.0, 5956.761729530857, 5958.672273025025, 5973.104034394789, 6173.41352571505, 6175.608931156025, 6180.6291622502795, 6188.938110618927, 6222.657753823492, 5208.921866172011, 6105.084237364342, 6153.692315384213, 6177.907331390033, 6184.721687524072, 5972.134040564094, 6021.217241758543, 6045.274567051982, 6077.4226448487325, 6123.081537769282, 4884.462709101996, 4889.584385327216, 4893.559579637849, 4899.681520701143, 4932.087563800708, 4386.122775444924, 4390.714603550759, 4408.977955110204, 4410.536893626123, 4456.17560751262, 5545.833624991256, 5572.749226284827, 5575.080111781592, 5608.691521966331, 5675.858103547444, 6134.069235337226, 6205.193324867739, 6221.129041934133, 6224.719887605069, 6228.034654628449, 5624.606277560613, 5693.231413759172, 5710.543156547466, 5713.857153571194, 5724.799632012943, 5219.530242278213, 5339.81310654133, 5342.813001544927, 5355.357357117093, 5362.383325917492, 6148.938510614837, 6234.594751341239, 6270.9339412299105, 6290.119383286252, 6299.149614802126, 6227.626342419469, 6238.781642642486, 6241.656708881055, 6244.625322480666, 6255.812325630239, 6250.656213908097, 6258.624482531062, 6287.717052732546, 6314.273858506147, 6322.968385158045, 5891.882162356643, 5915.260592425947, 5937.139114828412, 6028.638281703112, 6120.020796672511, 4361.890952726208, 5331.840044798662, 6084.817455476343, 6246.46905013079, 6260.342664020394, 5062.797488100435, 5982.940170598238, 6029.60807547508, 6056.545759068, 6062.848428789315, 5869.0, 5899.085641725539, 5970.0, 6011.338752737282, 6064.211652485149, 5796.710164491843, 5840.678762668094, 5855.2973643163095, 5860.970695146497, 5907.438793314628, 5820.476157145878, 6002.639841609517, 6008.579399441997, 6008.909866352095, 6022.277326720821, 5694.914576281442, 5758.683272419889, 5761.251037365116, 5774.624649397776, 5775.8556036099435], "value": 5594.0151783486535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.52070532993656, 507.36325910981725, 527.1512864288493, 527.765144510693, 574.2075935209413, 24200.87899560502, 37717.62282377175, 38876.80561597191, 39061.41407878884, 40550.797246013804, 39494.81515554531, 39900.0, 39929.40105898414, 39943.0, 40004.199916001664, 7862.37101031919, 8353.624086916103, 9344.486053267068, 9356.578953947088, 9363.95318023409, 8345.040320363145, 8485.830283394329, 8512.063672995966, 8514.787130321729, 10938.890611093906, 10958.780824383508, 11069.225154239206, 11164.330140191574, 11228.101751859831, 11242.66272011837, 11165.888341116606, 11219.439028048564, 11244.831327530093, 11246.381449020342, 11248.437578121062, 11095.55617775288, 11161.274517156358, 11165.944170279097, 11184.776304473946, 11191.552337906474, 37320.626793731986, 37624.43563346525, 37631.81184094062, 37643.0, 37659.81170094132, 3336.132605522582, 3739.9626003739886, 3745.5879853216125, 3755.248950209969, 3816.9427458588143, 27499.900003999817, 29210.0, 29451.70548294511, 29468.705312947026, 29898.551521726975, 2583.8966441342322, 3187.6015498062743, 3190.6490286068515, 3206.486962086055, 3235.6117265928237, 487842.0, 488401.0, 488540.0, 488724.99999999645, 489064.0, 54251.45748542543, 54520.0, 54531.72734136304, 54599.0, 54660.453395466335, 57654.0, 57729.13406298909, 57773.8445231093, 57776.71111644415, 57816.421835781104, 14953.850461495464, 15136.470223542343, 15178.241087945558, 15226.695466090616, 15233.781297496174, 11830.763384732429, 11834.88165118355, 11835.934765871112, 11847.111466639864, 11860.991815695763, 12778.105532612628, 13116.475340986253, 13136.540221092217, 14167.929160354339, 14715.19066451334, 5732.885342293214, 6279.874402511924, 6487.54587178893, 6542.574732642363, 6555.410013098844, 833.3957880536684, 923.8013827027221, 968.8304546704298, 1047.3506426015767, 1065.0999905080196, 27807.0, 28136.43727125446, 28315.451609355256, 28450.85774571114, 29704.257393565334, 920.5397301349325, 957.9952100239454, 999.120773719137, 1032.1330082730542, 1106.551846502168, 14440.133591984304, 14567.781483277759, 14581.708365832625, 14683.513982873192, 14803.481878134213, 664002.0, 671483.0, 671971.2802872007, 672395.0, 673117.0, 44375.11249774986, 44954.32568511475, 49974.750126249135, 51035.23447148222, 51131.233031504555, 1314.9145305555107, 1335.1455068756004, 1361.5234667866564, 1361.9046666733234, 1365.2218235605808, 28604.85697571499, 28846.567301490075, 29361.706382935896, 29776.702232977394, 30050.0, 46492.53507464882, 46942.82642933817, 47007.76496117497, 47039.76480117578, 47073.0, 577531.0, 579906.0, 580308.0, 582590.0, 582855.0, 24375.26874193777, 24754.752452475965, 24877.12930047512, 25673.229803105936, 25761.742382575936, 42560.36159457672, 42570.14859702851, 42995.0, 43222.351664724425, 43395.13209735851, 34992.65007349996, 35349.469757953135, 35446.46830297496, 35496.645033549335, 35783.0, 35335.293294134484, 35800.8209958959, 35819.82090089637, 36374.8181259092, 36537.81731091328, 34476.96569102931, 34834.47748283829, 34938.47592286067, 34986.47520287248, 35545.644543554234, 30143.547846781876, 31329.686703132676, 31582.684173158894, 31749.0, 32016.35967280595, 23900.04399824057, 24210.394740131636, 24218.878905605357, 24362.02551897904, 24776.50446991014], "value": 19086.564450959344, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.63880744621866, 346.2676439330815, 366.1761037665255, 371.6692143991848, 388.7065265724378, 14743.189124598157, 15154.166520841349, 15904.284307206175, 20354.0, 20849.895750521227, 19147.42557723267, 19665.01674916252, 20130.49673758157, 20189.394318170478, 20359.38921832344, 5666.376698563155, 5964.493018093445, 5974.074018527135, 5977.043673012319, 5982.341942386356, 5536.307961504812, 5861.179434879099, 5889.146073819294, 5911.88176236475, 5914.615549989258, 4123.546409894909, 4364.607185353318, 5864.413558644144, 5866.501347385477, 6134.0, 6236.937630623681, 6412.39082287182, 6463.741450342028, 6505.707243174022, 6511.9348806512035, 5612.41069687681, 5662.348829884575, 5663.830085097454, 5668.489835914746, 5678.4605462480995, 25082.498350033078, 25441.0, 25444.74555254461, 25587.360315992064, 25732.356691082685, 2329.126577533425, 2490.601503759408, 2955.763538916903, 3005.6994300569986, 3126.1403114143563, 14556.0, 14858.0, 15065.548033559011, 15319.770203446957, 16071.678566428724, 1786.657892900232, 1810.6107186954869, 1843.5759775251768, 1849.1493912800129, 1851.4168037068414, 86022.70965935515, 107499.0, 108067.91932080657, 109867.0, 120583.79416205814, 11240.775184496264, 11415.258008229439, 11461.223510355909, 11482.253653512495, 11646.301221926711, 14735.968482206357, 14795.334209960578, 14802.889783266299, 14820.92589537067, 14838.40646374133, 6790.558613690095, 8027.719029833927, 8291.502509849428, 8441.620127094295, 8482.957585212158, 8096.51420914747, 8107.270345668918, 8115.431919765678, 8218.383621228431, 8243.917560824315, 4535.7732113394195, 4539.886502837389, 4554.863354099382, 4559.4072770539615, 4637.837675681335, 1868.7570615820127, 1904.1241029126757, 1921.5484361175113, 1934.2553117050038, 1949.2008276606523, 683.4327508168209, 688.958249329216, 763.1224092293907, 864.7319331007408, 898.3352319283814, 19729.0, 21195.0, 22249.55500889973, 22281.66577501339, 23156.88421557915, 868.3660927522817, 870.6996086350199, 899.3569597737649, 913.4930113786779, 937.0676177203726, 10819.0, 11125.666230013112, 11160.16298777595, 11172.553097876156, 11253.831192532118, 598345.0, 606412.0, 618683.0, 618882.9055854692, 623707.0517179262, 135606.0, 135774.32112839373, 140815.29592351973, 141017.5898241025, 141224.0, 990.5740531571494, 997.3866072365588, 1008.4705529596873, 1010.7169992402286, 1014.0265345268625, 13667.726645466835, 18665.53336166607, 19831.504212394804, 19896.50258743543, 19906.0, 55748.72125639346, 55792.0, 57409.13886291793, 57430.42569574251, 58206.4179358201, 79357.0, 80953.78569321583, 82340.0, 82859.0, 83007.0, 6778.559393639398, 7861.2531809478005, 7949.3243074339325, 8258.380621453294, 8309.00291964968, 7782.844343113219, 12039.318128869396, 12611.495540178657, 12673.683157921127, 13320.800187996994, 8184.7953801155445, 10570.788584228427, 11068.225224234226, 11224.775504490028, 11507.597234096766, 10732.785344293226, 10949.507272172612, 11054.889451105386, 11318.94340528292, 11399.715007124556, 11515.424228788695, 11537.307761534335, 11612.477438515118, 11697.707557310794, 11749.353785541878, 8159.632816523152, 8233.547154906299, 8376.790580235544, 8392.118827523022, 8453.283703248937, 9008.819823603622, 11137.554497819996, 11373.601923932623, 11480.425978701198, 11520.596779112691], "value": 9605.88594138904, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.4767483705864, 1473.2928194466656, 1628.2510045379124, 1785.571462848915, 1811.32980797105, 7881.369490440766, 8161.428699991003, 8173.632186551605, 8180.181981801817, 8186.38602104843, 7306.853862922751, 7510.28652278034, 7517.0, 7540.245975402457, 7540.660670269837, 2087.3529205946165, 2248.910043598254, 2320.9419764505856, 2325.767423257669, 2327.627579587266, 2348.6829278047503, 2349.2482405630203, 2352.517733864562, 2355.646653002055, 2356.1282325539487, 1847.612001479691, 2275.055851821492, 2324.7562554033593, 2333.556624241397, 2333.9416514587188, 2251.425886398974, 2257.6162052451123, 2307.40007598024, 2308.8499247548857, 2312.4681323295567, 2283.5204607032542, 2311.3412677386864, 2334.241371554235, 2335.49786795838, 2341.824363172752, 8057.315128214109, 8091.919080809234, 8145.959270203671, 8156.062052863937, 8183.468074575134, 4945.035718034985, 5102.591792656579, 5104.64267501275, 5106.106431374495, 5116.386033675983, 3228.1284053305712, 3404.063882432351, 3455.740819438552, 3488.232588830453, 3529.1530032791943, 996.3922007575374, 1028.0, 1059.655611926127, 1059.6662051453768, 1060.596973150198, 8975.955120224422, 9097.454152750855, 10534.367937923673, 10538.631147909786, 10586.576536938435, 4345.152695224464, 4530.70550414222, 4598.724076555417, 4600.95399046012, 4668.5564871337165, 9110.772230694285, 9114.86327705085, 9116.08839116083, 9126.81746365069, 9137.543122843832, 5028.547430731252, 5128.512791284822, 5132.563732082815, 5135.871603209875, 5142.177251639794, 4579.862604121881, 4691.108689349081, 4717.985633088902, 4728.810847566127, 4734.597559207506, 3526.612072672004, 3590.605033446319, 3591.5331006968927, 3751.793651349202, 3831.233753249361, 1212.4362171590253, 1213.7633161533415, 1217.5312504685583, 1227.4844565282604, 1234.9012079033655, 854.9658013679383, 860.9139086091466, 874.4228808986165, 885.9778505537284, 893.0533634347612, 5727.541796656257, 5778.711064446761, 5926.407359264039, 5988.820335389945, 6031.185789918392, 902.6524787956683, 929.2101713543569, 948.5257371314343, 957.7414098193449, 995.2784231432298, 3805.1438426354403, 3901.7463864848696, 3983.0241590810438, 3984.8007599619905, 3989.601039896045, 8984.281257499384, 9753.609855605839, 9864.704058878246, 9879.703608891745, 9891.159251463707, 9443.811123777487, 9837.704868853945, 9861.75345616351, 9869.0, 9887.307888447884, 7480.925190748132, 7718.189590092959, 7719.266669666373, 7736.0, 7758.107817600935, 5602.187682785891, 5605.215269862141, 5663.348714897675, 5786.161006654096, 5846.766129354778, 9170.679026234317, 9192.770180745267, 9209.986901440701, 9212.032736562564, 9238.214751745909, 7650.693972241048, 9069.63721451161, 9163.816723665355, 9172.633094676139, 9405.905940940776, 6912.066870972354, 6940.305969403019, 6958.930410695828, 7020.824479388056, 7027.61348125858, 6953.26990665993, 7106.324899134574, 7120.857582848417, 7132.643367831692, 7530.5858177798555, 6333.65164915934, 6884.277150899084, 6932.826679333057, 6937.236903940663, 6972.895406569007, 6724.193096828216, 6795.0, 6800.9319906802275, 6813.69338379784, 6824.38580527735, 6414.454771344302, 6544.901826472702, 6550.082988381535, 6556.18047744032, 6559.344065593403, 4733.431988161443, 4807.0, 4817.0606731686985, 4856.344393506973, 4861.343718598086, 4772.785224664829, 4784.856454306377, 4814.325994360723, 4911.287863259879, 4949.0], "value": 4344.877179344727, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.65741905846167, 272.63603089875016, 300.7533822265742, 304.45350595680753, 305.669876533344, 4877.829275975333, 5001.699558114889, 5049.469805670402, 5067.315912351836, 5126.512981266783, 4497.5158197794735, 4933.679310844793, 4938.48145944676, 4958.950410495894, 4965.6772309799835, 3422.6235114137426, 3443.0703709998347, 3888.6111388861173, 3899.3760998240286, 3899.40124548935, 3737.2338670572603, 3866.28473732359, 3869.496965394495, 3878.8448462061624, 3899.7270191086636, 3246.0099669600763, 3547.71618270539, 3820.6370394812448, 3867.7872717000555, 3868.6324799144177, 3167.9208019799457, 3176.745860331168, 3569.1434055826676, 3856.382978723391, 3873.6320049595524, 3386.86452541898, 3390.0677313738674, 3393.9490907636405, 3397.643247459006, 3407.71034462071, 4467.632154957878, 4614.561616646413, 4899.7550122494085, 4902.901941961177, 4982.427020892607, 4342.934855977162, 4390.53899340571, 4406.735595864258, 4413.161499315152, 4420.734755914656, 4423.447069116361, 5246.685198888079, 5251.68489890604, 5288.81489147878, 5334.946650533522, 3705.907352316187, 3708.684761795251, 3711.5917249102577, 3725.1432170600915, 3729.738918275722, 5080.390353157644, 5420.539254163401, 5507.917381239285, 5533.418991005967, 5539.9169012464845, 3288.67113288674, 3692.7784332939573, 3705.7035437164964, 3715.2569486102348, 3722.553293604785, 3505.859765609398, 3724.366857634209, 3760.9623903761158, 3780.5408051633, 3792.4540554581554, 4070.653994410449, 4338.674599405057, 4369.410129632459, 4388.758618276025, 4412.411531848532, 4529.26335772982, 5220.869478262998, 5432.809851655252, 5439.728013599304, 5466.808661696821, 1104.7017305327518, 1134.723436134349, 1154.5554961339944, 1156.4911438966828, 1213.6298428979246, 696.7839969609437, 770.2682451670935, 817.852786498424, 845.3237410071918, 848.6859861851154, 3865.028835293964, 4654.906901861944, 4687.718736875799, 4689.601383882408, 4701.717896926195, 4435.401220835211, 4707.435107787088, 5028.396592408934, 5119.6160287978555, 5132.408133892435, 4137.296659567881, 4295.441592593005, 5015.523525265094, 5086.601184674244, 5191.662541934762, 2369.407648087978, 2435.391152211947, 2435.6103023516152, 2587.5676863748017, 2707.6615423072117, 4818.734969576707, 4870.223972335805, 5003.574696150795, 5086.16078347076, 5439.918401223985, 4637.886907142329, 4690.413698287714, 4936.234883593049, 5317.8936421271355, 5319.946800532022, 5198.32421785173, 5334.839954801362, 5357.276767636397, 5360.919586206132, 5371.274877891435, 4394.538573449741, 4879.365682461257, 5075.822346217865, 5117.744112794271, 5274.7098909560345, 4334.306510958231, 5377.112776392008, 5425.430329815313, 5430.8099216527235, 5449.003048323327, 4984.700917944935, 5473.616846820684, 5636.85907852307, 5647.548196144381, 5670.234518339971, 3327.6006879174656, 3601.3517566838223, 3709.182500574753, 3714.7399682022, 3714.981425092857, 5392.595555333287, 5425.593080519056, 5435.918461223164, 5467.480589344006, 5475.808346708005, 4412.735235885857, 5265.710385928811, 5281.498257665516, 5312.256284120149, 5339.839804805862, 4399.5820397062225, 4405.757683327448, 5014.7994080236385, 5278.445763194809, 5280.128778751463, 4962.379702537183, 5129.333186685707, 5144.151215049475, 5268.418474947325, 5307.7442087113595, 4552.499225085304, 5216.321878155815, 5217.478252174752, 5223.843284701465, 5230.712310822789, 4810.382033248121, 4905.852824415273, 4999.47505511916, 5263.157894736823, 5265.131253343308], "value": 3814.9500948982304, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.5594351784304, 689.7275576147421, 698.8526593857193, 754.8377098923734, 756.9886451703222, 13836.239006854632, 14067.226302553368, 14438.0, 14492.710145797078, 14640.633984150401, 12657.367131643427, 12926.806097908515, 13073.215607063583, 13075.28085955272, 13122.803157952614, 654.3227759269157, 659.8119535932249, 670.286145255304, 825.6160885188386, 830.9709160179365, 806.1776987472774, 823.4853216739538, 829.0093338460542, 838.6226198210799, 841.4656692999928, 890.4657205676607, 895.4403497813867, 896.6951236579562, 897.1342654338562, 899.0739538275586, 680.9421199198049, 705.3898377903129, 836.3100442135262, 862.6549380247889, 887.6050157679836, 625.1685258606088, 625.5183508698323, 637.3721883944311, 649.1949982022259, 650.4243744286341, 13958.790618140736, 14067.0, 14071.859281407158, 14074.71850562983, 14078.788818167735, 12598.0, 12778.977681785434, 12972.416241269166, 12976.35118244084, 13095.148815326973, 11520.0, 11723.824142637868, 11826.467808948619, 12066.0, 12104.697382565419, 4327.480702315711, 4392.758398288093, 4469.08383781324, 4541.659375546814, 4548.931766023512, 88648.11351886463, 90473.0, 91430.0, 91508.6273705895, 93185.06814931899, 9843.0, 10246.282760206714, 10327.896721032694, 10390.636327728644, 10491.108255798343, 19832.60334793296, 19947.700784488243, 20131.0, 20134.395968120978, 20182.0, 7492.325690687755, 7516.436267279976, 7531.359834414026, 7607.771766847003, 7679.961600192075, 4052.230076285496, 4113.362428823537, 4153.335466325373, 4160.895977600524, 4197.076643138505, 3237.838108094586, 3344.6989770920736, 3392.728581713457, 3395.5076513905346, 3400.982995085058, 636.7771280051945, 637.0921436952325, 639.0510092512615, 645.5577929118568, 646.4537465841356, 527.8970600732897, 545.3183520599251, 600.4625859855493, 629.646260539843, 636.949034093749, 8817.779555511164, 9054.86417703735, 9238.90761092394, 9373.859392109123, 9446.0, 592.3158751641861, 639.517164540778, 642.1235014205575, 673.5217995223446, 676.5872817581271, 4397.846075387346, 4489.9551004490195, 4516.977415112903, 4531.501534831166, 4568.977155114203, 89270.0, 105520.0, 105542.47228763961, 110361.0, 112388.0, 67990.0, 76867.0, 83045.0, 98297.0, 101685.49157254166, 90508.54745726229, 91623.5418822915, 92283.0, 93359.53320233355, 93913.0, 8719.869201961848, 8751.868721969304, 8789.824203516022, 8819.162179593184, 8821.470711757314, 46627.30059049185, 51166.0, 53008.469915301896, 54954.725226373615, 56733.0, 78543.60728196322, 80828.36272367876, 81922.59038704769, 83676.0, 84458.57770711106, 9902.950485247527, 11641.417929103342, 11758.11814113962, 11800.82298765502, 11931.105167112328, 11397.487113079766, 11589.24669896454, 12219.87780122223, 12725.363731813557, 12755.553555625509, 11729.120315976506, 11825.704357391134, 11976.401179940794, 12015.699607509881, 12189.939050304693, 11306.321620702784, 11449.427528623703, 11916.642500724645, 12326.81509777336, 12456.564020259248, 11300.265482743594, 11670.299782013106, 12547.560835371083, 12761.425735841724, 12792.808107878576, 10565.788684226427, 10795.838062429228, 11211.66365009051, 11407.315561066363, 11421.428928553705, 11068.882900563112, 11155.832662509907, 11209.663710088711, 11335.489902954221, 11388.0], "value": 7174.080328368509, "unit": "score"}], "name": "dromaeo_css", "value": 7042.257294538046, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 113.0, 115.0, 113.0, 116.0, 115.0, 114.0, 115.0, 116.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 108.0, 146.0, 111.0, 110.0, 114.0, 108.0, 139.0, 109.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 182.0, 183.0, 182.0, 183.0, 180.0, 182.0, 182.0, 185.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 89.0, 61.0, 59.0, 60.0, 60.0, 99.0, 80.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 95.0, 69.0, 67.0, 69.0, 90.0, 69.0, 69.0, 91.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 101.0, 85.0, 87.0, 85.0, 89.0, 85.0, 87.0, 84.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 95.0, 96.0, 97.0, 95.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 80.0, 79.0, 80.0, 81.0, 82.0, 84.0, 80.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 56.0, 58.0, 58.0, 56.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 71.0, 76.0, 70.0, 70.0, 69.0, 71.0, 71.0, 72.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 82.0, 83.0, 83.0, 83.0, 85.0, 88.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 136.0, 141.0, 162.0, 148.0, 160.0, 157.0, 176.0, 147.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 162.0, 165.0, 164.0, 166.0, 166.0, 164.0, 165.0, 161.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 67.0, 66.0, 65.0, 68.0, 80.0, 84.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1433.6, "alertThreshold": 2.0}]} 11:53:29 INFO - Return code: 0 11:53:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:53:29 INFO - # TBPL SUCCESS # 11:53:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:53:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:53:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:53:29 INFO - 2017-01-17 11:53:29.612 screenresolution[1655:10092] starting screenresolution argv=screenresolution get 11:53:29 INFO - 2017-01-17 11:53:29.615 screenresolution[1655:10092] Display 0: 1600x1200x32@60 11:53:29 INFO - 2017-01-17 11:53:29.621 screenresolution[1656:10095] starting screenresolution argv=screenresolution list 11:53:29 INFO - Available Modes on Display 0 11:53:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:53:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:53:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:53:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:53:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:53:29 INFO - Intel Iris: 11:53:29 INFO - Chipset Model: Intel Iris 11:53:29 INFO - Type: GPU 11:53:29 INFO - Bus: Built-In 11:53:29 INFO - VRAM (Dynamic, Max): 1536 MB 11:53:29 INFO - Vendor: Intel (0x8086) 11:53:29 INFO - Device ID: 0x0a2e 11:53:29 INFO - Revision ID: 0x0009 11:53:29 INFO - Displays: 11:53:29 INFO - Display: 11:53:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:53:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:53:29 INFO - Display Serial Number: 1600x1200 60 11:53:29 INFO - Main Display: Yes 11:53:29 INFO - Mirror: Off 11:53:29 INFO - Online: Yes 11:53:29 INFO - Rotation: Supported 11:53:29 INFO - Return code: 0 11:53:29 INFO - Running post-action listener: _resource_record_post_action 11:53:29 INFO - [mozharness: 2017-01-17 19:53:29.857979Z] Finished run-tests step (success) 11:53:29 INFO - Running post-run listener: _resource_record_post_run 11:53:29 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37770240; Write bytes: 659703808; Read time: 238; Write time: 1904 11:53:29 INFO - TinderboxPrint: CPU usage
53.2% 11:53:29 INFO - TinderboxPrint: I/O read bytes / time
37,770,240 / 238 11:53:29 INFO - TinderboxPrint: I/O write bytes / time
659,703,808 / 1,904 11:53:29 INFO - TinderboxPrint: CPU idle
2,269.0 (46.8%) 11:53:29 INFO - TinderboxPrint: CPU system
964.0 (19.9%) 11:53:29 INFO - TinderboxPrint: CPU user
1,616.6 (33.3%) 11:53:29 INFO - TinderboxPrint: Swap in / out
386,883,584 / 0 11:53:29 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346477056; Write bytes: 346388480; Read time: 27811; Write time: 710 11:53:29 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 35709952; Write bytes: 306188288; Read time: 220; Write time: 1164 11:53:30 INFO - Running post-run listener: _upload_blobber_files 11:53:30 INFO - Blob upload gear active. 11:53:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:53:30 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 11:53:30 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:53:30 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:53:30 INFO - (blobuploader) - INFO - Open directory for files ... 11:53:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:53:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:53:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:53:32 INFO - (blobuploader) - INFO - Done attempting. 11:53:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:53:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:53:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:53:33 INFO - (blobuploader) - INFO - Done attempting. 11:53:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:53:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:53:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:53:36 INFO - (blobuploader) - INFO - Done attempting. 11:53:36 INFO - (blobuploader) - INFO - Iteration through files over. 11:53:36 INFO - Return code: 0 11:53:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:53:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:53:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/523636da30efb667f80f94eaede7f5b83528ee64e85cbea008827d94376bf2cb754bb2c58f8262833327d3d00207e94f9eb8d417008507c2050871d9025c1fe9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bd003f35e1967d8613b9c27a4c8408cc00b427470d297b9d5b442c2e043671f67107a9fb2bb42238222864a0ea38ba2aa60ff6e58f618ce3bb405c9d840c95f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9d2bf57bb56d3aec7f775ae56fb6ad532ba14f93aece1180e5277a14aba59e0d7656fe077a5773d2bf72f2aa2600e149b10243cef8b2b3c02b5114f140ab3d"} 11:53:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:53:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:53:36 INFO - Contents: 11:53:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/523636da30efb667f80f94eaede7f5b83528ee64e85cbea008827d94376bf2cb754bb2c58f8262833327d3d00207e94f9eb8d417008507c2050871d9025c1fe9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bd003f35e1967d8613b9c27a4c8408cc00b427470d297b9d5b442c2e043671f67107a9fb2bb42238222864a0ea38ba2aa60ff6e58f618ce3bb405c9d840c95f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9d2bf57bb56d3aec7f775ae56fb6ad532ba14f93aece1180e5277a14aba59e0d7656fe077a5773d2bf72f2aa2600e149b10243cef8b2b3c02b5114f140ab3d"} 11:53:36 INFO - Running post-run listener: copy_logs_to_upload_dir 11:53:36 INFO - Copying logs to upload dir... 11:53:36 INFO - mkdir: /builds/slave/test/build/upload/logs 11:53:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.259976 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-17 11:53:36.435511) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 11:53:36.439105) ========= 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.qzepLvQhch/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.YXVFVKgAJT/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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/Mozilla-Aurora/sha512/523636da30efb667f80f94eaede7f5b83528ee64e85cbea008827d94376bf2cb754bb2c58f8262833327d3d00207e94f9eb8d417008507c2050871d9025c1fe9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bd003f35e1967d8613b9c27a4c8408cc00b427470d297b9d5b442c2e043671f67107a9fb2bb42238222864a0ea38ba2aa60ff6e58f618ce3bb405c9d840c95f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9d2bf57bb56d3aec7f775ae56fb6ad532ba14f93aece1180e5277a14aba59e0d7656fe077a5773d2bf72f2aa2600e149b10243cef8b2b3c02b5114f140ab3d"} build_url:https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/aeq7fVgASU-PGFTaivNqcQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/523636da30efb667f80f94eaede7f5b83528ee64e85cbea008827d94376bf2cb754bb2c58f8262833327d3d00207e94f9eb8d417008507c2050871d9025c1fe9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bd003f35e1967d8613b9c27a4c8408cc00b427470d297b9d5b442c2e043671f67107a9fb2bb42238222864a0ea38ba2aa60ff6e58f618ce3bb405c9d840c95f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9d2bf57bb56d3aec7f775ae56fb6ad532ba14f93aece1180e5277a14aba59e0d7656fe077a5773d2bf72f2aa2600e149b10243cef8b2b3c02b5114f140ab3d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 11:53:36.473109) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 11:53:36.473426) ========= 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.qzepLvQhch/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.YXVFVKgAJT/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.005047 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 11:53:36.521645) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 11:53:36.521980) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 11:53:36.522334) ========= ========= Total master_lag: 0.18 =========