builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0368 starttime: 1482404448.16 results: success (0) buildid: 20161222004019 builduid: 6c33d445397e4fe18aad9c26c94d8d82 revision: 40e863f1e8180487fa1011ed6ac807a30905d617 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.156039) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.156456) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.156725) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.180727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.180985) ========= 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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-22 03:00:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 124M=0s 2016-12-22 03:00:48 (124 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093163 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.300150) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.300456) ========= 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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.020326 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:48.348456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 03:00:48.348759) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 40e863f1e8180487fa1011ed6ac807a30905d617 --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 40e863f1e8180487fa1011ed6ac807a30905d617 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-22 03:00:48,432 truncating revision to first 12 chars 2016-12-22 03:00:48,432 Setting DEBUG logging. 2016-12-22 03:00:48,432 attempt 1/10 2016-12-22 03:00:48,433 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/40e863f1e818?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 03:00:49,457 unpacking tar archive at: mozilla-aurora-40e863f1e818/testing/mozharness/ program finished with exit code 0 elapsedTime=1.278954 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 03:00:49.653922) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:49.657252) ========= script_repo_revision: 40e863f1e8180487fa1011ed6ac807a30905d617 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:49.657634) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:49.657895) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 03:00:49.677370) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-22 03:00:49.677638) ========= /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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:00:49 INFO - MultiFileLogger online at 20161222 03:00:49 in /builds/slave/test 03:00:49 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 03:00:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:49 INFO - {'append_to_log': False, 03:00:49 INFO - 'base_work_dir': '/builds/slave/test', 03:00:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:00:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:49 INFO - 'branch': 'Mozilla-Aurora', 03:00:49 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:49 INFO - 'config_files': ('talos/mac_config.py',), 03:00:49 INFO - 'default_actions': ('clobber', 03:00:49 INFO - 'read-buildbot-config', 03:00:49 INFO - 'download-and-extract', 03:00:49 INFO - 'populate-webroot', 03:00:49 INFO - 'create-virtualenv', 03:00:49 INFO - 'install', 03:00:49 INFO - 'run-tests'), 03:00:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:49 INFO - 'download_minidump_stackwalk': True, 03:00:49 INFO - 'download_symbols': 'ondemand', 03:00:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:00:49 INFO - 'tooltool.py': '/tools/tooltool.py', 03:00:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:00:49 INFO - '/tools/misc-python/virtualenv.py')}, 03:00:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:49 INFO - 'installer_path': 'installer.exe', 03:00:49 INFO - 'log_level': 'info', 03:00:49 INFO - 'log_name': 'talos', 03:00:49 INFO - 'log_to_console': True, 03:00:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:00:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:00:49 INFO - 'opt_config_files': (), 03:00:49 INFO - 'pip_index': False, 03:00:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:49 INFO - 'cmd': ('bash', 03:00:49 INFO - '-c', 03:00:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:49 INFO - 'enabled': True, 03:00:49 INFO - 'halt_on_failure': False, 03:00:49 INFO - 'name': 'check_screen_resolution'},), 03:00:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:49 INFO - 'cmd': ('bash', 03:00:49 INFO - '-c', 03:00:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:49 INFO - 'enabled': True, 03:00:49 INFO - 'halt_on_failure': False, 03:00:49 INFO - 'name': 'check_screen_resolution'},), 03:00:49 INFO - 'run_cmd_checks_enabled': True, 03:00:49 INFO - 'sps_profile': False, 03:00:49 INFO - 'sps_profile_interval': 0, 03:00:49 INFO - 'suite': 'dromaeojs', 03:00:49 INFO - 'system_bits': '32', 03:00:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:49 INFO - 'title': 't-yosemite-r7-0368', 03:00:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:49 INFO - 'use_talos_json': True, 03:00:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:49 INFO - 'work_dir': 'build'} 03:00:49 INFO - [mozharness: 2016-12-22 11:00:49.880208Z] Running clobber step. 03:00:49 INFO - Running pre-action listener: _resource_record_pre_action 03:00:49 INFO - Running main action method: clobber 03:00:49 INFO - rmtree: /builds/slave/test/build 03:00:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:51 INFO - Running post-action listener: _resource_record_post_action 03:00:51 INFO - [mozharness: 2016-12-22 11:00:51.459755Z] Finished clobber step (success) 03:00:51 INFO - [mozharness: 2016-12-22 11:00:51.459885Z] Running read-buildbot-config step. 03:00:51 INFO - Running pre-action listener: _resource_record_pre_action 03:00:51 INFO - Running main action method: read_buildbot_config 03:00:51 INFO - Using buildbot properties: 03:00:51 INFO - { 03:00:51 INFO - "project": "", 03:00:51 INFO - "product": "firefox", 03:00:51 INFO - "script_repo_revision": "production", 03:00:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:00:51 INFO - "repository": "", 03:00:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:00:51 INFO - "buildid": "20161222004019", 03:00:51 INFO - "stage_platform": "macosx64", 03:00:51 INFO - "basedir": "/builds/slave/test", 03:00:51 INFO - "nightly_build": "True", 03:00:51 INFO - "buildnumber": 6, 03:00:51 INFO - "slavename": "t-yosemite-r7-0368", 03:00:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:00:51 INFO - "platform": "yosemite_r7", 03:00:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:00:51 INFO - "branch": "mozilla-aurora", 03:00:51 INFO - "repo_path": "releases/mozilla-aurora", 03:00:51 INFO - "revision": "40e863f1e8180487fa1011ed6ac807a30905d617", 03:00:51 INFO - "pgo_build": "False", 03:00:51 INFO - "builduid": "6c33d445397e4fe18aad9c26c94d8d82", 03:00:51 INFO - "slavebuilddir": "test" 03:00:51 INFO - } 03:00:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:00:51 INFO - Running post-action listener: _resource_record_post_action 03:00:51 INFO - [mozharness: 2016-12-22 11:00:51.463247Z] Finished read-buildbot-config step (success) 03:00:51 INFO - [mozharness: 2016-12-22 11:00:51.463361Z] Running download-and-extract step. 03:00:51 INFO - Running pre-action listener: _resource_record_pre_action 03:00:51 INFO - Running main action method: download_and_extract 03:00:51 INFO - mkdir: /builds/slave/test/build/tests 03:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:51 INFO - trying https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:00:51 INFO - Downloading https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/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 03:00:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/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 03:00:52 INFO - Downloaded 1199 bytes. 03:00:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:00:52 INFO - Using the following test package requirements: 03:00:52 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:00:52 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:00:52 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:00:52 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'jsshell-mac.zip'], 03:00:52 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:00:52 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:00:52 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:00:52 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:00:52 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:00:52 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:52 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:00:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:00:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:00:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:00:52 INFO - Fetch https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:00:52 INFO - Http code: 200 03:00:52 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 03:00:52 INFO - x-amz-cf-id: L_rdDr6g8Y-wMuTalCnICwJy8u8fkKGCkMEOWy3dJUbMTpF7VMhmzg== 03:00:52 INFO - x-amz-version-id: tFotBOKw2rwqAq8mazu8PHg4Us.678m3 03:00:52 INFO - Expected file size: 16269668 03:00:52 INFO - Obtained file size: 16269668 03:00:59 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 03:00:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:00:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:00:59 INFO - Fetch https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:01:00 INFO - Http code: 200 03:01:00 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 03:01:00 INFO - x-amz-cf-id: huxGGSBioS3Lpb9EuV-GvpSO2UyREi6zX4Ftv8S-XI1PYwvcpm8Iaw== 03:01:00 INFO - x-amz-version-id: tFotBOKw2rwqAq8mazu8PHg4Us.678m3 03:01:00 INFO - Expected file size: 16269668 03:01:00 INFO - Obtained file size: 16269668 03:01:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:01:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:01:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:01:07 INFO - Http code: 200 03:01:07 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 03:01:07 INFO - x-amz-cf-id: sbYMXKRt600hbweMg7z_nHo7LBea3t7KqtayyMCnFJFGni6uulAJ4A== 03:01:07 INFO - x-amz-version-id: AToCeg0te2fbkahYXnOIhrjuDm7czaqY 03:01:07 INFO - Expected file size: 14409038 03:01:07 INFO - Obtained file size: 14409038 03:01:08 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')]} 03:01:08 INFO - trying https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:08 INFO - Downloading https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:01:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/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 03:01:10 INFO - Downloaded 109867207 bytes. 03:01:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:10 INFO - mkdir: /builds/slave/test/properties 03:01:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:01:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:01:10 INFO - Contents: 03:01:10 INFO - build_url:https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:11 INFO - Running post-action listener: _resource_record_post_action 03:01:11 INFO - Running post-action listener: set_extra_try_arguments 03:01:11 INFO - [mozharness: 2016-12-22 11:01:11.559981Z] Finished download-and-extract step (success) 03:01:11 INFO - [mozharness: 2016-12-22 11:01:11.560131Z] Running populate-webroot step. 03:01:11 INFO - Running pre-action listener: _resource_record_pre_action 03:01:11 INFO - Running main action method: populate_webroot 03:01:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:01:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:01:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:01:11 INFO - '--tppagecycles', 03:01:11 INFO - '7'], 03:01:11 INFO - 'tests': ['tsvgm']}}, 03:01:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:01:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:01:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:01:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:01:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['damp', 'tps']}, 03:01:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'tests': ['damp', 'tps']}, 03:01:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['dromaeo_dom']}, 03:01:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:01:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['basic_compositor_video']}, 03:01:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:01:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['a11yr', 03:01:11 INFO - 'ts_paint', 03:01:11 INFO - 'tpaint', 03:01:11 INFO - 'sessionrestore', 03:01:11 INFO - 'sessionrestore_no_auto_restore', 03:01:11 INFO - 'tabpaint']}, 03:01:11 INFO - 'other-e10s': {'tests': ['a11yr', 03:01:11 INFO - 'ts_paint', 03:01:11 INFO - 'tpaint', 03:01:11 INFO - 'sessionrestore', 03:01:11 INFO - 'sessionrestore_no_auto_restore', 03:01:11 INFO - 'tabpaint']}, 03:01:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:01:11 INFO - 'ts_paint', 03:01:11 INFO - 'tpaint', 03:01:11 INFO - 'sessionrestore', 03:01:11 INFO - 'sessionrestore_no_auto_restore', 03:01:11 INFO - 'tabpaint']}, 03:01:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:01:11 INFO - 'ts_paint', 03:01:11 INFO - 'tpaint', 03:01:11 INFO - 'sessionrestore', 03:01:11 INFO - 'sessionrestore_no_auto_restore', 03:01:11 INFO - 'tabpaint']}, 03:01:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['a11yr', 03:01:11 INFO - 'ts_paint', 03:01:11 INFO - 'tpaint', 03:01:11 INFO - 'sessionrestore', 03:01:11 INFO - 'sessionrestore_no_auto_restore', 03:01:11 INFO - 'tabpaint']}, 03:01:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['a11yr', 03:01:11 INFO - 'ts_paint', 03:01:11 INFO - 'tpaint', 03:01:11 INFO - 'sessionrestore', 03:01:11 INFO - 'sessionrestore_no_auto_restore', 03:01:11 INFO - 'tabpaint']}, 03:01:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['tsvgx', 03:01:11 INFO - 'tsvgr_opacity', 03:01:11 INFO - 'tart', 03:01:11 INFO - 'tscrollx', 03:01:11 INFO - 'cart']}, 03:01:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:01:11 INFO - 'tsvgr_opacity', 03:01:11 INFO - 'tart', 03:01:11 INFO - 'tscrollx', 03:01:11 INFO - 'cart']}, 03:01:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'talos_options': ['--disable-e10s'], 03:01:11 INFO - 'tests': ['tp5o']}, 03:01:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'tests': ['tp5o']}, 03:01:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'talos_options': ['--disable-e10s', 03:01:11 INFO - '--xperf_path', 03:01:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:11 INFO - 'tests': ['tp5n']}, 03:01:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:11 INFO - 'talos_options': ['--xperf_path', 03:01:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:11 INFO - 'tests': ['tp5n']}}, 03:01:11 INFO - 'talos.zip': {'path': '', 03:01:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:01:11 INFO - Running post-action listener: _resource_record_post_action 03:01:11 INFO - [mozharness: 2016-12-22 11:01:11.579159Z] Finished populate-webroot step (success) 03:01:11 INFO - [mozharness: 2016-12-22 11:01:11.579278Z] Running create-virtualenv step. 03:01:11 INFO - Running pre-action listener: _resource_record_pre_action 03:01:11 INFO - Running main action method: create_virtualenv 03:01:11 INFO - Creating virtualenv /builds/slave/test/build/venv 03:01:11 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 03:01:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:01:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:01:11 INFO - Using real prefix '/tools/python27' 03:01:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:01:12 INFO - Installing distribute.............................................................................................................................................................................................done. 03:01:15 INFO - Installing pip.................done. 03:01:15 INFO - Return code: 0 03:01:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:01:15 INFO - Reading from file tmpfile_stdout 03:01:15 INFO - Output received: 03:01:15 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) 03:01:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:01: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')]} 03:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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')]} 03:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:15 INFO - 'HOME': '/Users/cltbld', 03:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:15 INFO - 'LOGNAME': 'cltbld', 03:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:15 INFO - 'MOZ_NO_REMOTE': '1', 03:01:15 INFO - 'NO_EM_RESTART': '1', 03:01:15 INFO - 'PAGER': '/bin/cat', 03:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:15 INFO - 'PWD': '/builds/slave/test', 03:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:15 INFO - 'SHELL': '/bin/bash', 03:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:15 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:15 INFO - 'USER': 'cltbld', 03:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:15 INFO - 'XPC_FLAGS': '0x0', 03:01:15 INFO - 'XPC_SERVICE_NAME': '0', 03:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:15 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 03:01:15 INFO - Cleaning up... 03:01:15 INFO - Return code: 0 03:01:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:01: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')]} 03:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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')]} 03:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:15 INFO - 'HOME': '/Users/cltbld', 03:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:15 INFO - 'LOGNAME': 'cltbld', 03:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:15 INFO - 'MOZ_NO_REMOTE': '1', 03:01:15 INFO - 'NO_EM_RESTART': '1', 03:01:15 INFO - 'PAGER': '/bin/cat', 03:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:15 INFO - 'PWD': '/builds/slave/test', 03:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:15 INFO - 'SHELL': '/bin/bash', 03:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:15 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:15 INFO - 'USER': 'cltbld', 03:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:15 INFO - 'XPC_FLAGS': '0x0', 03:01:15 INFO - 'XPC_SERVICE_NAME': '0', 03:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:16 INFO - Downloading/unpacking psutil>=3.1.1 03:01:16 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 03:01:16 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 03:01:16 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 03:01:16 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 03:01:16 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 03:01:16 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 03:01:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:19 INFO - Installing collected packages: psutil 03:01:19 INFO - Running setup.py install for psutil 03:01:19 INFO - building 'psutil._psutil_osx' extension 03:01:19 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 03:01:19 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 03:01:19 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 03:01:20 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 03:01:20 INFO - building 'psutil._psutil_posix' extension 03:01:20 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 03:01:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:01:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:01:20 INFO - ^ 03:01:20 INFO - 1 warning generated. 03:01:20 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 03:01:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:20 INFO - Successfully installed psutil 03:01:20 INFO - Cleaning up... 03:01:20 INFO - Return code: 0 03:01:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:01:20 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')]} 03:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:20 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')]} 03:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:20 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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:20 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 03:01:20 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 03:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:20 INFO - 'HOME': '/Users/cltbld', 03:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:20 INFO - 'LOGNAME': 'cltbld', 03:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:20 INFO - 'MOZ_NO_REMOTE': '1', 03:01:20 INFO - 'NO_EM_RESTART': '1', 03:01:20 INFO - 'PAGER': '/bin/cat', 03:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:20 INFO - 'PWD': '/builds/slave/test', 03:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:20 INFO - 'SHELL': '/bin/bash', 03:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:20 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:20 INFO - 'USER': 'cltbld', 03:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:20 INFO - 'XPC_FLAGS': '0x0', 03:01:20 INFO - 'XPC_SERVICE_NAME': '0', 03:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:01:20 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 03:01:20 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 03:01:20 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 03:01:20 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 03:01:20 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 03:01:20 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 03:01:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:01:23 INFO - Installing collected packages: mozsystemmonitor 03:01:23 INFO - Running setup.py install for mozsystemmonitor 03:01:23 INFO - Successfully installed mozsystemmonitor 03:01:23 INFO - Cleaning up... 03:01:23 INFO - Return code: 0 03:01:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:23 INFO - 'HOME': '/Users/cltbld', 03:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:23 INFO - 'LOGNAME': 'cltbld', 03:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:23 INFO - 'MOZ_NO_REMOTE': '1', 03:01:23 INFO - 'NO_EM_RESTART': '1', 03:01:23 INFO - 'PAGER': '/bin/cat', 03:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:23 INFO - 'PWD': '/builds/slave/test', 03:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:23 INFO - 'SHELL': '/bin/bash', 03:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:23 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:23 INFO - 'USER': 'cltbld', 03:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:23 INFO - 'XPC_FLAGS': '0x0', 03:01:23 INFO - 'XPC_SERVICE_NAME': '0', 03:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:23 INFO - Downloading/unpacking jsonschema==2.5.1 03:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:01:26 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 03:01:26 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 03:01:26 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 03:01:26 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 03:01:26 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 03:01:26 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 03:01:26 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:26 INFO - warning: no files found matching '*.txt' 03:01:26 INFO - no previously-included directories found matching 'build' 03:01:26 INFO - no previously-included directories found matching 'dist' 03:01:26 INFO - no previously-included directories found matching '.git*' 03:01:26 INFO - Installing collected packages: jsonschema, functools32 03:01:27 INFO - Running setup.py install for functools32 03:01:27 INFO - warning: no files found matching '*.txt' 03:01:27 INFO - no previously-included directories found matching 'build' 03:01:27 INFO - no previously-included directories found matching 'dist' 03:01:27 INFO - no previously-included directories found matching '.git*' 03:01:27 INFO - Successfully installed jsonschema functools32 03:01:27 INFO - Cleaning up... 03:01:27 INFO - Return code: 0 03:01:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:27 INFO - 'HOME': '/Users/cltbld', 03:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:27 INFO - 'LOGNAME': 'cltbld', 03:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:27 INFO - 'MOZ_NO_REMOTE': '1', 03:01:27 INFO - 'NO_EM_RESTART': '1', 03:01:27 INFO - 'PAGER': '/bin/cat', 03:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:27 INFO - 'PWD': '/builds/slave/test', 03:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:27 INFO - 'SHELL': '/bin/bash', 03:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:27 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:27 INFO - 'USER': 'cltbld', 03:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:27 INFO - 'XPC_FLAGS': '0x0', 03:01:27 INFO - 'XPC_SERVICE_NAME': '0', 03:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:01:27 INFO - Cleaning up... 03:01:27 INFO - Return code: 0 03:01:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:27 INFO - 'HOME': '/Users/cltbld', 03:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:27 INFO - 'LOGNAME': 'cltbld', 03:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:27 INFO - 'MOZ_NO_REMOTE': '1', 03:01:27 INFO - 'NO_EM_RESTART': '1', 03:01:27 INFO - 'PAGER': '/bin/cat', 03:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:27 INFO - 'PWD': '/builds/slave/test', 03:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:27 INFO - 'SHELL': '/bin/bash', 03:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:27 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:27 INFO - 'USER': 'cltbld', 03:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:27 INFO - 'XPC_FLAGS': '0x0', 03:01:27 INFO - 'XPC_SERVICE_NAME': '0', 03:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:27 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:30 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:01:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:31 INFO - Downloading docopt-0.6.1.tar.gz 03:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:31 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:31 INFO - Running setup.py install for blobuploader 03:01:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:31 INFO - Running setup.py install for requests 03:01:32 INFO - Running setup.py install for docopt 03:01:32 INFO - Successfully installed blobuploader requests docopt 03:01:32 INFO - Cleaning up... 03:01:32 INFO - Return code: 0 03:01:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:32 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')]} 03:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:32 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')]} 03:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:32 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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:32 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 03:01:32 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 03:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:32 INFO - 'HOME': '/Users/cltbld', 03:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:32 INFO - 'LOGNAME': 'cltbld', 03:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:32 INFO - 'MOZ_NO_REMOTE': '1', 03:01:32 INFO - 'NO_EM_RESTART': '1', 03:01:32 INFO - 'PAGER': '/bin/cat', 03:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:32 INFO - 'PWD': '/builds/slave/test', 03:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:32 INFO - 'SHELL': '/bin/bash', 03:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:32 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:32 INFO - 'USER': 'cltbld', 03:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:32 INFO - 'XPC_FLAGS': '0x0', 03:01:32 INFO - 'XPC_SERVICE_NAME': '0', 03:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:32 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-EqGtGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:32 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2Ns57B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-CgEaHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-iZ6cnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-y_4DQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-nbe64m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-tD9B7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-SqMCOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-G35OPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2kW3JX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Hvn52w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:33 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-pIlVp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:34 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-y4HkBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:34 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-lNhRQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:34 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2bmKyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:34 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-jpUPwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:34 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-qswkyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:34 INFO - Running setup.py install for manifestparser 03:01:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:34 INFO - Running setup.py install for mozcrash 03:01:34 INFO - Running setup.py install for mozdebug 03:01:34 INFO - Running setup.py install for mozdevice 03:01:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Running setup.py install for mozfile 03:01:35 INFO - Running setup.py install for mozhttpd 03:01:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Running setup.py install for mozinfo 03:01:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Running setup.py install for mozInstall 03:01:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:35 INFO - Running setup.py install for mozleak 03:01:35 INFO - Running setup.py install for mozlog 03:01:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:36 INFO - Running setup.py install for moznetwork 03:01:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:36 INFO - Running setup.py install for mozprocess 03:01:36 INFO - Running setup.py install for mozprofile 03:01:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:36 INFO - Running setup.py install for mozrunner 03:01:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:36 INFO - Running setup.py install for mozscreenshot 03:01:36 INFO - Running setup.py install for moztest 03:01:37 INFO - Running setup.py install for mozversion 03:01:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:37 INFO - Cleaning up... 03:01:37 INFO - Return code: 0 03:01:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:37 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')]} 03:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:37 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')]} 03:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:37 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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:37 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 03:01:37 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 03:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:37 INFO - 'HOME': '/Users/cltbld', 03:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:37 INFO - 'LOGNAME': 'cltbld', 03:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:37 INFO - 'MOZ_NO_REMOTE': '1', 03:01:37 INFO - 'NO_EM_RESTART': '1', 03:01:37 INFO - 'PAGER': '/bin/cat', 03:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:37 INFO - 'PWD': '/builds/slave/test', 03:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:37 INFO - 'SHELL': '/bin/bash', 03:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:37 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:37 INFO - 'USER': 'cltbld', 03:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:37 INFO - 'XPC_FLAGS': '0x0', 03:01:37 INFO - 'XPC_SERVICE_NAME': '0', 03:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:37 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-8QxBxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:37 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)) 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:37 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-wExOO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:37 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)) 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:37 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-t2M27C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:37 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)) 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:37 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-xRpzZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:37 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)) 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:37 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-73Sr4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:37 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)) 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-YSmGbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-8CMc1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-3zsYPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-5ak_S3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-AT61Sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ptBTLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-T0LeID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-GnTp8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-0GX0Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:38 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)) 03:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:38 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-zVSDBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:39 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)) 03:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:39 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-YGDnaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:39 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-UtjWom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:39 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)) 03:01:39 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)) 03:01:39 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)) 03:01:39 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)) 03:01:39 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)) 03:01:39 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)) 03:01:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:39 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 03:01:39 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 03:01:39 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 03:01:39 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 03:01:39 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 03:01:39 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 03:01:41 INFO - Downloading blessings-1.6.tar.gz 03:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:42 INFO - Installing collected packages: blessings 03:01:42 INFO - Running setup.py install for blessings 03:01:42 INFO - Successfully installed blessings 03:01:42 INFO - Cleaning up... 03:01:42 INFO - Return code: 0 03:01:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:01:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:42 INFO - Reading from file tmpfile_stdout 03:01:42 INFO - Current package versions: 03:01:42 INFO - blessings == 1.6 03:01:42 INFO - blobuploader == 1.2.4 03:01:42 INFO - docopt == 0.6.1 03:01:42 INFO - functools32 == 3.2.3-2 03:01:42 INFO - jsonschema == 2.5.1 03:01:42 INFO - manifestparser == 1.1 03:01:42 INFO - mozInstall == 1.12 03:01:42 INFO - mozcrash == 1.0 03:01:42 INFO - mozdebug == 0.1 03:01:42 INFO - mozdevice == 0.48 03:01:42 INFO - mozfile == 1.2 03:01:42 INFO - mozhttpd == 0.7 03:01:42 INFO - mozinfo == 0.9 03:01:42 INFO - mozleak == 0.1 03:01:42 INFO - mozlog == 3.4 03:01:42 INFO - moznetwork == 0.27 03:01:42 INFO - mozprocess == 0.23 03:01:42 INFO - mozprofile == 0.28 03:01:42 INFO - mozrunner == 6.13 03:01:42 INFO - mozscreenshot == 0.1 03:01:42 INFO - mozsystemmonitor == 0.3 03:01:42 INFO - moztest == 0.7 03:01:42 INFO - mozversion == 1.4 03:01:42 INFO - psutil == 3.1.1 03:01:42 INFO - requests == 1.2.3 03:01:42 INFO - wsgiref == 0.1.2 03:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:42 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')]} 03:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:42 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')]} 03:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:42 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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:42 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 03:01:42 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 03:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:42 INFO - 'HOME': '/Users/cltbld', 03:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:42 INFO - 'LOGNAME': 'cltbld', 03:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:42 INFO - 'MOZ_NO_REMOTE': '1', 03:01:42 INFO - 'NO_EM_RESTART': '1', 03:01:42 INFO - 'PAGER': '/bin/cat', 03:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:42 INFO - 'PWD': '/builds/slave/test', 03:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:42 INFO - 'SHELL': '/bin/bash', 03:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:42 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:42 INFO - 'USER': 'cltbld', 03:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:42 INFO - 'XPC_FLAGS': '0x0', 03:01:42 INFO - 'XPC_SERVICE_NAME': '0', 03:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 INFO - Cleaning up... 03:01:42 INFO - Return code: 0 03:01:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:01:42 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')]} 03:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:42 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')]} 03:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:42 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 0x106af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c259d8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, '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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:01:42 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 03:01:42 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 03:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:42 INFO - 'HOME': '/Users/cltbld', 03:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:42 INFO - 'LOGNAME': 'cltbld', 03:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:42 INFO - 'MOZ_NO_REMOTE': '1', 03:01:42 INFO - 'NO_EM_RESTART': '1', 03:01:42 INFO - 'PAGER': '/bin/cat', 03:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:42 INFO - 'PWD': '/builds/slave/test', 03:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:42 INFO - 'SHELL': '/bin/bash', 03:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:01:42 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:42 INFO - 'USER': 'cltbld', 03:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:42 INFO - 'XPC_FLAGS': '0x0', 03:01:42 INFO - 'XPC_SERVICE_NAME': '0', 03:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:01:43 INFO - Cleaning up... 03:01:43 INFO - Return code: 0 03:01:43 INFO - Running post-action listener: _resource_record_post_action 03:01:43 INFO - Running post-action listener: _start_resource_monitoring 03:01:43 INFO - Starting resource monitoring. 03:01:43 INFO - [mozharness: 2016-12-22 11:01:43.113850Z] Finished create-virtualenv step (success) 03:01:43 INFO - [mozharness: 2016-12-22 11:01:43.114867Z] Running install step. 03:01:43 INFO - Running pre-action listener: _resource_record_pre_action 03:01:43 INFO - Running main action method: install 03:01:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:43 INFO - Reading from file tmpfile_stdout 03:01:43 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:01:43 INFO - Reading from file tmpfile_stdout 03:01:43 INFO - Output received: 03:01:43 INFO - Usage: mozinstall [options] installer 03:01:43 INFO - Options: 03:01:43 INFO - -h, --help show this help message and exit 03:01:43 INFO - -d DEST, --destination=DEST 03:01:43 INFO - Directory to install application into. [default: 03:01:43 INFO - "/builds/slave/test"] 03:01:43 INFO - --app=APP Application being installed. [default: firefox] 03:01:43 INFO - mkdir: /builds/slave/test/build/application 03:01:43 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'] 03:01:43 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 03:02:14 INFO - Reading from file tmpfile_stdout 03:02:14 INFO - Output received: 03:02:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:02:14 INFO - Running post-action listener: _resource_record_post_action 03:02:14 INFO - [mozharness: 2016-12-22 11:02:14.779321Z] Finished install step (success) 03:02:14 INFO - [mozharness: 2016-12-22 11:02:14.779470Z] Running run-tests step. 03:02:14 INFO - Running pre-action listener: _resource_record_pre_action 03:02:14 INFO - Running main action method: run_tests 03:02:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:14 INFO - 2016-12-22 03:02:14.793 screenresolution[1914:20024] starting screenresolution argv=screenresolution get 03:02:14 INFO - 2016-12-22 03:02:14.796 screenresolution[1914:20024] Display 0: 1600x1200x32@60 03:02:14 INFO - 2016-12-22 03:02:14.802 screenresolution[1915:20026] starting screenresolution argv=screenresolution list 03:02:14 INFO - Available Modes on Display 0 03:02:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:15 INFO - Intel Iris: 03:02:15 INFO - Chipset Model: Intel Iris 03:02:15 INFO - Type: GPU 03:02:15 INFO - Bus: Built-In 03:02:15 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:15 INFO - Vendor: Intel (0x8086) 03:02:15 INFO - Device ID: 0x0a2e 03:02:15 INFO - Revision ID: 0x0009 03:02:15 INFO - Displays: 03:02:15 INFO - Display: 03:02:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:15 INFO - Display Serial Number: 1600x1200 60 03:02:15 INFO - Main Display: Yes 03:02:15 INFO - Mirror: Off 03:02:15 INFO - Online: Yes 03:02:15 INFO - Rotation: Supported 03:02:15 INFO - Return code: 0 03:02:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:02:15 INFO - Python 2.7.3 03:02:15 INFO - Return code: 0 03:02:15 INFO - grabbing minidump binary from tooltool 03:02: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')]} 03:02:15 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 0x106906d30>, '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 0x10676cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:02:15 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 03:02:15 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 03:02:15 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 03:02:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:02:15 INFO - Return code: 0 03:02:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:02:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:02:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:02:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:02:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:02:15 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/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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 03:02:15 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/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0368 --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 03:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C6d2w4U1Xd/Render', 03:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:15 INFO - 'HOME': '/Users/cltbld', 03:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:15 INFO - 'LOGNAME': 'cltbld', 03:02:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:02:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:15 INFO - 'MOZ_NO_REMOTE': '1', 03:02:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:02:15 INFO - 'NO_EM_RESTART': '1', 03:02:15 INFO - 'PAGER': '/bin/cat', 03:02:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:15 INFO - 'PWD': '/builds/slave/test', 03:02:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:15 INFO - 'SHELL': '/bin/bash', 03:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ge20wh9Ppp/Listeners', 03:02:15 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:15 INFO - 'USER': 'cltbld', 03:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:15 INFO - 'XPC_FLAGS': '0x0', 03:02:15 INFO - 'XPC_SERVICE_NAME': '0', 03:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:15 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/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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 03:02:15 INFO - mozversion application_buildid: 20161222004019 03:02:15 INFO - mozversion application_changeset: 40e863f1e8180487fa1011ed6ac807a30905d617 03:02:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:02:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:02:15 INFO - mozversion application_name: Firefox 03:02:15 INFO - mozversion application_remotingname: firefox-dev 03:02:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:15 INFO - mozversion application_vendor: Mozilla 03:02:15 INFO - mozversion application_version: 52.0a2 03:02:15 INFO - mozversion platform_buildid: 20161222004019 03:02:15 INFO - mozversion platform_changeset: 40e863f1e8180487fa1011ed6ac807a30905d617 03:02:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:15 INFO - mozversion platform_version: 52.0a2 03:02:15 INFO - using testdate: 1482404535 03:02:15 INFO - actual date: 1482404535 03:02:15 INFO - starting webserver on 'localhost:55656' 03:02:15 INFO - SUITE-START | Running 2 tests 03:02:15 INFO - TEST-START | dromaeo_css 03:02:15 INFO - operating with platform_type : mac_ 03:02:15 INFO - Initialising browser for dromaeo_css test... 03:02:15 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpgvLnxA/profile http://localhost:55656/getInfo.html) 03:02:18 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 03:02:18 INFO - PROCESS | 1925 | colorDepth:24 03:02:18 INFO - PROCESS | 1925 | Browser inner width/height: 1024/689 03:02:18 INFO - PROCESS | 1925 | __metrics 03:02:18 INFO - PROCESS | 1925 | 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" 03:02:18 INFO - PROCESS | 1925 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:02:19 INFO - TEST-INFO | 1925: exit 0 03:02:19 INFO - Browser initialized. 03:02:19 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:19 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpgvLnxA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:31 INFO - PROCESS | 1928 | RSS: Main: 222605312 03:02:31 INFO - PROCESS | 1928 | 03:03:22 INFO - PROCESS | 1928 | 1482404602106 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:05:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55656/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55656/tests/dromaeo/cssquery-ext.html) 03:05:38 INFO - PROCESS | 1928 | RSS: Main: 454717440 03:05:38 INFO - PROCESS | 1928 | 03:08:41 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55656/tests/dromaeo/cssquery-ext.html (next: http://localhost:55656/tests/dromaeo/cssquery-jquery.html) 03:08:42 INFO - PROCESS | 1928 | RSS: Main: 348557312 03:08:42 INFO - PROCESS | 1928 | 03:11:44 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55656/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55656/tests/dromaeo/cssquery-mootools.html) 03:11:44 INFO - PROCESS | 1928 | RSS: Main: 315527168 03:11:44 INFO - PROCESS | 1928 | 03:14:47 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55656/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55656/tests/dromaeo/cssquery-prototype.html) 03:14:47 INFO - PROCESS | 1928 | RSS: Main: 332771328 03:14:47 INFO - PROCESS | 1928 | 03:17:51 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55656/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55656/tests/dromaeo/cssquery-yui.html) 03:17:51 INFO - PROCESS | 1928 | RSS: Main: 309760000 03:17:51 INFO - PROCESS | 1928 | 03:20:54 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55656/tests/dromaeo/cssquery-yui.html (next: http://localhost:55656/tests/dromaeo/cssquery-dojo.html) 03:20:55 INFO - PROCESS | 1928 | RSS: Main: 366886912 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | __start_tp_report 03:20:55 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 03:20:55 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 03:20:55 INFO - PROCESS | 1928 | |i|pagename|runs| 03:20:55 INFO - PROCESS | 1928 | |0;dojo.html;3034.575159477673;3331.550240717503;3353.9496907546404;3370.8820191293294;3420.914477138073;5653.236813030245;5703.800366987156;5704.401037891024;5736.684482353474;5745.109508026252;5621.353544342402;5637.210790489335;5731.254936858213;5737.627054241471;5738.167965644979;4253.638440732541;5182.974085129569;5186.948130518703;5209.661372010826;5242.528172464477;5206.088934436478;5209.583233341343;5223.947760522403;5298.814541491049;5346.518813306801;5218.1650935850275;5251.711155886424;5262.236975638531;5276.208568714705;5293.86765330866;4347.174036933005;5238.973805130969;5250.501202385767;5263.157894736862;5360.812371567015;5181.559567436772;5226.033183860998;5255.527002569749;5256.211568264753;5266.815661451831;4635.6523260755575;4708;5597.804076857331;5630.8029218977545;5638.295213098363;6127.311699317065;6161.876762464771;6170.074488826712;6176.382361763833;6186.164867742842;6254.312025677172;6261.624302541816;6266.49868010558;6268.937310626927;6269.780557680459;5673.517750991129;5720.199172115824;5745.109508026263;5762.855928601776;5811.099279611667;6230.408111229426;6235.407636274546;6236.438720515084;6244.2819075805955;6244.781432649836;6143.815685529441;6148.8770224595255;6155.938440615537;6160.260768707783;6183.721732522047;4989.90020199594;6079.118527813531;6167.414095660905;6247.875042499125;6262.342454042351;6106.460400469315;6147.293061297919;6204.751809927552;6235.033569796688;6237.220347456568;5177.611679124081;6244.781432649836;6257.405546473078;6257.8748425031245;6275.02737075754;4911.852644420673;4918.975405122951;4942.283368911488;4951.752412379438;4966.8261610844165;4366.410534577792;4397.780110994438;4404.801783919731;4434.889127771831;4437.179121862466;5772.047612144033;5828;5834.27071616048;5869.2956845178;5871.148683440877;6216.44052035319;6220.34686357935;6232.376762323822;6236.563440559208;6251.687415629201;4908.288298196741;5576.693281869536;5641.746121424546;5738.311402631711;5788.652680839163;5355.812546560929;5389.299391079135;5392.595555333287;5403.13549832025;5470.835874923679;6302.464290535172;6305.684715764285;6309.49524038085;6310.936890631035;6314.368563143833;6125.876495711566;6194.4115309045565;6213;6218.62688238689;6231.875362492816;6074.301455332693;6107.450329470369;6208.2860471044605;6230.345813689679;6246.968765156145;6137.938620613737;6197.628142311476;6202.968985155046;6214.533909956862;6218.253809542884;5629.915551266652;6167.938320616916;6182.196314479269;6245.313015568375;6251.812445626639;5971.850703732268;6057.606255593372;6080.878382432238;6109.6639684817765;6138.806454026249;6077.270727512727;6086.421789929949;6089.604175728564;6089.665068421279;6097.451229389376;5912.793052243151;5918.260217472816;5939.049752039653;5943.732532035982;5965.970170149121;6010.549208809444;6073.240844894388;6094.54290928191;6099.542534309856;6111.1444397785235;5891.23413956183;5893.057110862242;5906.497947674324;5912.526997840077;5914.645121292736 03:20:55 INFO - PROCESS | 1928 | |1;ext.html;463.2264118921401;495.5936132371456;508.97187680884605;522.514061922412;535.0556268186649;30817.845910770444;40044.79977600111;41339.793301033525;41349.759507214796;42963;41853.58146418535;42466.787666061704;42596.36105458421;42620.57379426205;42770.572294277044;9344.345895787297;9420.576074076665;9550.952245238765;9557.13985741283;9578;7974.521528708267;9501.71494855152;9566.617335306579;9569.33014688972;9576.904230957705;9389.67136150235;9443.244540436748;9450.385724927892;9467.526623668824;9498.19265362445;9431;9513.667021654244;9560.856587151198;9566.18687411564;9650.131488166098;9399.34204605678;9445.244380449547;9445.338826282163;9446.338756287063;9446.433214007182;40223;40392;40405.79797101025;40433.98915027118;40539.39190912139;3260.2175477885135;3309.3050459403553;3331.716804071657;3341.983290083534;3728.5339332583426;28951.56572651412;29078;30490.695093049006;30930.226744331347;30998.225044373845;2956.39393924245;3001.864916078782;3133.4673105572106;3146.905592832196;3158.115727596277;507649;512607;512934;513281;522551;63047.684761575896;63673.363266366745;63696.36303636997;63753.72492550123;63826.36173638297;34498.48252276218;34671.65328346684;35371.11572210664;36186;67625;16155.676886462204;16216.189190540426;16216.351345946267;16240.51278461648;16269.51191464258;12435.191712538655;12501.687457813627;12504.499820007099;12536.623901282976;12573.37133143339;19179.041047947547;19310.420687379403;19354.903225483784;19394.418167455;19443.902780486005;7721.884171737428;7851.568163751049;7861.724839630586;7875.6062196889925;7927;741.1958025542347;978.5419724610422;989.8465737810651;995.5669283861544;1039.667306461925;28250.576241356397;30242;30305;30478.39043219123;30826.53760193599;990.559201155479;1014.1886490807325;1022.9693109206736;1087.3367245979946;1138.6868611132004;13787.51743688966;15626.68746625061;15869.047857128378;15902.522924312289;16138.435154769762;676701;681500;682467;683063;683282;59152.7042364794;59552.426854388876;59609;59609.403905961255;59683.701581492685;1251.6870782304425;1296.5267677297907;1298.0589072922244;1397.77635578307;1473.9631509212356;28845;31369.8431507841;31777.523337149498;31841.840790795897;32422.83788581042;50528.24207636962;51296.74351628218;51338.74330628323;51414.22878656748;51462.970740585726;614849;623867;624846;626954;627455;36157.91526254216;37015.483529064695;37624;43449.3482597755;44187.11625767531;38588;40816.59183408128;45632.54367456283;46625.766871165426;47395.05209895852;37983.430248545745;38618.80690596529;38909;40293.79118626446;40522.18955620812;36719.714809981524;38664.226715466095;38769.4184587237;40093;40200.39699404453;36841.71054013097;38106.85679429622;38679.80660096682;38681.613183868925;38741.806290968365;32369;34590.404357845655;35054.29891402107;35170.12074698153;35346;22070.10869293855;25597.616035759107;25605.48789024172;25642.102526412233;26005.869970650783 03:20:55 INFO - PROCESS | 1928 | |2;jquery.html;316.5236319339394;365.5229924947942;370.5682879445447;389.2060197197717;400.8877514295998;18462.53843653908;19195.32816351428;19765.604687906234;20047.59904801903;20312.796872031275;20080.799192008075;20364.8981755091;20397;20671.86304753238;20700.861452620098;5720.513756330717;5762.56780741445;5993.670348130851;6017.097435384705;6019.097135429677;5890.705464726768;6045.183900173486;6045.848853778669;6052.818415447544;6061.212042434478;5883.146943693162;6048.637081775108;6054.091886217081;6570.1130347403205;6660.76687315944;6664.766733164341;6694.263631000586;6706.798796036127;6707.765228217014;6707.832304192385;5186.948130518685;5464.289642346509;5513.807016754435;5552.444755524456;5558.972205138989;13126;13425.66435839112;17509.7373539397;24783.380415489577;25100.49799004028;2261.694671219381;2353.5881220786464;2360.1267531013636;2612.1118819601425;2769.6399468069226;9761.414315141114;9887.752806179904;9988.350757200831;10173.898261017443;15133.319000644888;1714.3571160814695;1793.3454289184485;1828.9300759055968;1851.4908400190056;1864.5711486358077;121138.1260218083;121656;126410;131930.6806931928;134152.999999999;11137.944310278397;11501.424928753697;11530.94234528822;11547.422628868691;11750.177487575958;14884.925575372054;14886.255687215596;14886.330115144845;14920.253987300592;14937;8198;8425.747227583182;8499.66001359951;8599.441036332744;8648.394612377073;6923.6192009439965;8400.117987611213;8400.957995210107;8413.326933845277;8579.270761985177;4092.570280120604;4405.757683327383;4485.977570112129;4505.414296141481;4575.313702944519;1716.8712346574057;1739.2868923741205;1876.1557299215408;1887.6224755049045;1892.6720134234693;708.2386434582844;749.4304328710219;792.338397438134;849.1720572441819;890.8262888736764;19666.803331966785;21039.474013149793;21528.569428611652;21582.460438488848;21638.35084947454;881.1805021330224;926.666400095965;952.5951470624957;955.4553904274496;955.8422860227985;9266.258699304039;10612.840807387895;11324.433778311051;11327.546898123983;11359.977602015859;618050;632674;635198.824005877;645105;648176;91738;126176;126651.74129353234;134358;137636.31181844228;822.7120507822215;915.1763412928285;946.0917519181526;1021.1320377679062;1024.4826362686795;18049.72925406146;18945.33691320797;19612.215511379956;19634.410967670992;19663.41009769709;53726.731366342916;55103.724481377336;55720.885582287316;55852.16221756758;56299.71850140723;67887;80644;82308.17691823005;83584;84419;7098.716051357887;7398.81515407971;7495.550266983998;7725.956995805494;7912.36701063925;11165.832512512481;12970.416331265304;13037.739245214852;13477.19136851792;13561.389737462036;9131.634734610541;11316.83024754613;11569.363684997406;11699.88300117022;11826.994705450134;5139.357580302462;7880.566568838769;10236.436995965294;10263.486825658836;10945.398003109905;9599.664011759554;11369.715757106138;11444.370559618968;11794.174407791716;11878.406079695807;6900.309969003059;7152.320529549685;7381;7541.622918854145;7827.099883513469;9586.376885502421;11270.94364528172;11322.433878305887;11672.824907626562;11841.763164736829 03:20:55 INFO - PROCESS | 1928 | |3;mootools.html;1299.2334522631647;1457.8687918087373;1592.800899887514;1760.8327208915148;1832.2304632054536;8047.758567242985;8483.957580212098;8484.363672724547;8487.981442226941;8501.022382426023;7423.369013633834;7644.350230230423;8080.6767729290905;8330;8345.290650294732;2302.435903203713;2339.181286549711;2339.543788961153;2340.602097643396;2343.7773411525877;2243.730752309717;2274.9772502275014;2279.3048120323297;2281.178775640776;2281.783230593091;2263.3323169664905;2270.14869423966;2291.2438895164637;2295.747467778543;2315.9652605210936;2257.221258665758;2267.489814791675;2270.5345404192103;2275.226423016166;2275.2946586558137;2325.802306803924;2348.0842471436154;2349.9647505287435;2351.823613229015;2361.6339467382413;8350.540720260384;8420.368472364597;8421.326293896474;8443.15568443157;8443.746687599381;5050.368703912011;5226.764795584208;5234.319538460014;5237.4500677428705;5239.554637855748;3197.8560964756643;3264.363449127421;3517.6482351764876;3529.6823285904134;3530.9117272068147;990.4107056301489;1053.6312290698195;1056.75166335911;1075.473018221066;1091.1325496230436;10790.5683772649;10877;10891.782164356668;10934.617288394868;10949.124070074455;4534.319852022223;4606.838760643362;4678.111158879802;4686.95313046872;4797.304390863305;9438.150566449052;9438.764030899283;9446;9451.716448506555;9454.385464944757;5194.35070616173;5201.479852014844;5203.089459344599;5207.0887594670785;5217.50433708797;4259.9787001065415;4395.274779661384;4585.70192937458;4701.553352431513;4769.713817170981;3769.208466222069;3786.356319425649;3878.9224215515533;3888.2223555289056;3932.134930315326;1243.2726854790048;1249.575144450891;1250.6310638361706;1268.4355461819403;1273.5542560104075;877.4252864373718;879.9120087991149;889.644142343068;895.2166854002747;903.2773780975193;5422.593305502024;5587.636803607753;5674.177244299636;5729.570282228766;5773.826785196451;334.49157280932843;385.77432202161486;452.7577745905925;457.09039012365156;458.91510070637037;3778.0932576181517;3786.4888240087785;3864.2271545690414;3881.262560113626;3899.3760998240145;9959.203263738884;10112.342697724624;10193.796124077477;10209.897901021044;10233.283670143019;10161.18710503158;10168.33905796121;10168.389896606228;10180.880103188643;10196.643117490852;7405.8148546286775;7686.846263074707;7749.2638199372095;7913;7918;5547.862688148839;5645.858853528694;5680.914786278292;5797.101449275369;5917.053271476543;8417.242448179448;9181.862272065791;9232.21526170283;9247.72256832296;9399.436033837992;9426.622935082794;9462;9486.857697134687;9540.284478663989;9551.42691438516;6629.900551491635;6850.73261446614;6855.314468553105;7192.676329565077;7340.412766978736;7629.885551716618;7901.288884000468;7949.8410031797885;7959.602019898866;8051.597420129087;7028.50800443964;7167.498275120692;7228.96385518069;7239.167495738057;7291.34377905991;6955.478339124485;7038.894416583653;7062.505624606229;7138.214796372498;7181.676824542804;6331.986882098842;6369.108324834434;6464.159659244268;6470.320616335405;6473;4806.44725856517;4817.759112044314;4836.395450568679;4858.368412106403;4902.509749025069;4782.354382158364;4797.112534181188;4882.170031094794;4964.726940018334;4989.800407983786 03:20:55 INFO - PROCESS | 1928 | |4;prototype.html;293.3399850336742;298.3749045749155;299.40567972574433;308.34322892239527;311.3539405733104;4377.956220437794;4464.709793863397;4586.816527338907;5191.5327620514145;5380.139177731564;4944.3794788761925;4958.35541379621;5091.949080509194;5140.640155189139;5144.356955380577;4000.0999775050673;4035.3946907963746;4037.253108174982;4041.0503531670033;4087.3255912774357;3324.268660894599;3354.85934782174;3476.8783092591766;4068.1456894052285;4079.2045551117544;3212.9196770080816;3994.6005399460114;3999.3800960851113;4015.698822588303;4043.4541336919433;3309.635940046593;4025.81883815229;4042.8584999525024;4050.8860063482484;4061.0659548304056;2647.7026257134057;2946.7789915756402;3456.585209774818;3545.3514115935955;3554.751167418282;4828.589569886564;4991.850244492671;5050.621203409759;5063.8987220255385;5070.29015937769;3818.6563209311016;4510.661700372452;4569.451665800092;4583.2666773316105;4589.403377560896;4547.499775024745;5461.453854614547;5488.313960754906;5504.256925315071;5507.697076660782;3220.888793366285;3817.7709337439564;3855.7879316637573;3857.286402015636;3863.74885632433;5455.890882182334;5705.834740639357;5724.8855022899725;5770.740316685759;5799.88400231993;3152.511360739089;3609.639036096369;3900.3726534322786;3913.96086039136;3924.4498423123277;3448.8620455182013;3862.1117143057254;3982.7411218270718;3991.6806655467494;3992.8203230854683;4162.895927601774;4346.326319420495;4552.795124219418;4625.7687115644085;4633.305004249322;4411.09572537629;4868.634852386085;5147.948520514747;5536.920300541356;5585.497305242548;1074.4305518075432;1152.7895709504987;1225.4669218889812;1288.15625765124;1292.560529419983;751.6993202718957;827.9213474719892;843.8143608406141;860.0539406652632;880.7181701855473;4218.535961044283;4223.72545784523;4259.1268789897995;4756.025014871943;4990.476000020019;4276.978615106905;4909.288153217834;5257;5460.372057213392;5481.2874326338115;4648.58162765346;4784.497627749106;4813.507812578042;4824.155772739757;4889.511048895153;2633.499635069369;2783.3180870686406;2785.3036740814796;2789.3445040415486;2868.885244590193;5221.7128057956415;5309.522143007148;5537.335519737658;5577.27495425592;5613.831585052455;4539.931901021488;4736.3369128322065;5184.911168654549;5405.540529054996;5447.455254474521;4687.414073240845;4890.608751299887;5111.192161839454;5478.890422191614;5483.669440487856;5017.971315880162;5165.509276618715;5264.972640731671;5331.9466805333;5386.59600529954;4386.223579450328;4609.838655647036;4725.8582242532775;5232.273349794635;5408.78364865401;5179;5180.497655679961;5198.844034678966;5365.597580181424;5648.971755141198;3110.538047117851;3460.117669994135;3591.425371940477;3738.513993180869;3751.8124093795745;4774.665773395968;4813.614910807057;5347.7593508293;5348.572114230775;5374;4410.603045725772;5057.418396884405;5111.769970351269;5116.7441627917715;5153.577818425334;4308.116836048665;4386.451693538308;4976.751162441936;5081.466446023114;5148.473295792394;4798.224656876839;5146.922796157985;5301.807093403953;5391.7304134793885;5443.945560544502;4518;4615.446146462312;4627.67606267558;5404.459554044564;5443.755031023693;5392.379876314274;5431.582945589175;5439.782408703766;5451.465068743054;5515.586331025109 03:20:55 INFO - PROCESS | 1928 | |5;yui.html;684.5755631508465;706.5972395734431;712.5724565260842;749.2170681637688;775.2712450296721;14006.229657368853;14511.564653060399;14517.419303227858;14520.564383068511;14565.490207842715;12968.935155324234;13024.934875325634;13031.60905172844;13094.345282735872;13154.671133221651;648.3160265919465;672.5124284893462;676.4486943141324;804.7545498622918;831.9209675080888;820.7948012996751;824.8597738384489;829.875518672201;834.4200780457554;835.4778263585259;886.4858382138376;887.6316328723572;889.3374436045156;890.7728529225038;892.2282225874636;686.8557602903396;878.3193025405299;879.9692010779656;880.2561835249236;880.3749337970049;522.7908836465406;633.1547384242042;649.7336092202222;650.8372906773307;656.5929123943138;13941.651458713512;13985.650358741113;14000.369983350673;14010.439582416691;14078.436862525487;12804.679883002906;13007.959363250917;13024.004478969795;13060.477580896753;13154.671133221651;10727.517261723242;11118.777624447546;11124.721881953015;11846.052315814713;11913.225640333438;3907.745996510246;4087.652549533293;4344.717593356421;4362.236608593484;4521;81487.7776833348;82100.35799283981;82986;83801.74297385543;84059.8985025367;9998.950005250073;10016.14862736661;10247.59009639621;10300.742481438025;10344.741381465523;15685.372585096697;18773.24907003732;18815.71776423355;18853;18936.90531547361;7242.456815738736;7248.383887369527;7321.267873212636;7428.220036896156;7471.589062601501;4144.849914065332;4165.875023749292;4189.183109293658;4218.7468751874985;4291.721038132574;3295.6210035845706;3405.1487128217946;3416.4533674611944;3417.1457135716073;3424.5719285089363;585.4643001653525;586.416515567005;587.5094296262583;593.4984937727613;602.9698515074228;529.7774934527507;547.5017733862167;589.8318979090939;644.3459888213467;674.5480528046221;8661.610227539775;8901.065388134282;8938.703887936343;9064.954675226581;9481.383710058824;604.0882944208959;658.1739916404977;658.9472842172614;677.8305423644089;690.1614538335946;3890.513685789276;4458.665600080007;4491.573300536444;4513.729176249435;4617.376654151713;75881.62059189669;109325.45337273263;110009;111995;115103.2734508983;28263.717362826108;34766.82616586901;89007;99496.50251748695;101846;66300.6684966572;90624.5468772665;91769.0823091774;92489.61265581156;92666.53666731624;7465.365443937326;8587.484750914966;8595.140485951355;8822.911770882209;8833.558322083998;43787.34318985154;53599.19601206063;55262.72368638131;56577.71711141583;57835.421645783004;77404.2259577397;79624;82340;82559.17440825755;83023;10711.410872402093;11141.554337826396;11175.21773475849;11316.26444281119;11423.714407139889;12001.579944701894;12177.33024683651;12205.69485762863;12242.143049986415;12437.315947622968;9927.354721943051;11199.440027998731;12553.058520610808;12736.108472406842;13198.14212076212;12841.873859176783;13018.674533136747;13435.395407206504;13490.86509134896;13768.65578360549;11816.291022538331;12028.332517458051;12079.939600301943;12144.33206173669;12698.36508174606;11012.889871101186;11213.439328033728;11264.718382040515;11573.421328933688;11602.825957610474;10909.34543927367;11066.446677666245;11068;11140;11528.711782205512 03:20:55 INFO - PROCESS | 1928 | __end_tp_report 03:20:55 INFO - PROCESS | 1928 | __start_cc_report 03:20:55 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,3035 03:20:55 INFO - PROCESS | 1928 | __end_cc_report 03:20:55 INFO - PROCESS | 1928 | __startTimestamp1482405655219__endTimestamp 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | ------- Summary: start ------- 03:20:55 INFO - PROCESS | 1928 | Number of tests: 6 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:5695.33 Median:5912.79 stddev:663.06 (11.2%) stddev-sans-first:631.38 03:20:55 INFO - PROCESS | 1928 | Values: 3034.6 3331.6 3353.9 3370.9 3420.9 5653.2 5703.8 5704.4 5736.7 5745.1 5621.4 5637.2 5731.3 5737.6 5738.2 4253.6 5183.0 5186.9 5209.7 5242.5 5206.1 5209.6 5223.9 5298.8 5346.5 5218.2 5251.7 5262.2 5276.2 5293.9 4347.2 5239.0 5250.5 5263.2 5360.8 5181.6 5226.0 5255.5 5256.2 5266.8 4635.7 4708.0 5597.8 5630.8 5638.3 6127.3 6161.9 6170.1 6176.4 6186.2 6254.3 6261.6 6266.5 6268.9 6269.8 5673.5 5720.2 5745.1 5762.9 5811.1 6230.4 6235.4 6236.4 6244.3 6244.8 6143.8 6148.9 6155.9 6160.3 6183.7 4989.9 6079.1 6167.4 6247.9 6262.3 6106.5 6147.3 6204.8 6235.0 6237.2 5177.6 6244.8 6257.4 6257.9 6275.0 4911.9 4919.0 4942.3 4951.8 4966.8 4366.4 4397.8 4404.8 4434.9 4437.2 5772.0 5828.0 5834.3 5869.3 5871.1 6216.4 6220.3 6232.4 6236.6 6251.7 4908.3 5576.7 5641.7 5738.3 5788.7 5355.8 5389.3 5392.6 5403.1 5470.8 6302.5 6305.7 6309.5 6310.9 6314.4 6125.9 6194.4 6213.0 6218.6 6231.9 6074.3 6107.5 6208.3 6230.3 6247.0 6137.9 6197.6 6203.0 6214.5 6218.3 5629.9 6167.9 6182.2 6245.3 6251.8 5971.9 6057.6 6080.9 6109.7 6138.8 6077.3 6086.4 6089.6 6089.7 6097.5 5912.8 5918.3 5939.0 5943.7 5966.0 6010.5 6073.2 6094.5 6099.5 6111.1 5891.2 5893.1 5906.5 5912.5 5914.6 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:73237.20 Median:40398.90 stddev:163366.40 (404.4%) stddev-sans-first:163733.32 03:20:55 INFO - PROCESS | 1928 | Values: 463.2 495.6 509.0 522.5 535.1 30817.8 40044.8 41339.8 41349.8 42963.0 41853.6 42466.8 42596.4 42620.6 42770.6 9344.3 9420.6 9551.0 9557.1 9578.0 7974.5 9501.7 9566.6 9569.3 9576.9 9389.7 9443.2 9450.4 9467.5 9498.2 9431.0 9513.7 9560.9 9566.2 9650.1 9399.3 9445.2 9445.3 9446.3 9446.4 40223.0 40392.0 40405.8 40434.0 40539.4 3260.2 3309.3 3331.7 3342.0 3728.5 28951.6 29078.0 30490.7 30930.2 30998.2 2956.4 3001.9 3133.5 3146.9 3158.1 507649.0 512607.0 512934.0 513281.0 522551.0 63047.7 63673.4 63696.4 63753.7 63826.4 34498.5 34671.7 35371.1 36186.0 67625.0 16155.7 16216.2 16216.4 16240.5 16269.5 12435.2 12501.7 12504.5 12536.6 12573.4 19179.0 19310.4 19354.9 19394.4 19443.9 7721.9 7851.6 7861.7 7875.6 7927.0 741.2 978.5 989.8 995.6 1039.7 28250.6 30242.0 30305.0 30478.4 30826.5 990.6 1014.2 1023.0 1087.3 1138.7 13787.5 15626.7 15869.0 15902.5 16138.4 676701.0 681500.0 682467.0 683063.0 683282.0 59152.7 59552.4 59609.0 59609.4 59683.7 1251.7 1296.5 1298.1 1397.8 1474.0 28845.0 31369.8 31777.5 31841.8 32422.8 50528.2 51296.7 51338.7 51414.2 51463.0 614849.0 623867.0 624846.0 626954.0 627455.0 36157.9 37015.5 37624.0 43449.3 44187.1 38588.0 40816.6 45632.5 46625.8 47395.1 37983.4 38618.8 38909.0 40293.8 40522.2 36719.7 38664.2 38769.4 40093.0 40200.4 36841.7 38106.9 38679.8 38681.6 38741.8 32369.0 34590.4 35054.3 35170.1 35346.0 22070.1 25597.6 25605.5 25642.1 26005.9 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:36086.95 Median:395.05 stddev:106009.32 (26834.6%) stddev-sans-first:106272.68 03:20:55 INFO - PROCESS | 1928 | Values: 316.5 365.5 370.6 389.2 400.9 18462.5 19195.3 19765.6 20047.6 20312.8 20080.8 20364.9 20397.0 20671.9 20700.9 5720.5 5762.6 5993.7 6017.1 6019.1 5890.7 6045.2 6045.8 6052.8 6061.2 5883.1 6048.6 6054.1 6570.1 6660.8 6664.8 6694.3 6706.8 6707.8 6707.8 5186.9 5464.3 5513.8 5552.4 5559.0 13126.0 13425.7 17509.7 24783.4 25100.5 2261.7 2353.6 2360.1 2612.1 2769.6 9761.4 9887.8 9988.4 10173.9 15133.3 1714.4 1793.3 1828.9 1851.5 1864.6 121138.1 121656.0 126410.0 131930.7 134153.0 11137.9 11501.4 11530.9 11547.4 11750.2 14884.9 14886.3 14886.3 14920.3 14937.0 8198.0 8425.7 8499.7 8599.4 8648.4 6923.6 8400.1 8401.0 8413.3 8579.3 4092.6 4405.8 4486.0 4505.4 4575.3 1716.9 1739.3 1876.2 1887.6 1892.7 708.2 749.4 792.3 849.2 890.8 19666.8 21039.5 21528.6 21582.5 21638.4 881.2 926.7 952.6 955.5 955.8 9266.3 10612.8 11324.4 11327.5 11360.0 618050.0 632674.0 635198.8 645105.0 648176.0 91738.0 126176.0 126651.7 134358.0 137636.3 822.7 915.2 946.1 1021.1 1024.5 18049.7 18945.3 19612.2 19634.4 19663.4 53726.7 55103.7 55720.9 55852.2 56299.7 67887.0 80644.0 82308.2 83584.0 84419.0 7098.7 7398.8 7495.6 7726.0 7912.4 11165.8 12970.4 13037.7 13477.2 13561.4 9131.6 11316.8 11569.4 11699.9 11827.0 5139.4 7880.6 10236.4 10263.5 10945.4 9599.7 11369.7 11444.4 11794.2 11878.4 6900.3 7152.3 7381.0 7541.6 7827.1 9586.4 11270.9 11322.4 11672.8 11841.8 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5439.49 Median:4933.62 stddev:3005.07 (60.9%) stddev-sans-first:2997.39 03:20:55 INFO - PROCESS | 1928 | Values: 1299.2 1457.9 1592.8 1760.8 1832.2 8047.8 8484.0 8484.4 8488.0 8501.0 7423.4 7644.4 8080.7 8330.0 8345.3 2302.4 2339.2 2339.5 2340.6 2343.8 2243.7 2275.0 2279.3 2281.2 2281.8 2263.3 2270.1 2291.2 2295.7 2316.0 2257.2 2267.5 2270.5 2275.2 2275.3 2325.8 2348.1 2350.0 2351.8 2361.6 8350.5 8420.4 8421.3 8443.2 8443.7 5050.4 5226.8 5234.3 5237.5 5239.6 3197.9 3264.4 3517.6 3529.7 3530.9 990.4 1053.6 1056.8 1075.5 1091.1 10790.6 10877.0 10891.8 10934.6 10949.1 4534.3 4606.8 4678.1 4687.0 4797.3 9438.2 9438.8 9446.0 9451.7 9454.4 5194.4 5201.5 5203.1 5207.1 5217.5 4260.0 4395.3 4585.7 4701.6 4769.7 3769.2 3786.4 3878.9 3888.2 3932.1 1243.3 1249.6 1250.6 1268.4 1273.6 877.4 879.9 889.6 895.2 903.3 5422.6 5587.6 5674.2 5729.6 5773.8 334.5 385.8 452.8 457.1 458.9 3778.1 3786.5 3864.2 3881.3 3899.4 9959.2 10112.3 10193.8 10209.9 10233.3 10161.2 10168.3 10168.4 10180.9 10196.6 7405.8 7686.8 7749.3 7913.0 7918.0 5547.9 5645.9 5680.9 5797.1 5917.1 8417.2 9181.9 9232.2 9247.7 9399.4 9426.6 9462.0 9486.9 9540.3 9551.4 6629.9 6850.7 6855.3 7192.7 7340.4 7629.9 7901.3 7949.8 7959.6 8051.6 7028.5 7167.5 7229.0 7239.2 7291.3 6955.5 7038.9 7062.5 7138.2 7181.7 6332.0 6369.1 6464.2 6470.3 6473.0 4806.4 4817.8 4836.4 4858.4 4902.5 4782.4 4797.1 4882.2 4964.7 4989.8 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4287.10 Median:4731.10 stddev:1300.75 (27.5%) stddev-sans-first:1269.39 03:20:55 INFO - PROCESS | 1928 | Values: 293.3 298.4 299.4 308.3 311.4 4378.0 4464.7 4586.8 5191.5 5380.1 4944.4 4958.4 5091.9 5140.6 5144.4 4000.1 4035.4 4037.3 4041.1 4087.3 3324.3 3354.9 3476.9 4068.1 4079.2 3212.9 3994.6 3999.4 4015.7 4043.5 3309.6 4025.8 4042.9 4050.9 4061.1 2647.7 2946.8 3456.6 3545.4 3554.8 4828.6 4991.9 5050.6 5063.9 5070.3 3818.7 4510.7 4569.5 4583.3 4589.4 4547.5 5461.5 5488.3 5504.3 5507.7 3220.9 3817.8 3855.8 3857.3 3863.7 5455.9 5705.8 5724.9 5770.7 5799.9 3152.5 3609.6 3900.4 3914.0 3924.4 3448.9 3862.1 3982.7 3991.7 3992.8 4162.9 4346.3 4552.8 4625.8 4633.3 4411.1 4868.6 5147.9 5536.9 5585.5 1074.4 1152.8 1225.5 1288.2 1292.6 751.7 827.9 843.8 860.1 880.7 4218.5 4223.7 4259.1 4756.0 4990.5 4277.0 4909.3 5257.0 5460.4 5481.3 4648.6 4784.5 4813.5 4824.2 4889.5 2633.5 2783.3 2785.3 2789.3 2868.9 5221.7 5309.5 5537.3 5577.3 5613.8 4539.9 4736.3 5184.9 5405.5 5447.5 4687.4 4890.6 5111.2 5478.9 5483.7 5018.0 5165.5 5265.0 5331.9 5386.6 4386.2 4609.8 4725.9 5232.3 5408.8 5179.0 5180.5 5198.8 5365.6 5649.0 3110.5 3460.1 3591.4 3738.5 3751.8 4774.7 4813.6 5347.8 5348.6 5374.0 4410.6 5057.4 5111.8 5116.7 5153.6 4308.1 4386.5 4976.8 5081.5 5148.5 4798.2 5146.9 5301.8 5391.7 5443.9 4518.0 4615.4 4627.7 5404.5 5443.8 5392.4 5431.6 5439.8 5451.5 5515.6 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:19560.79 Median:24123.00 stddev:28908.22 (119.8%) stddev-sans-first:28954.57 03:20:55 INFO - PROCESS | 1928 | Values: 684.6 706.6 712.6 749.2 775.3 14006.2 14511.6 14517.4 14520.6 14565.5 12968.9 13024.9 13031.6 13094.3 13154.7 648.3 672.5 676.4 804.8 831.9 820.8 824.9 829.9 834.4 835.5 886.5 887.6 889.3 890.8 892.2 686.9 878.3 880.0 880.3 880.4 522.8 633.2 649.7 650.8 656.6 13941.7 13985.7 14000.4 14010.4 14078.4 12804.7 13008.0 13024.0 13060.5 13154.7 10727.5 11118.8 11124.7 11846.1 11913.2 3907.7 4087.7 4344.7 4362.2 4521.0 81487.8 82100.4 82986.0 83801.7 84059.9 9999.0 10016.1 10247.6 10300.7 10344.7 15685.4 18773.2 18815.7 18853.0 18936.9 7242.5 7248.4 7321.3 7428.2 7471.6 4144.8 4165.9 4189.2 4218.7 4291.7 3295.6 3405.1 3416.5 3417.1 3424.6 585.5 586.4 587.5 593.5 603.0 529.8 547.5 589.8 644.3 674.5 8661.6 8901.1 8938.7 9065.0 9481.4 604.1 658.2 658.9 677.8 690.2 3890.5 4458.7 4491.6 4513.7 4617.4 75881.6 109325.5 110009.0 111995.0 115103.3 28263.7 34766.8 89007.0 99496.5 101846.0 66300.7 90624.5 91769.1 92489.6 92666.5 7465.4 8587.5 8595.1 8822.9 8833.6 43787.3 53599.2 55262.7 56577.7 57835.4 77404.2 79624.0 82340.0 82559.2 83023.0 10711.4 11141.6 11175.2 11316.3 11423.7 12001.6 12177.3 12205.7 12242.1 12437.3 9927.4 11199.4 12553.1 12736.1 13198.1 12841.9 13018.7 13435.4 13490.9 13768.7 11816.3 12028.3 12079.9 12144.3 12698.4 11012.9 11213.4 11264.7 11573.4 11602.8 10909.3 11066.4 11068.0 11140.0 11528.7 03:20:55 INFO - PROCESS | 1928 | -------- Summary: end -------- 03:20:55 INFO - PROCESS | 1928 | 03:20:55 INFO - TEST-INFO | 1928: exit 0 03:20:55 INFO - TEST-OK | dromaeo_css | took 1120474ms 03:20:55 INFO - TEST-START | kraken 03:20:55 INFO - operating with platform_type : mac_ 03:20:55 INFO - Initialising browser for kraken test... 03:20:55 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpqFswtF/profile http://localhost:55656/getInfo.html) 03:20:58 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 03:20:58 INFO - PROCESS | 1932 | colorDepth:24 03:20:58 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 03:20:58 INFO - PROCESS | 1932 | __metrics 03:20:58 INFO - PROCESS | 1932 | 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" 03:20:58 INFO - PROCESS | 1932 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:20:59 INFO - TEST-INFO | 1932: exit 0 03:20:59 INFO - Browser initialized. 03:20:59 INFO - Running cycle 1/1 for kraken test... 03:20:59 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpqFswtF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:11 INFO - PROCESS | 1934 | RSS: Main: 222425088 03:21:11 INFO - PROCESS | 1934 | 03:21:14 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55656/tests/kraken/driver.html?testName=audio-beat-detection) 03:21:15 INFO - PROCESS | 1934 | RSS: Main: 272543744 03:21:15 INFO - PROCESS | 1934 | 03:21:18 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55656/tests/kraken/driver.html?testName=audio-dft) 03:21:18 INFO - PROCESS | 1934 | RSS: Main: 283193344 03:21:18 INFO - PROCESS | 1934 | 03:21:21 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55656/tests/kraken/driver.html?testName=audio-fft) 03:21:22 INFO - PROCESS | 1934 | RSS: Main: 380399616 03:21:22 INFO - PROCESS | 1934 | 03:21:24 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55656/tests/kraken/driver.html?testName=audio-oscillator) 03:21:25 INFO - PROCESS | 1934 | RSS: Main: 282251264 03:21:25 INFO - PROCESS | 1934 | 03:21:27 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55656/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:21:27 INFO - PROCESS | 1934 | RSS: Main: 284684288 03:21:27 INFO - PROCESS | 1934 | 03:21:30 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55656/tests/kraken/driver.html?testName=imaging-darkroom) 03:21:31 INFO - PROCESS | 1934 | RSS: Main: 325308416 03:21:31 INFO - PROCESS | 1934 | 03:21:34 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55656/tests/kraken/driver.html?testName=imaging-desaturate) 03:21:34 INFO - PROCESS | 1934 | RSS: Main: 326455296 03:21:34 INFO - PROCESS | 1934 | 03:21:37 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55656/tests/kraken/driver.html?testName=json-parse-financial) 03:21:38 INFO - PROCESS | 1934 | RSS: Main: 325292032 03:21:38 INFO - PROCESS | 1934 | 03:21:39 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55656/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:21:40 INFO - PROCESS | 1934 | RSS: Main: 299130880 03:21:40 INFO - PROCESS | 1934 | 03:21:42 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:21:42 INFO - PROCESS | 1934 | RSS: Main: 302309376 03:21:42 INFO - PROCESS | 1934 | 03:21:45 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:21:45 INFO - PROCESS | 1934 | RSS: Main: 304177152 03:21:45 INFO - PROCESS | 1934 | 03:21:48 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:21:49 INFO - PROCESS | 1934 | RSS: Main: 316080128 03:21:49 INFO - PROCESS | 1934 | 03:21:52 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:52 INFO - PROCESS | 1934 | RSS: Main: 315686912 03:21:52 INFO - PROCESS | 1934 | 03:21:54 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55656/tests/kraken/driver.html?testName=ai-astar) 03:21:55 INFO - PROCESS | 1934 | RSS: Main: 359473152 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | __start_tp_report 03:21:55 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 03:21:55 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 03:21:55 INFO - PROCESS | 1934 | |i|pagename|runs| 03:21:55 INFO - PROCESS | 1934 | |0;ai-astar;115;117;115;114;116;116;116;116;116;115 03:21:55 INFO - PROCESS | 1934 | |1;audio-beat-detection;142;111;111;145;103;110;104;107;138;97 03:21:55 INFO - PROCESS | 1934 | |2;audio-dft;186;185;192;185;185;184;184;183;185;184 03:21:55 INFO - PROCESS | 1934 | |3;audio-fft;81;65;81;114;60;57;74;81;108;72 03:21:55 INFO - PROCESS | 1934 | |4;audio-oscillator;88;68;93;69;68;69;89;66;69;92 03:21:55 INFO - PROCESS | 1934 | |5;imaging-gaussian-blur;90;87;91;88;88;87;91;89;90;86 03:21:55 INFO - PROCESS | 1934 | |6;imaging-darkroom;96;95;96;103;147;95;96;95;94;96 03:21:55 INFO - PROCESS | 1934 | |7;imaging-desaturate;80;82;83;78;80;81;83;83;80;83 03:21:55 INFO - PROCESS | 1934 | |8;json-parse-financial;48;48;84;85;86;58;58;57;55;57 03:21:55 INFO - PROCESS | 1934 | |9;json-stringify-tinderbox;65;78;68;78;73;71;70;73;72;70 03:21:55 INFO - PROCESS | 1934 | |10;stanford-crypto-aes;84;82;84;83;82;83;82;83;82;82 03:21:55 INFO - PROCESS | 1934 | |11;stanford-crypto-ccm;144;146;153;139;138;153;162;144;182;149 03:21:55 INFO - PROCESS | 1934 | |12;stanford-crypto-pbkdf2;162;165;162;213;189;164;168;164;164;163 03:21:55 INFO - PROCESS | 1934 | |13;stanford-crypto-sha256-iterative;66;68;74;87;86;90;85;67;68;66 03:21:55 INFO - PROCESS | 1934 | __end_tp_report 03:21:55 INFO - PROCESS | 1934 | __start_cc_report 03:21:55 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,2096 03:21:55 INFO - PROCESS | 1934 | __end_cc_report 03:21:55 INFO - PROCESS | 1934 | __startTimestamp1482405715117__endTimestamp 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | ------- Summary: start ------- 03:21:55 INFO - PROCESS | 1934 | Number of tests: 14 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.00 stddev:0.84 (0.7%) stddev-sans-first:0.87 03:21:55 INFO - PROCESS | 1934 | Values: 115.0 117.0 115.0 114.0 116.0 116.0 116.0 116.0 116.0 115.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#1] audio-beat-detection Cycles:10 Average:116.80 Median:124.50 stddev:17.75 (14.3%) stddev-sans-first:16.32 03:21:55 INFO - PROCESS | 1934 | Values: 142.0 111.0 111.0 145.0 103.0 110.0 104.0 107.0 138.0 97.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#2] audio-dft Cycles:10 Average:185.30 Median:185.00 stddev:2.50 (1.3%) stddev-sans-first:2.64 03:21:55 INFO - PROCESS | 1934 | Values: 186.0 185.0 192.0 185.0 185.0 184.0 184.0 183.0 185.0 184.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#3] audio-fft Cycles:10 Average:79.30 Median:73.00 stddev:18.83 (25.8%) stddev-sans-first:19.97 03:21:55 INFO - PROCESS | 1934 | Values: 81.0 65.0 81.0 114.0 60.0 57.0 74.0 81.0 108.0 72.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.50 stddev:11.65 (14.8%) stddev-sans-first:11.67 03:21:55 INFO - PROCESS | 1934 | Values: 88.0 68.0 93.0 69.0 68.0 69.0 89.0 66.0 69.0 92.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:89.50 stddev:1.77 (2.0%) stddev-sans-first:1.81 03:21:55 INFO - PROCESS | 1934 | Values: 90.0 87.0 91.0 88.0 88.0 87.0 91.0 89.0 90.0 86.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:95.50 stddev:16.25 (17.0%) stddev-sans-first:17.12 03:21:55 INFO - PROCESS | 1934 | Values: 96.0 95.0 96.0 103.0 147.0 95.0 96.0 95.0 94.0 96.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#7] imaging-desaturate Cycles:10 Average:81.30 Median:82.50 stddev:1.77 (2.1%) stddev-sans-first:1.81 03:21:55 INFO - PROCESS | 1934 | Values: 80.0 82.0 83.0 78.0 80.0 81.0 83.0 83.0 80.0 83.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:58.00 stddev:15.23 (26.3%) stddev-sans-first:15.07 03:21:55 INFO - PROCESS | 1934 | Values: 48.0 48.0 84.0 85.0 86.0 58.0 58.0 57.0 55.0 57.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.50 stddev:4.05 (5.6%) stddev-sans-first:3.47 03:21:55 INFO - PROCESS | 1934 | Values: 65.0 78.0 68.0 78.0 73.0 71.0 70.0 73.0 72.0 70.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.73 03:21:55 INFO - PROCESS | 1934 | Values: 84.0 82.0 84.0 83.0 82.0 83.0 82.0 83.0 82.0 82.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:151.00 stddev:13.04 (8.6%) stddev-sans-first:13.58 03:21:55 INFO - PROCESS | 1934 | Values: 144.0 146.0 153.0 139.0 138.0 153.0 162.0 144.0 182.0 149.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:164.50 stddev:16.68 (10.1%) stddev-sans-first:17.34 03:21:55 INFO - PROCESS | 1934 | Values: 162.0 165.0 162.0 213.0 189.0 164.0 168.0 164.0 164.0 163.0 03:21:55 INFO - PROCESS | 1934 | 03:21:55 INFO - PROCESS | 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:79.50 stddev:10.06 (12.6%) stddev-sans-first:10.03 03:21:55 INFO - PROCESS | 1934 | Values: 66.0 68.0 74.0 87.0 86.0 90.0 85.0 67.0 68.0 66.0 03:21:55 INFO - PROCESS | 1934 | -------- Summary: end -------- 03:21:55 INFO - PROCESS | 1934 | 03:21:56 INFO - TEST-INFO | 1934: exit 0 03:21:56 INFO - TEST-OK | kraken | took 60327ms 03:21:56 INFO - SUITE-END | took 1180s 03:21:56 INFO - Completed test suite (00:19:40) 03:21:56 INFO - javascript benchmark 03:21:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3034.575159477673, 3331.550240717503, 3353.9496907546404, 3370.8820191293294, 3420.914477138073, 5653.236813030245, 5703.800366987156, 5704.401037891024, 5736.684482353474, 5745.109508026252, 5621.353544342402, 5637.210790489335, 5731.254936858213, 5737.627054241471, 5738.167965644979, 4253.638440732541, 5182.974085129569, 5186.948130518703, 5209.661372010826, 5242.528172464477, 5206.088934436478, 5209.583233341343, 5223.947760522403, 5298.814541491049, 5346.518813306801, 5218.1650935850275, 5251.711155886424, 5262.236975638531, 5276.208568714705, 5293.86765330866, 4347.174036933005, 5238.973805130969, 5250.501202385767, 5263.157894736862, 5360.812371567015, 5181.559567436772, 5226.033183860998, 5255.527002569749, 5256.211568264753, 5266.815661451831, 4635.6523260755575, 4708.0, 5597.804076857331, 5630.8029218977545, 5638.295213098363, 6127.311699317065, 6161.876762464771, 6170.074488826712, 6176.382361763833, 6186.164867742842, 6254.312025677172, 6261.624302541816, 6266.49868010558, 6268.937310626927, 6269.780557680459, 5673.517750991129, 5720.199172115824, 5745.109508026263, 5762.855928601776, 5811.099279611667, 6230.408111229426, 6235.407636274546, 6236.438720515084, 6244.2819075805955, 6244.781432649836, 6143.815685529441, 6148.8770224595255, 6155.938440615537, 6160.260768707783, 6183.721732522047, 4989.90020199594, 6079.118527813531, 6167.414095660905, 6247.875042499125, 6262.342454042351, 6106.460400469315, 6147.293061297919, 6204.751809927552, 6235.033569796688, 6237.220347456568, 5177.611679124081, 6244.781432649836, 6257.405546473078, 6257.8748425031245, 6275.02737075754, 4911.852644420673, 4918.975405122951, 4942.283368911488, 4951.752412379438, 4966.8261610844165, 4366.410534577792, 4397.780110994438, 4404.801783919731, 4434.889127771831, 4437.179121862466, 5772.047612144033, 5828.0, 5834.27071616048, 5869.2956845178, 5871.148683440877, 6216.44052035319, 6220.34686357935, 6232.376762323822, 6236.563440559208, 6251.687415629201, 4908.288298196741, 5576.693281869536, 5641.746121424546, 5738.311402631711, 5788.652680839163, 5355.812546560929, 5389.299391079135, 5392.595555333287, 5403.13549832025, 5470.835874923679, 6302.464290535172, 6305.684715764285, 6309.49524038085, 6310.936890631035, 6314.368563143833, 6125.876495711566, 6194.4115309045565, 6213.0, 6218.62688238689, 6231.875362492816, 6074.301455332693, 6107.450329470369, 6208.2860471044605, 6230.345813689679, 6246.968765156145, 6137.938620613737, 6197.628142311476, 6202.968985155046, 6214.533909956862, 6218.253809542884, 5629.915551266652, 6167.938320616916, 6182.196314479269, 6245.313015568375, 6251.812445626639, 5971.850703732268, 6057.606255593372, 6080.878382432238, 6109.6639684817765, 6138.806454026249, 6077.270727512727, 6086.421789929949, 6089.604175728564, 6089.665068421279, 6097.451229389376, 5912.793052243151, 5918.260217472816, 5939.049752039653, 5943.732532035982, 5965.970170149121, 6010.549208809444, 6073.240844894388, 6094.54290928191, 6099.542534309856, 6111.1444397785235, 5891.23413956183, 5893.057110862242, 5906.497947674324, 5912.526997840077, 5914.645121292736], "value": 5653.076077666193, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.2264118921401, 495.5936132371456, 508.97187680884605, 522.514061922412, 535.0556268186649, 30817.845910770444, 40044.79977600111, 41339.793301033525, 41349.759507214796, 42963.0, 41853.58146418535, 42466.787666061704, 42596.36105458421, 42620.57379426205, 42770.572294277044, 9344.345895787297, 9420.576074076665, 9550.952245238765, 9557.13985741283, 9578.0, 7974.521528708267, 9501.71494855152, 9566.617335306579, 9569.33014688972, 9576.904230957705, 9389.67136150235, 9443.244540436748, 9450.385724927892, 9467.526623668824, 9498.19265362445, 9431.0, 9513.667021654244, 9560.856587151198, 9566.18687411564, 9650.131488166098, 9399.34204605678, 9445.244380449547, 9445.338826282163, 9446.338756287063, 9446.433214007182, 40223.0, 40392.0, 40405.79797101025, 40433.98915027118, 40539.39190912139, 3260.2175477885135, 3309.3050459403553, 3331.716804071657, 3341.983290083534, 3728.5339332583426, 28951.56572651412, 29078.0, 30490.695093049006, 30930.226744331347, 30998.225044373845, 2956.39393924245, 3001.864916078782, 3133.4673105572106, 3146.905592832196, 3158.115727596277, 507649.0, 512607.0, 512934.0, 513281.0, 522551.0, 63047.684761575896, 63673.363266366745, 63696.36303636997, 63753.72492550123, 63826.36173638297, 34498.48252276218, 34671.65328346684, 35371.11572210664, 36186.0, 67625.0, 16155.676886462204, 16216.189190540426, 16216.351345946267, 16240.51278461648, 16269.51191464258, 12435.191712538655, 12501.687457813627, 12504.499820007099, 12536.623901282976, 12573.37133143339, 19179.041047947547, 19310.420687379403, 19354.903225483784, 19394.418167455, 19443.902780486005, 7721.884171737428, 7851.568163751049, 7861.724839630586, 7875.6062196889925, 7927.0, 741.1958025542347, 978.5419724610422, 989.8465737810651, 995.5669283861544, 1039.667306461925, 28250.576241356397, 30242.0, 30305.0, 30478.39043219123, 30826.53760193599, 990.559201155479, 1014.1886490807325, 1022.9693109206736, 1087.3367245979946, 1138.6868611132004, 13787.51743688966, 15626.68746625061, 15869.047857128378, 15902.522924312289, 16138.435154769762, 676701.0, 681500.0, 682467.0, 683063.0, 683282.0, 59152.7042364794, 59552.426854388876, 59609.0, 59609.403905961255, 59683.701581492685, 1251.6870782304425, 1296.5267677297907, 1298.0589072922244, 1397.77635578307, 1473.9631509212356, 28845.0, 31369.8431507841, 31777.523337149498, 31841.840790795897, 32422.83788581042, 50528.24207636962, 51296.74351628218, 51338.74330628323, 51414.22878656748, 51462.970740585726, 614849.0, 623867.0, 624846.0, 626954.0, 627455.0, 36157.91526254216, 37015.483529064695, 37624.0, 43449.3482597755, 44187.11625767531, 38588.0, 40816.59183408128, 45632.54367456283, 46625.766871165426, 47395.05209895852, 37983.430248545745, 38618.80690596529, 38909.0, 40293.79118626446, 40522.18955620812, 36719.714809981524, 38664.226715466095, 38769.4184587237, 40093.0, 40200.39699404453, 36841.71054013097, 38106.85679429622, 38679.80660096682, 38681.613183868925, 38741.806290968365, 32369.0, 34590.404357845655, 35054.29891402107, 35170.12074698153, 35346.0, 22070.10869293855, 25597.616035759107, 25605.48789024172, 25642.102526412233, 26005.869970650783], "value": 20042.19408155206, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.5236319339394, 365.5229924947942, 370.5682879445447, 389.2060197197717, 400.8877514295998, 18462.53843653908, 19195.32816351428, 19765.604687906234, 20047.59904801903, 20312.796872031275, 20080.799192008075, 20364.8981755091, 20397.0, 20671.86304753238, 20700.861452620098, 5720.513756330717, 5762.56780741445, 5993.670348130851, 6017.097435384705, 6019.097135429677, 5890.705464726768, 6045.183900173486, 6045.848853778669, 6052.818415447544, 6061.212042434478, 5883.146943693162, 6048.637081775108, 6054.091886217081, 6570.1130347403205, 6660.76687315944, 6664.766733164341, 6694.263631000586, 6706.798796036127, 6707.765228217014, 6707.832304192385, 5186.948130518685, 5464.289642346509, 5513.807016754435, 5552.444755524456, 5558.972205138989, 13126.0, 13425.66435839112, 17509.7373539397, 24783.380415489577, 25100.49799004028, 2261.694671219381, 2353.5881220786464, 2360.1267531013636, 2612.1118819601425, 2769.6399468069226, 9761.414315141114, 9887.752806179904, 9988.350757200831, 10173.898261017443, 15133.319000644888, 1714.3571160814695, 1793.3454289184485, 1828.9300759055968, 1851.4908400190056, 1864.5711486358077, 121138.1260218083, 121656.0, 126410.0, 131930.6806931928, 134152.999999999, 11137.944310278397, 11501.424928753697, 11530.94234528822, 11547.422628868691, 11750.177487575958, 14884.925575372054, 14886.255687215596, 14886.330115144845, 14920.253987300592, 14937.0, 8198.0, 8425.747227583182, 8499.66001359951, 8599.441036332744, 8648.394612377073, 6923.6192009439965, 8400.117987611213, 8400.957995210107, 8413.326933845277, 8579.270761985177, 4092.570280120604, 4405.757683327383, 4485.977570112129, 4505.414296141481, 4575.313702944519, 1716.8712346574057, 1739.2868923741205, 1876.1557299215408, 1887.6224755049045, 1892.6720134234693, 708.2386434582844, 749.4304328710219, 792.338397438134, 849.1720572441819, 890.8262888736764, 19666.803331966785, 21039.474013149793, 21528.569428611652, 21582.460438488848, 21638.35084947454, 881.1805021330224, 926.666400095965, 952.5951470624957, 955.4553904274496, 955.8422860227985, 9266.258699304039, 10612.840807387895, 11324.433778311051, 11327.546898123983, 11359.977602015859, 618050.0, 632674.0, 635198.824005877, 645105.0, 648176.0, 91738.0, 126176.0, 126651.74129353234, 134358.0, 137636.31181844228, 822.7120507822215, 915.1763412928285, 946.0917519181526, 1021.1320377679062, 1024.4826362686795, 18049.72925406146, 18945.33691320797, 19612.215511379956, 19634.410967670992, 19663.41009769709, 53726.731366342916, 55103.724481377336, 55720.885582287316, 55852.16221756758, 56299.71850140723, 67887.0, 80644.0, 82308.17691823005, 83584.0, 84419.0, 7098.716051357887, 7398.81515407971, 7495.550266983998, 7725.956995805494, 7912.36701063925, 11165.832512512481, 12970.416331265304, 13037.739245214852, 13477.19136851792, 13561.389737462036, 9131.634734610541, 11316.83024754613, 11569.363684997406, 11699.88300117022, 11826.994705450134, 5139.357580302462, 7880.566568838769, 10236.436995965294, 10263.486825658836, 10945.398003109905, 9599.664011759554, 11369.715757106138, 11444.370559618968, 11794.174407791716, 11878.406079695807, 6900.309969003059, 7152.320529549685, 7381.0, 7541.622918854145, 7827.099883513469, 9586.376885502421, 11270.94364528172, 11322.433878305887, 11672.824907626562, 11841.763164736829], "value": 9439.485836168977, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.2334522631647, 1457.8687918087373, 1592.800899887514, 1760.8327208915148, 1832.2304632054536, 8047.758567242985, 8483.957580212098, 8484.363672724547, 8487.981442226941, 8501.022382426023, 7423.369013633834, 7644.350230230423, 8080.6767729290905, 8330.0, 8345.290650294732, 2302.435903203713, 2339.181286549711, 2339.543788961153, 2340.602097643396, 2343.7773411525877, 2243.730752309717, 2274.9772502275014, 2279.3048120323297, 2281.178775640776, 2281.783230593091, 2263.3323169664905, 2270.14869423966, 2291.2438895164637, 2295.747467778543, 2315.9652605210936, 2257.221258665758, 2267.489814791675, 2270.5345404192103, 2275.226423016166, 2275.2946586558137, 2325.802306803924, 2348.0842471436154, 2349.9647505287435, 2351.823613229015, 2361.6339467382413, 8350.540720260384, 8420.368472364597, 8421.326293896474, 8443.15568443157, 8443.746687599381, 5050.368703912011, 5226.764795584208, 5234.319538460014, 5237.4500677428705, 5239.554637855748, 3197.8560964756643, 3264.363449127421, 3517.6482351764876, 3529.6823285904134, 3530.9117272068147, 990.4107056301489, 1053.6312290698195, 1056.75166335911, 1075.473018221066, 1091.1325496230436, 10790.5683772649, 10877.0, 10891.782164356668, 10934.617288394868, 10949.124070074455, 4534.319852022223, 4606.838760643362, 4678.111158879802, 4686.95313046872, 4797.304390863305, 9438.150566449052, 9438.764030899283, 9446.0, 9451.716448506555, 9454.385464944757, 5194.35070616173, 5201.479852014844, 5203.089459344599, 5207.0887594670785, 5217.50433708797, 4259.9787001065415, 4395.274779661384, 4585.70192937458, 4701.553352431513, 4769.713817170981, 3769.208466222069, 3786.356319425649, 3878.9224215515533, 3888.2223555289056, 3932.134930315326, 1243.2726854790048, 1249.575144450891, 1250.6310638361706, 1268.4355461819403, 1273.5542560104075, 877.4252864373718, 879.9120087991149, 889.644142343068, 895.2166854002747, 903.2773780975193, 5422.593305502024, 5587.636803607753, 5674.177244299636, 5729.570282228766, 5773.826785196451, 334.49157280932843, 385.77432202161486, 452.7577745905925, 457.09039012365156, 458.91510070637037, 3778.0932576181517, 3786.4888240087785, 3864.2271545690414, 3881.262560113626, 3899.3760998240145, 9959.203263738884, 10112.342697724624, 10193.796124077477, 10209.897901021044, 10233.283670143019, 10161.18710503158, 10168.33905796121, 10168.389896606228, 10180.880103188643, 10196.643117490852, 7405.8148546286775, 7686.846263074707, 7749.2638199372095, 7913.0, 7918.0, 5547.862688148839, 5645.858853528694, 5680.914786278292, 5797.101449275369, 5917.053271476543, 8417.242448179448, 9181.862272065791, 9232.21526170283, 9247.72256832296, 9399.436033837992, 9426.622935082794, 9462.0, 9486.857697134687, 9540.284478663989, 9551.42691438516, 6629.900551491635, 6850.73261446614, 6855.314468553105, 7192.676329565077, 7340.412766978736, 7629.885551716618, 7901.288884000468, 7949.8410031797885, 7959.602019898866, 8051.597420129087, 7028.50800443964, 7167.498275120692, 7228.96385518069, 7239.167495738057, 7291.34377905991, 6955.478339124485, 7038.894416583653, 7062.505624606229, 7138.214796372498, 7181.676824542804, 6331.986882098842, 6369.108324834434, 6464.159659244268, 6470.320616335405, 6473.0, 4806.44725856517, 4817.759112044314, 4836.395450568679, 4858.368412106403, 4902.509749025069, 4782.354382158364, 4797.112534181188, 4882.170031094794, 4964.726940018334, 4989.800407983786], "value": 4327.35171281391, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.3399850336742, 298.3749045749155, 299.40567972574433, 308.34322892239527, 311.3539405733104, 4377.956220437794, 4464.709793863397, 4586.816527338907, 5191.5327620514145, 5380.139177731564, 4944.3794788761925, 4958.35541379621, 5091.949080509194, 5140.640155189139, 5144.356955380577, 4000.0999775050673, 4035.3946907963746, 4037.253108174982, 4041.0503531670033, 4087.3255912774357, 3324.268660894599, 3354.85934782174, 3476.8783092591766, 4068.1456894052285, 4079.2045551117544, 3212.9196770080816, 3994.6005399460114, 3999.3800960851113, 4015.698822588303, 4043.4541336919433, 3309.635940046593, 4025.81883815229, 4042.8584999525024, 4050.8860063482484, 4061.0659548304056, 2647.7026257134057, 2946.7789915756402, 3456.585209774818, 3545.3514115935955, 3554.751167418282, 4828.589569886564, 4991.850244492671, 5050.621203409759, 5063.8987220255385, 5070.29015937769, 3818.6563209311016, 4510.661700372452, 4569.451665800092, 4583.2666773316105, 4589.403377560896, 4547.499775024745, 5461.453854614547, 5488.313960754906, 5504.256925315071, 5507.697076660782, 3220.888793366285, 3817.7709337439564, 3855.7879316637573, 3857.286402015636, 3863.74885632433, 5455.890882182334, 5705.834740639357, 5724.8855022899725, 5770.740316685759, 5799.88400231993, 3152.511360739089, 3609.639036096369, 3900.3726534322786, 3913.96086039136, 3924.4498423123277, 3448.8620455182013, 3862.1117143057254, 3982.7411218270718, 3991.6806655467494, 3992.8203230854683, 4162.895927601774, 4346.326319420495, 4552.795124219418, 4625.7687115644085, 4633.305004249322, 4411.09572537629, 4868.634852386085, 5147.948520514747, 5536.920300541356, 5585.497305242548, 1074.4305518075432, 1152.7895709504987, 1225.4669218889812, 1288.15625765124, 1292.560529419983, 751.6993202718957, 827.9213474719892, 843.8143608406141, 860.0539406652632, 880.7181701855473, 4218.535961044283, 4223.72545784523, 4259.1268789897995, 4756.025014871943, 4990.476000020019, 4276.978615106905, 4909.288153217834, 5257.0, 5460.372057213392, 5481.2874326338115, 4648.58162765346, 4784.497627749106, 4813.507812578042, 4824.155772739757, 4889.511048895153, 2633.499635069369, 2783.3180870686406, 2785.3036740814796, 2789.3445040415486, 2868.885244590193, 5221.7128057956415, 5309.522143007148, 5537.335519737658, 5577.27495425592, 5613.831585052455, 4539.931901021488, 4736.3369128322065, 5184.911168654549, 5405.540529054996, 5447.455254474521, 4687.414073240845, 4890.608751299887, 5111.192161839454, 5478.890422191614, 5483.669440487856, 5017.971315880162, 5165.509276618715, 5264.972640731671, 5331.9466805333, 5386.59600529954, 4386.223579450328, 4609.838655647036, 4725.8582242532775, 5232.273349794635, 5408.78364865401, 5179.0, 5180.497655679961, 5198.844034678966, 5365.597580181424, 5648.971755141198, 3110.538047117851, 3460.117669994135, 3591.425371940477, 3738.513993180869, 3751.8124093795745, 4774.665773395968, 4813.614910807057, 5347.7593508293, 5348.572114230775, 5374.0, 4410.603045725772, 5057.418396884405, 5111.769970351269, 5116.7441627917715, 5153.577818425334, 4308.116836048665, 4386.451693538308, 4976.751162441936, 5081.466446023114, 5148.473295792394, 4798.224656876839, 5146.922796157985, 5301.807093403953, 5391.7304134793885, 5443.945560544502, 4518.0, 4615.446146462312, 4627.67606267558, 5404.459554044564, 5443.755031023693, 5392.379876314274, 5431.582945589175, 5439.782408703766, 5451.465068743054, 5515.586331025109], "value": 3874.442118836424, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.5755631508465, 706.5972395734431, 712.5724565260842, 749.2170681637688, 775.2712450296721, 14006.229657368853, 14511.564653060399, 14517.419303227858, 14520.564383068511, 14565.490207842715, 12968.935155324234, 13024.934875325634, 13031.60905172844, 13094.345282735872, 13154.671133221651, 648.3160265919465, 672.5124284893462, 676.4486943141324, 804.7545498622918, 831.9209675080888, 820.7948012996751, 824.8597738384489, 829.875518672201, 834.4200780457554, 835.4778263585259, 886.4858382138376, 887.6316328723572, 889.3374436045156, 890.7728529225038, 892.2282225874636, 686.8557602903396, 878.3193025405299, 879.9692010779656, 880.2561835249236, 880.3749337970049, 522.7908836465406, 633.1547384242042, 649.7336092202222, 650.8372906773307, 656.5929123943138, 13941.651458713512, 13985.650358741113, 14000.369983350673, 14010.439582416691, 14078.436862525487, 12804.679883002906, 13007.959363250917, 13024.004478969795, 13060.477580896753, 13154.671133221651, 10727.517261723242, 11118.777624447546, 11124.721881953015, 11846.052315814713, 11913.225640333438, 3907.745996510246, 4087.652549533293, 4344.717593356421, 4362.236608593484, 4521.0, 81487.7776833348, 82100.35799283981, 82986.0, 83801.74297385543, 84059.8985025367, 9998.950005250073, 10016.14862736661, 10247.59009639621, 10300.742481438025, 10344.741381465523, 15685.372585096697, 18773.24907003732, 18815.71776423355, 18853.0, 18936.90531547361, 7242.456815738736, 7248.383887369527, 7321.267873212636, 7428.220036896156, 7471.589062601501, 4144.849914065332, 4165.875023749292, 4189.183109293658, 4218.7468751874985, 4291.721038132574, 3295.6210035845706, 3405.1487128217946, 3416.4533674611944, 3417.1457135716073, 3424.5719285089363, 585.4643001653525, 586.416515567005, 587.5094296262583, 593.4984937727613, 602.9698515074228, 529.7774934527507, 547.5017733862167, 589.8318979090939, 644.3459888213467, 674.5480528046221, 8661.610227539775, 8901.065388134282, 8938.703887936343, 9064.954675226581, 9481.383710058824, 604.0882944208959, 658.1739916404977, 658.9472842172614, 677.8305423644089, 690.1614538335946, 3890.513685789276, 4458.665600080007, 4491.573300536444, 4513.729176249435, 4617.376654151713, 75881.62059189669, 109325.45337273263, 110009.0, 111995.0, 115103.2734508983, 28263.717362826108, 34766.82616586901, 89007.0, 99496.50251748695, 101846.0, 66300.6684966572, 90624.5468772665, 91769.0823091774, 92489.61265581156, 92666.53666731624, 7465.365443937326, 8587.484750914966, 8595.140485951355, 8822.911770882209, 8833.558322083998, 43787.34318985154, 53599.19601206063, 55262.72368638131, 56577.71711141583, 57835.421645783004, 77404.2259577397, 79624.0, 82340.0, 82559.17440825755, 83023.0, 10711.410872402093, 11141.554337826396, 11175.21773475849, 11316.26444281119, 11423.714407139889, 12001.579944701894, 12177.33024683651, 12205.69485762863, 12242.143049986415, 12437.315947622968, 9927.354721943051, 11199.440027998731, 12553.058520610808, 12736.108472406842, 13198.14212076212, 12841.873859176783, 13018.674533136747, 13435.395407206504, 13490.86509134896, 13768.65578360549, 11816.291022538331, 12028.332517458051, 12079.939600301943, 12144.33206173669, 12698.36508174606, 11012.889871101186, 11213.439328033728, 11264.718382040515, 11573.421328933688, 11602.825957610474, 10909.34543927367, 11066.446677666245, 11068.0, 11140.0, 11528.711782205512], "value": 7075.985657595443, "unit": "score"}], "name": "dromaeo_css", "value": 7088.8302928631865, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 115.0, 114.0, 116.0, 116.0, 116.0, 116.0, 116.0, 115.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 111.0, 145.0, 103.0, 110.0, 104.0, 107.0, 138.0, 97.0], "value": 116.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 192.0, 185.0, 185.0, 184.0, 184.0, 183.0, 185.0, 184.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 65.0, 81.0, 114.0, 60.0, 57.0, 74.0, 81.0, 108.0, 72.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 93.0, 69.0, 68.0, 69.0, 89.0, 66.0, 69.0, 92.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 88.0, 87.0, 91.0, 89.0, 90.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 103.0, 147.0, 95.0, 96.0, 95.0, 94.0, 96.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 83.0, 78.0, 80.0, 81.0, 83.0, 83.0, 80.0, 83.0], "value": 81.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 84.0, 85.0, 86.0, 58.0, 58.0, 57.0, 55.0, 57.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 68.0, 78.0, 73.0, 71.0, 70.0, 73.0, 72.0, 70.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 84.0, 83.0, 82.0, 83.0, 82.0, 83.0, 82.0, 82.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 146.0, 153.0, 139.0, 138.0, 153.0, 162.0, 144.0, 182.0, 149.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 162.0, 213.0, 189.0, 164.0, 168.0, 164.0, 164.0, 163.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 74.0, 87.0, 86.0, 90.0, 85.0, 67.0, 68.0, 66.0], "value": 75.7, "unit": "score"}], "name": "kraken", "value": 1461.6000000000001, "alertThreshold": 2.0}]} 03:21:56 INFO - Return code: 0 03:21:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:21:56 INFO - # TBPL SUCCESS # 03:21:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:21:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:21:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:21:56 INFO - 2016-12-22 03:21:56.445 screenresolution[1938:22293] starting screenresolution argv=screenresolution get 03:21:56 INFO - 2016-12-22 03:21:56.448 screenresolution[1938:22293] Display 0: 1600x1200x32@60 03:21:56 INFO - 2016-12-22 03:21:56.455 screenresolution[1939:22296] starting screenresolution argv=screenresolution list 03:21:56 INFO - Available Modes on Display 0 03:21:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:21:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:21:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:21:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:21:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:21:56 INFO - Intel Iris: 03:21:56 INFO - Chipset Model: Intel Iris 03:21:56 INFO - Type: GPU 03:21:56 INFO - Bus: Built-In 03:21:56 INFO - VRAM (Dynamic, Max): 1536 MB 03:21:56 INFO - Vendor: Intel (0x8086) 03:21:56 INFO - Device ID: 0x0a2e 03:21:56 INFO - Revision ID: 0x0009 03:21:56 INFO - Displays: 03:21:56 INFO - Display: 03:21:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:21:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:21:56 INFO - Display Serial Number: 1600x1200 60 03:21:56 INFO - Main Display: Yes 03:21:56 INFO - Mirror: Off 03:21:56 INFO - Online: Yes 03:21:56 INFO - Rotation: Supported 03:21:56 INFO - Return code: 0 03:21:56 INFO - Running post-action listener: _resource_record_post_action 03:21:56 INFO - [mozharness: 2016-12-22 11:21:56.700176Z] Finished run-tests step (success) 03:21:56 INFO - Running post-run listener: _resource_record_post_run 03:21:56 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 512000; Write bytes: 658007552; Read time: 9; Write time: 1917 03:21:56 INFO - TinderboxPrint: CPU usage
53.7% 03:21:56 INFO - TinderboxPrint: I/O read bytes / time
512,000 / 9 03:21:56 INFO - TinderboxPrint: I/O write bytes / time
658,007,552 / 1,917 03:21:56 INFO - TinderboxPrint: CPU idle
2,249.4 (46.3%) 03:21:56 INFO - TinderboxPrint: CPU system
975.1 (20.1%) 03:21:56 INFO - TinderboxPrint: CPU user
1,629.1 (33.6%) 03:21:56 INFO - TinderboxPrint: Swap in / out
349,347,840 / 0 03:21:56 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 342315520; Write bytes: 336390144; Read time: 26804; Write time: 707 03:21:56 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 512000; Write bytes: 306204160; Read time: 9; Write time: 1159 03:21:57 INFO - Running post-run listener: _upload_blobber_files 03:21:57 INFO - Blob upload gear active. 03:21:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:21:57 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 03:21:57 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'] 03:21:57 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 03:21:57 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:21:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:21:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:58 INFO - (blobuploader) - INFO - Done attempting. 03:21:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:21:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:22:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:00 INFO - (blobuploader) - INFO - Done attempting. 03:22:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:22:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:22:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:03 INFO - (blobuploader) - INFO - Done attempting. 03:22:03 INFO - (blobuploader) - INFO - Iteration through files over. 03:22:03 INFO - Return code: 0 03:22:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:22:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:22:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7436eaa1621962d18dec905e2796a14c4cf952d8db4124ac57c666b83b8a2bc860a26c7d3cad87bf43ce12405f9100da3ee2b06945860c8afa39050b5cdcaf0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53d56c30a6cf67314d959268532ae455e500bbc727fedea89ee8663e476f89fdfb334a64d31fcb6f92ece4c81e372d61533ec4cc6ea3f8e6300678a4806fb05e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07f81dfb554909fbed6fd82f19092eb7ea615fd7ad5e4a1c4fd2df1856e8e4716c58ac89b9a56d0c143a8f13bbf8e9aaee5c0f7993ffe9147e1f740a3bdb8dc7"} 03:22:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:22:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:22:03 INFO - Contents: 03:22:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7436eaa1621962d18dec905e2796a14c4cf952d8db4124ac57c666b83b8a2bc860a26c7d3cad87bf43ce12405f9100da3ee2b06945860c8afa39050b5cdcaf0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53d56c30a6cf67314d959268532ae455e500bbc727fedea89ee8663e476f89fdfb334a64d31fcb6f92ece4c81e372d61533ec4cc6ea3f8e6300678a4806fb05e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07f81dfb554909fbed6fd82f19092eb7ea615fd7ad5e4a1c4fd2df1856e8e4716c58ac89b9a56d0c143a8f13bbf8e9aaee5c0f7993ffe9147e1f740a3bdb8dc7"} 03:22:03 INFO - Running post-run listener: copy_logs_to_upload_dir 03:22:03 INFO - Copying logs to upload dir... 03:22:03 INFO - mkdir: /builds/slave/test/build/upload/logs 03:22:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.366207 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-22 03:22:03.096228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 03:22:03.104106) ========= 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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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/7436eaa1621962d18dec905e2796a14c4cf952d8db4124ac57c666b83b8a2bc860a26c7d3cad87bf43ce12405f9100da3ee2b06945860c8afa39050b5cdcaf0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53d56c30a6cf67314d959268532ae455e500bbc727fedea89ee8663e476f89fdfb334a64d31fcb6f92ece4c81e372d61533ec4cc6ea3f8e6300678a4806fb05e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07f81dfb554909fbed6fd82f19092eb7ea615fd7ad5e4a1c4fd2df1856e8e4716c58ac89b9a56d0c143a8f13bbf8e9aaee5c0f7993ffe9147e1f740a3bdb8dc7"} build_url:https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011240 build_url: 'https://queue.taskcluster.net/v1/task/VV6lutqdQ3iKoXuDOPvMhg/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/7436eaa1621962d18dec905e2796a14c4cf952d8db4124ac57c666b83b8a2bc860a26c7d3cad87bf43ce12405f9100da3ee2b06945860c8afa39050b5cdcaf0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53d56c30a6cf67314d959268532ae455e500bbc727fedea89ee8663e476f89fdfb334a64d31fcb6f92ece4c81e372d61533ec4cc6ea3f8e6300678a4806fb05e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07f81dfb554909fbed6fd82f19092eb7ea615fd7ad5e4a1c4fd2df1856e8e4716c58ac89b9a56d0c143a8f13bbf8e9aaee5c0f7993ffe9147e1f740a3bdb8dc7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 03:22:03.141762) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:22:03.142100) ========= 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.C6d2w4U1Xd/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.ge20wh9Ppp/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:22:03.190838) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 03:22:03.191158) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 03:22:03.191725) ========= ========= Total master_lag: 0.20 =========