builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0163 starttime: 1479136276.66 results: success (0) buildid: 20161114043447 builduid: b3ce9a064b684e8d999f5a98ceef2059 revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.662471) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.662896) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.663180) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.686784) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.687084) ========= 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.hsgKLVPcMG/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.iij93fB2qk/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 07:11:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.8M=0s 2016-11-14 07:11:16 (26.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.791884) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.792160) ========= 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.hsgKLVPcMG/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.iij93fB2qk/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.025803 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:16.839990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 07:11:16.840319) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --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 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hsgKLVPcMG/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.iij93fB2qk/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 07:11:16,921 truncating revision to first 12 chars 2016-11-14 07:11:16,921 Setting DEBUG logging. 2016-11-14 07:11:16,921 attempt 1/10 2016-11-14 07:11:16,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7e29173b1641?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 07:11:18,405 unpacking tar archive at: mozilla-aurora-7e29173b1641/testing/mozharness/ program finished with exit code 0 elapsedTime=1.740805 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 07:11:18.602550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:18.602864) ========= script_repo_revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:18.603244) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:18.603506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:11:18.621561) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-14 07:11:18.621836) ========= /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.hsgKLVPcMG/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.iij93fB2qk/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:11:18 INFO - MultiFileLogger online at 20161114 07:11:18 in /builds/slave/test 07:11:18 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 07:11:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:11:18 INFO - {'append_to_log': False, 07:11:18 INFO - 'base_work_dir': '/builds/slave/test', 07:11:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:11:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:11:18 INFO - 'branch': 'Mozilla-Aurora', 07:11:18 INFO - 'buildbot_json_path': 'buildprops.json', 07:11:18 INFO - 'config_files': ('talos/mac_config.py',), 07:11:18 INFO - 'default_actions': ('clobber', 07:11:18 INFO - 'read-buildbot-config', 07:11:18 INFO - 'download-and-extract', 07:11:18 INFO - 'populate-webroot', 07:11:18 INFO - 'create-virtualenv', 07:11:18 INFO - 'install', 07:11:18 INFO - 'run-tests'), 07:11:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:11:18 INFO - 'download_minidump_stackwalk': True, 07:11:18 INFO - 'download_symbols': 'ondemand', 07:11:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:11:18 INFO - 'tooltool.py': '/tools/tooltool.py', 07:11:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:11:18 INFO - '/tools/misc-python/virtualenv.py')}, 07:11:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:11:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:11:18 INFO - 'installer_path': 'installer.exe', 07:11:18 INFO - 'log_level': 'info', 07:11:18 INFO - 'log_name': 'talos', 07:11:18 INFO - 'log_to_console': True, 07:11:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:11:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:11:18 INFO - 'opt_config_files': (), 07:11:18 INFO - 'pip_index': False, 07:11:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:11:18 INFO - 'cmd': ('bash', 07:11:18 INFO - '-c', 07:11:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:11:18 INFO - 'enabled': True, 07:11:18 INFO - 'halt_on_failure': False, 07:11:18 INFO - 'name': 'check_screen_resolution'},), 07:11:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:11:18 INFO - 'cmd': ('bash', 07:11:18 INFO - '-c', 07:11:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:11:18 INFO - 'enabled': True, 07:11:18 INFO - 'halt_on_failure': False, 07:11:18 INFO - 'name': 'check_screen_resolution'},), 07:11:18 INFO - 'run_cmd_checks_enabled': True, 07:11:18 INFO - 'sps_profile': False, 07:11:18 INFO - 'sps_profile_interval': 0, 07:11:18 INFO - 'suite': 'dromaeojs', 07:11:18 INFO - 'system_bits': '32', 07:11:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:11:18 INFO - 'title': 't-yosemite-r7-0163', 07:11:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:11:18 INFO - 'use_talos_json': True, 07:11:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:11:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:11:18 INFO - 'work_dir': 'build'} 07:11:18 INFO - [mozharness: 2016-11-14 15:11:18.830342Z] Running clobber step. 07:11:18 INFO - Running pre-action listener: _resource_record_pre_action 07:11:18 INFO - Running main action method: clobber 07:11:18 INFO - rmtree: /builds/slave/test/build 07:11:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:11:21 INFO - Running post-action listener: _resource_record_post_action 07:11:21 INFO - [mozharness: 2016-11-14 15:11:21.257018Z] Finished clobber step (success) 07:11:21 INFO - [mozharness: 2016-11-14 15:11:21.257141Z] Running read-buildbot-config step. 07:11:21 INFO - Running pre-action listener: _resource_record_pre_action 07:11:21 INFO - Running main action method: read_buildbot_config 07:11:21 INFO - Using buildbot properties: 07:11:21 INFO - { 07:11:21 INFO - "project": "", 07:11:21 INFO - "product": "firefox", 07:11:21 INFO - "script_repo_revision": "production", 07:11:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:11:21 INFO - "repository": "", 07:11:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:11:21 INFO - "buildid": "20161114043447", 07:11:21 INFO - "pgo_build": "False", 07:11:21 INFO - "basedir": "/builds/slave/test", 07:11:21 INFO - "buildnumber": 35, 07:11:21 INFO - "slavename": "t-yosemite-r7-0163", 07:11:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:11:21 INFO - "platform": "yosemite_r7", 07:11:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:11:21 INFO - "branch": "mozilla-aurora", 07:11:21 INFO - "repo_path": "releases/mozilla-aurora", 07:11:21 INFO - "revision": "7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f", 07:11:21 INFO - "stage_platform": "macosx64", 07:11:21 INFO - "builduid": "b3ce9a064b684e8d999f5a98ceef2059", 07:11:21 INFO - "slavebuilddir": "test" 07:11:21 INFO - } 07:11:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:11:21 INFO - Running post-action listener: _resource_record_post_action 07:11:21 INFO - [mozharness: 2016-11-14 15:11:21.260383Z] Finished read-buildbot-config step (success) 07:11:21 INFO - [mozharness: 2016-11-14 15:11:21.260469Z] Running download-and-extract step. 07:11:21 INFO - Running pre-action listener: _resource_record_pre_action 07:11:21 INFO - Running main action method: download_and_extract 07:11:21 INFO - mkdir: /builds/slave/test/build/tests 07:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:21 INFO - trying https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:11:21 INFO - Downloading https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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 07:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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 07:11:22 INFO - Downloaded 1199 bytes. 07:11:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:11:22 INFO - Using the following test package requirements: 07:11:22 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:11:22 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:11:22 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:11:22 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'jsshell-mac.zip'], 07:11:22 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:11:22 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:11:22 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:11:22 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:11:22 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:11:22 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:11:22 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:11:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:11:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:11:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:11:22 INFO - Fetch https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:11:23 INFO - Http code: 200 07:11:23 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 07:11:23 INFO - x-amz-cf-id: bYYYx9PoW871FXeuKgiLlPUsXLCPpS0KbF_oW5FLN_bX0UOv3dISJQ== 07:11:23 INFO - x-amz-version-id: 46b3rovJZlUGpegJJyUApBTnVWK4pxSj 07:11:23 INFO - Expected file size: 16327493 07:11:23 INFO - Obtained file size: 16327493 07:11:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:11:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:11:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:11:30 INFO - Fetch https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:11:30 INFO - Http code: 200 07:11:30 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 07:11:30 INFO - x-amz-cf-id: tuawwVat684p4tqh_1F6KnF0FalC2h_2qRtrhttGqVCchbnfJOJ88w== 07:11:30 INFO - x-amz-version-id: 46b3rovJZlUGpegJJyUApBTnVWK4pxSj 07:11:31 INFO - Expected file size: 16327493 07:11:31 INFO - Obtained file size: 16327493 07:11:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:11:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:11:37 INFO - Fetch https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:11:38 INFO - Http code: 200 07:11:38 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 07:11:38 INFO - x-amz-cf-id: eDbj4NffuldXAOkC6vNjNfHjsCQ3FJ7clHG01FXaWJchbThL4sgjsA== 07:11:38 INFO - x-amz-version-id: nZy2TRubHfItKTFzKY23wLmggGtvzYcP 07:11:38 INFO - Expected file size: 14409038 07:11:38 INFO - Obtained file size: 14409038 07:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:39 INFO - trying https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:11:39 INFO - Downloading https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:11:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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 07:11:42 INFO - Downloaded 114362976 bytes. 07:11:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:11:42 INFO - mkdir: /builds/slave/test/properties 07:11:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:11:42 INFO - Writing to file /builds/slave/test/properties/build_url 07:11:42 INFO - Contents: 07:11:42 INFO - build_url:https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:11:42 INFO - Running post-action listener: _resource_record_post_action 07:11:42 INFO - Running post-action listener: set_extra_try_arguments 07:11:42 INFO - [mozharness: 2016-11-14 15:11:42.740922Z] Finished download-and-extract step (success) 07:11:42 INFO - [mozharness: 2016-11-14 15:11:42.741073Z] Running populate-webroot step. 07:11:42 INFO - Running pre-action listener: _resource_record_pre_action 07:11:42 INFO - Running main action method: populate_webroot 07:11:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:11:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:11:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:11:42 INFO - '--tppagecycles', 07:11:42 INFO - '7'], 07:11:42 INFO - 'tests': ['tsvgm']}}, 07:11:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:11:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:11:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:11:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:11:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['damp', 'tps']}, 07:11:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'tests': ['damp', 'tps']}, 07:11:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['dromaeo_dom']}, 07:11:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:11:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['basic_compositor_video']}, 07:11:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:11:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['a11yr', 07:11:42 INFO - 'ts_paint', 07:11:42 INFO - 'tpaint', 07:11:42 INFO - 'sessionrestore', 07:11:42 INFO - 'sessionrestore_no_auto_restore', 07:11:42 INFO - 'tabpaint']}, 07:11:42 INFO - 'other-e10s': {'tests': ['a11yr', 07:11:42 INFO - 'ts_paint', 07:11:42 INFO - 'tpaint', 07:11:42 INFO - 'sessionrestore', 07:11:42 INFO - 'sessionrestore_no_auto_restore', 07:11:42 INFO - 'tabpaint']}, 07:11:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:11:42 INFO - 'ts_paint', 07:11:42 INFO - 'tpaint', 07:11:42 INFO - 'sessionrestore', 07:11:42 INFO - 'sessionrestore_no_auto_restore', 07:11:42 INFO - 'tabpaint']}, 07:11:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:11:42 INFO - 'ts_paint', 07:11:42 INFO - 'tpaint', 07:11:42 INFO - 'sessionrestore', 07:11:42 INFO - 'sessionrestore_no_auto_restore', 07:11:42 INFO - 'tabpaint']}, 07:11:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['a11yr', 07:11:42 INFO - 'ts_paint', 07:11:42 INFO - 'tpaint', 07:11:42 INFO - 'sessionrestore', 07:11:42 INFO - 'sessionrestore_no_auto_restore', 07:11:42 INFO - 'tabpaint']}, 07:11:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['a11yr', 07:11:42 INFO - 'ts_paint', 07:11:42 INFO - 'tpaint', 07:11:42 INFO - 'sessionrestore', 07:11:42 INFO - 'sessionrestore_no_auto_restore', 07:11:42 INFO - 'tabpaint']}, 07:11:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['tsvgx', 07:11:42 INFO - 'tsvgr_opacity', 07:11:42 INFO - 'tart', 07:11:42 INFO - 'tscrollx', 07:11:42 INFO - 'cart']}, 07:11:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:11:42 INFO - 'tsvgr_opacity', 07:11:42 INFO - 'tart', 07:11:42 INFO - 'tscrollx', 07:11:42 INFO - 'cart']}, 07:11:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'talos_options': ['--disable-e10s'], 07:11:42 INFO - 'tests': ['tp5o']}, 07:11:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'tests': ['tp5o']}, 07:11:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'talos_options': ['--disable-e10s', 07:11:42 INFO - '--xperf_path', 07:11:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:42 INFO - 'tests': ['tp5n']}, 07:11:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:42 INFO - 'talos_options': ['--xperf_path', 07:11:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:42 INFO - 'tests': ['tp5n']}}, 07:11:42 INFO - 'talos.zip': {'path': '', 07:11:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:11:42 INFO - Running post-action listener: _resource_record_post_action 07:11:42 INFO - [mozharness: 2016-11-14 15:11:42.759565Z] Finished populate-webroot step (success) 07:11:42 INFO - [mozharness: 2016-11-14 15:11:42.759675Z] Running create-virtualenv step. 07:11:42 INFO - Running pre-action listener: _resource_record_pre_action 07:11:42 INFO - Running main action method: create_virtualenv 07:11:42 INFO - Creating virtualenv /builds/slave/test/build/venv 07:11:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:11:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:11:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:11:42 INFO - Using real prefix '/tools/python27' 07:11:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:11:43 INFO - Installing distribute.............................................................................................................................................................................................done. 07:11:46 INFO - Installing pip.................done. 07:11:46 INFO - Return code: 0 07:11:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:11:46 INFO - Reading from file tmpfile_stdout 07:11:46 INFO - Output received: 07:11:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:11:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:11:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:11:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:46 INFO - 'HOME': '/Users/cltbld', 07:11:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:46 INFO - 'LOGNAME': 'cltbld', 07:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:46 INFO - 'MOZ_NO_REMOTE': '1', 07:11:46 INFO - 'NO_EM_RESTART': '1', 07:11:46 INFO - 'PAGER': '/bin/cat', 07:11:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:46 INFO - 'PWD': '/builds/slave/test', 07:11:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:46 INFO - 'SHELL': '/bin/bash', 07:11:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:11:46 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:11:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:46 INFO - 'USER': 'cltbld', 07:11:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:46 INFO - 'XPC_FLAGS': '0x0', 07:11:46 INFO - 'XPC_SERVICE_NAME': '0', 07:11:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:11:46 INFO - Cleaning up... 07:11:47 INFO - Return code: 0 07:11:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:11:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:11:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:47 INFO - 'HOME': '/Users/cltbld', 07:11:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:47 INFO - 'LOGNAME': 'cltbld', 07:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:47 INFO - 'MOZ_NO_REMOTE': '1', 07:11:47 INFO - 'NO_EM_RESTART': '1', 07:11:47 INFO - 'PAGER': '/bin/cat', 07:11:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:47 INFO - 'PWD': '/builds/slave/test', 07:11:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:47 INFO - 'SHELL': '/bin/bash', 07:11:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:11:47 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:11:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:47 INFO - 'USER': 'cltbld', 07:11:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:47 INFO - 'XPC_FLAGS': '0x0', 07:11:47 INFO - 'XPC_SERVICE_NAME': '0', 07:11:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:47 INFO - Downloading/unpacking psutil>=3.1.1 07:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:11:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:50 INFO - Installing collected packages: psutil 07:11:50 INFO - Running setup.py install for psutil 07:11:50 INFO - building 'psutil._psutil_osx' extension 07:11:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:11:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:11:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:11:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:11:51 INFO - building 'psutil._psutil_posix' extension 07:11:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:11:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:11:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:11:51 INFO - ^ 07:11:51 INFO - 1 warning generated. 07:11:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:11:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:51 INFO - Successfully installed psutil 07:11:51 INFO - Cleaning up... 07:11:51 INFO - Return code: 0 07:11:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:11: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')]} 07:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11: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')]} 07:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:11:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:11:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:11:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:11:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:51 INFO - 'HOME': '/Users/cltbld', 07:11:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:51 INFO - 'LOGNAME': 'cltbld', 07:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:51 INFO - 'MOZ_NO_REMOTE': '1', 07:11:51 INFO - 'NO_EM_RESTART': '1', 07:11:51 INFO - 'PAGER': '/bin/cat', 07:11:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:51 INFO - 'PWD': '/builds/slave/test', 07:11:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:51 INFO - 'SHELL': '/bin/bash', 07:11:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:11:51 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:11:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:51 INFO - 'USER': 'cltbld', 07:11:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:51 INFO - 'XPC_FLAGS': '0x0', 07:11:51 INFO - 'XPC_SERVICE_NAME': '0', 07:11:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:11:54 INFO - Installing collected packages: mozsystemmonitor 07:11:54 INFO - Running setup.py install for mozsystemmonitor 07:11:54 INFO - Successfully installed mozsystemmonitor 07:11:54 INFO - Cleaning up... 07:11:55 INFO - Return code: 0 07:11:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:11:55 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')]} 07:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:55 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')]} 07:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:55 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 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:11:55 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 07:11:55 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 07:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:55 INFO - 'HOME': '/Users/cltbld', 07:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:55 INFO - 'LOGNAME': 'cltbld', 07:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:55 INFO - 'MOZ_NO_REMOTE': '1', 07:11:55 INFO - 'NO_EM_RESTART': '1', 07:11:55 INFO - 'PAGER': '/bin/cat', 07:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:55 INFO - 'PWD': '/builds/slave/test', 07:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:55 INFO - 'SHELL': '/bin/bash', 07:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:11:55 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:55 INFO - 'USER': 'cltbld', 07:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:55 INFO - 'XPC_FLAGS': '0x0', 07:11:55 INFO - 'XPC_SERVICE_NAME': '0', 07:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:55 INFO - Downloading/unpacking jsonschema==2.5.1 07:11:55 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 07:11:55 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 07:11:55 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 07:11:55 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 07:11:55 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 07:11:55 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 07:11:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:11:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:58 INFO - Downloading functools32-3.2.3-2.tar.gz 07:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:11:58 INFO - warning: no files found matching '*.txt' 07:11:58 INFO - no previously-included directories found matching 'build' 07:11:58 INFO - no previously-included directories found matching 'dist' 07:11:58 INFO - no previously-included directories found matching '.git*' 07:11:58 INFO - Installing collected packages: jsonschema, functools32 07:11:58 INFO - Running setup.py install for functools32 07:11:58 INFO - warning: no files found matching '*.txt' 07:11:58 INFO - no previously-included directories found matching 'build' 07:11:58 INFO - no previously-included directories found matching 'dist' 07:11:58 INFO - no previously-included directories found matching '.git*' 07:11:58 INFO - Successfully installed jsonschema functools32 07:11:58 INFO - Cleaning up... 07:11:58 INFO - Return code: 0 07:11:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:58 INFO - 'HOME': '/Users/cltbld', 07:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:58 INFO - 'LOGNAME': 'cltbld', 07:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:58 INFO - 'MOZ_NO_REMOTE': '1', 07:11:58 INFO - 'NO_EM_RESTART': '1', 07:11:58 INFO - 'PAGER': '/bin/cat', 07:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:58 INFO - 'PWD': '/builds/slave/test', 07:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:58 INFO - 'SHELL': '/bin/bash', 07:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:11:58 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:58 INFO - 'USER': 'cltbld', 07:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:58 INFO - 'XPC_FLAGS': '0x0', 07:11:58 INFO - 'XPC_SERVICE_NAME': '0', 07:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:11:58 INFO - Cleaning up... 07:11:58 INFO - Return code: 0 07:11:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:58 INFO - 'HOME': '/Users/cltbld', 07:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:58 INFO - 'LOGNAME': 'cltbld', 07:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:58 INFO - 'MOZ_NO_REMOTE': '1', 07:11:58 INFO - 'NO_EM_RESTART': '1', 07:11:58 INFO - 'PAGER': '/bin/cat', 07:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:58 INFO - 'PWD': '/builds/slave/test', 07:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:58 INFO - 'SHELL': '/bin/bash', 07:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:11:58 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:58 INFO - 'USER': 'cltbld', 07:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:58 INFO - 'XPC_FLAGS': '0x0', 07:11:58 INFO - 'XPC_SERVICE_NAME': '0', 07:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:59 INFO - Downloading/unpacking blobuploader==1.2.4 07:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:01 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:02 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 07:12:02 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 07:12:02 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 07:12:02 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 07:12:02 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 07:12:02 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 07:12:02 INFO - Downloading docopt-0.6.1.tar.gz 07:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:03 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:03 INFO - Running setup.py install for blobuploader 07:12:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:03 INFO - Running setup.py install for requests 07:12:03 INFO - Running setup.py install for docopt 07:12:03 INFO - Successfully installed blobuploader requests docopt 07:12:03 INFO - Cleaning up... 07:12:03 INFO - Return code: 0 07:12:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:03 INFO - 'HOME': '/Users/cltbld', 07:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:03 INFO - 'LOGNAME': 'cltbld', 07:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:03 INFO - 'MOZ_NO_REMOTE': '1', 07:12:03 INFO - 'NO_EM_RESTART': '1', 07:12:03 INFO - 'PAGER': '/bin/cat', 07:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:03 INFO - 'PWD': '/builds/slave/test', 07:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:03 INFO - 'SHELL': '/bin/bash', 07:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:12:03 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:03 INFO - 'USER': 'cltbld', 07:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:03 INFO - 'XPC_FLAGS': '0x0', 07:12:03 INFO - 'XPC_SERVICE_NAME': '0', 07:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ImJoR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-8T9H6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-fLnus8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-278_rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-quebyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-CIkpE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-nG0vSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-vP9UMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-55xJEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-JSWGGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-2MvJ9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-qcvPlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-AZB43R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-dJXf_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-4T3nzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-g1eJOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:05 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-jkpkfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:05 INFO - Running setup.py install for manifestparser 07:12:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozcrash 07:12:06 INFO - Running setup.py install for mozdebug 07:12:06 INFO - Running setup.py install for mozdevice 07:12:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozfile 07:12:06 INFO - Running setup.py install for mozhttpd 07:12:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozinfo 07:12:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozInstall 07:12:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Running setup.py install for mozleak 07:12:07 INFO - Running setup.py install for mozlog 07:12:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Running setup.py install for moznetwork 07:12:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Running setup.py install for mozprocess 07:12:07 INFO - Running setup.py install for mozprofile 07:12:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Running setup.py install for mozrunner 07:12:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:08 INFO - Running setup.py install for mozscreenshot 07:12:08 INFO - Running setup.py install for moztest 07:12:08 INFO - Running setup.py install for mozversion 07:12:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:08 INFO - Cleaning up... 07:12:08 INFO - Return code: 0 07:12:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12: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')]} 07:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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')]} 07:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:08 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 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:12:08 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 07:12:08 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 07:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:08 INFO - 'HOME': '/Users/cltbld', 07:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:08 INFO - 'LOGNAME': 'cltbld', 07:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:08 INFO - 'MOZ_NO_REMOTE': '1', 07:12:08 INFO - 'NO_EM_RESTART': '1', 07:12:08 INFO - 'PAGER': '/bin/cat', 07:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:08 INFO - 'PWD': '/builds/slave/test', 07:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:08 INFO - 'SHELL': '/bin/bash', 07:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:12:08 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:08 INFO - 'USER': 'cltbld', 07:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:08 INFO - 'XPC_FLAGS': '0x0', 07:12:08 INFO - 'XPC_SERVICE_NAME': '0', 07:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-3yCMv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-4L3_DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:09 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)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-m8dd_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:09 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)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-7lfJNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:09 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)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-FZNRid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:09 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)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Sm7JI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:09 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)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-hDvxpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:09 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)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-PQPmkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:09 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)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-GHSIIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-aYMFHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Ty7Tsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:10 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-K4qBJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:10 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)) 07:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:10 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-JjFDOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:10 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)) 07:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:10 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-s5fbvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:10 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)) 07:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:10 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-wAN7vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:10 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)) 07:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:10 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-fplerk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:10 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)) 07:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:10 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-xBaM1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:10 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)) 07:12:10 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)) 07:12:10 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)) 07:12:10 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)) 07:12:10 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)) 07:12:10 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)) 07:12:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:10 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 07:12:10 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 07:12:10 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 07:12:10 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 07:12:10 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 07:12:10 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 07:12:13 INFO - Downloading blessings-1.6.tar.gz 07:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:12:13 INFO - Installing collected packages: blessings 07:12:13 INFO - Running setup.py install for blessings 07:12:13 INFO - Successfully installed blessings 07:12:13 INFO - Cleaning up... 07:12:13 INFO - Return code: 0 07:12:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:13 INFO - Reading from file tmpfile_stdout 07:12:13 INFO - Current package versions: 07:12:13 INFO - blessings == 1.6 07:12:13 INFO - blobuploader == 1.2.4 07:12:13 INFO - docopt == 0.6.1 07:12:13 INFO - functools32 == 3.2.3-2 07:12:13 INFO - jsonschema == 2.5.1 07:12:13 INFO - manifestparser == 1.1 07:12:13 INFO - mozInstall == 1.12 07:12:13 INFO - mozcrash == 1.0 07:12:13 INFO - mozdebug == 0.1 07:12:13 INFO - mozdevice == 0.48 07:12:13 INFO - mozfile == 1.2 07:12:13 INFO - mozhttpd == 0.7 07:12:13 INFO - mozinfo == 0.9 07:12:13 INFO - mozleak == 0.1 07:12:13 INFO - mozlog == 3.4 07:12:13 INFO - moznetwork == 0.27 07:12:13 INFO - mozprocess == 0.23 07:12:13 INFO - mozprofile == 0.28 07:12:13 INFO - mozrunner == 6.13 07:12:13 INFO - mozscreenshot == 0.1 07:12:13 INFO - mozsystemmonitor == 0.3 07:12:13 INFO - moztest == 0.7 07:12:13 INFO - mozversion == 1.4 07:12:13 INFO - psutil == 3.1.1 07:12:13 INFO - requests == 1.2.3 07:12:13 INFO - wsgiref == 0.1.2 07:12:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:14 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')]} 07:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:14 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 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:12:14 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 07:12:14 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 07:12:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:12:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:14 INFO - 'HOME': '/Users/cltbld', 07:12:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:14 INFO - 'LOGNAME': 'cltbld', 07:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:14 INFO - 'MOZ_NO_REMOTE': '1', 07:12:14 INFO - 'NO_EM_RESTART': '1', 07:12:14 INFO - 'PAGER': '/bin/cat', 07:12:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:14 INFO - 'PWD': '/builds/slave/test', 07:12:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:14 INFO - 'SHELL': '/bin/bash', 07:12:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:12:14 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:12:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:14 INFO - 'USER': 'cltbld', 07:12:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:14 INFO - 'XPC_FLAGS': '0x0', 07:12:14 INFO - 'XPC_SERVICE_NAME': '0', 07:12:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 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)) 07:12:14 INFO - Cleaning up... 07:12:14 INFO - Return code: 0 07:12:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:12:14 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')]} 07:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:14 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')]} 07:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103feb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe032d56680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, '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.hsgKLVPcMG/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.iij93fB2qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 07:12: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', 'jsonschema'] in /builds/slave/test/build 07:12: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 jsonschema 07:12:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:12:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:15 INFO - 'HOME': '/Users/cltbld', 07:12:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:15 INFO - 'LOGNAME': 'cltbld', 07:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:15 INFO - 'MOZ_NO_REMOTE': '1', 07:12:15 INFO - 'NO_EM_RESTART': '1', 07:12:15 INFO - 'PAGER': '/bin/cat', 07:12:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:15 INFO - 'PWD': '/builds/slave/test', 07:12:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:15 INFO - 'SHELL': '/bin/bash', 07:12:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:12:15 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:12:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:15 INFO - 'USER': 'cltbld', 07:12:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:15 INFO - 'XPC_FLAGS': '0x0', 07:12:15 INFO - 'XPC_SERVICE_NAME': '0', 07:12:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:12:15 INFO - Cleaning up... 07:12:15 INFO - Return code: 0 07:12:15 INFO - Running post-action listener: _resource_record_post_action 07:12:15 INFO - Running post-action listener: _start_resource_monitoring 07:12:15 INFO - Starting resource monitoring. 07:12:15 INFO - [mozharness: 2016-11-14 15:12:15.646368Z] Finished create-virtualenv step (success) 07:12:15 INFO - [mozharness: 2016-11-14 15:12:15.647202Z] Running install step. 07:12:15 INFO - Running pre-action listener: _resource_record_pre_action 07:12:15 INFO - Running main action method: install 07:12:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:15 INFO - Reading from file tmpfile_stdout 07:12:15 INFO - Detecting whether we're running mozinstall >=1.0... 07:12:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:12:16 INFO - Reading from file tmpfile_stdout 07:12:16 INFO - Output received: 07:12:16 INFO - Usage: mozinstall [options] installer 07:12:16 INFO - Options: 07:12:16 INFO - -h, --help show this help message and exit 07:12:16 INFO - -d DEST, --destination=DEST 07:12:16 INFO - Directory to install application into. [default: 07:12:16 INFO - "/builds/slave/test"] 07:12:16 INFO - --app=APP Application being installed. [default: firefox] 07:12:16 INFO - mkdir: /builds/slave/test/build/application 07:12:16 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'] 07:12:16 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 07:12:47 INFO - Reading from file tmpfile_stdout 07:12:47 INFO - Output received: 07:12:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:12:47 INFO - Running post-action listener: _resource_record_post_action 07:12:47 INFO - [mozharness: 2016-11-14 15:12:47.979160Z] Finished install step (success) 07:12:47 INFO - [mozharness: 2016-11-14 15:12:47.979290Z] Running run-tests step. 07:12:47 INFO - Running pre-action listener: _resource_record_pre_action 07:12:47 INFO - Running main action method: run_tests 07:12:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:12:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:12:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:12:47 INFO - 2016-11-14 07:12:47.992 screenresolution[1643:7582] starting screenresolution argv=screenresolution get 07:12:47 INFO - 2016-11-14 07:12:47.994 screenresolution[1643:7582] Display 0: 1600x1200x32@60 07:12:48 INFO - 2016-11-14 07:12:48.000 screenresolution[1644:7584] starting screenresolution argv=screenresolution list 07:12:48 INFO - Available Modes on Display 0 07:12:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:12:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:12:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:12:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:12:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:12:48 INFO - Intel Iris: 07:12:48 INFO - Chipset Model: Intel Iris 07:12:48 INFO - Type: GPU 07:12:48 INFO - Bus: Built-In 07:12:48 INFO - VRAM (Dynamic, Max): 1536 MB 07:12:48 INFO - Vendor: Intel (0x8086) 07:12:48 INFO - Device ID: 0x0a2e 07:12:48 INFO - Revision ID: 0x0009 07:12:48 INFO - Displays: 07:12:48 INFO - Display: 07:12:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:12:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:12:48 INFO - Display Serial Number: 1600x1200 60 07:12:48 INFO - Main Display: Yes 07:12:48 INFO - Mirror: Off 07:12:48 INFO - Online: Yes 07:12:48 INFO - Rotation: Supported 07:12:48 INFO - Return code: 0 07:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:12:48 INFO - Python 2.7.3 07:12:48 INFO - Return code: 0 07:12:48 INFO - grabbing minidump binary from tooltool 07:12:48 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')]} 07:12:48 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 0x103e89d30>, '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 0x103cefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e81b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:12:48 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 07:12:48 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 07:12:48 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 07:12:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:12:48 INFO - Return code: 0 07:12:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:12:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:12:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:12:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:12:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:12:48 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/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 07:12:48 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/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0163 --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 07:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hsgKLVPcMG/Render', 07:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:48 INFO - 'HOME': '/Users/cltbld', 07:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:48 INFO - 'LOGNAME': 'cltbld', 07:12:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:12:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:48 INFO - 'MOZ_NO_REMOTE': '1', 07:12:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:12:48 INFO - 'NO_EM_RESTART': '1', 07:12:48 INFO - 'PAGER': '/bin/cat', 07:12:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:48 INFO - 'PWD': '/builds/slave/test', 07:12:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:48 INFO - 'SHELL': '/bin/bash', 07:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iij93fB2qk/Listeners', 07:12:48 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 07:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:48 INFO - 'USER': 'cltbld', 07:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:48 INFO - 'XPC_FLAGS': '0x0', 07:12:48 INFO - 'XPC_SERVICE_NAME': '0', 07:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:48 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/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 07:12:48 INFO - mozversion application_buildid: 20161114043447 07:12:48 INFO - mozversion application_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 07:12:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:12:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:12:48 INFO - mozversion application_name: Firefox 07:12:48 INFO - mozversion application_remotingname: firefox-dev 07:12:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:12:48 INFO - mozversion application_vendor: Mozilla 07:12:48 INFO - mozversion application_version: 52.0a2 07:12:48 INFO - mozversion platform_buildid: 20161114043447 07:12:48 INFO - mozversion platform_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 07:12:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:12:48 INFO - mozversion platform_version: 52.0a2 07:12:48 INFO - using testdate: 1479136368 07:12:48 INFO - actual date: 1479136368 07:12:48 INFO - starting webserver on 'localhost:49272' 07:12:48 INFO - SUITE-START | Running 2 tests 07:12:48 INFO - TEST-START | dromaeo_css 07:12:48 INFO - operating with platform_type : mac_ 07:12:48 INFO - Initialising browser for dromaeo_css test... 07:12:48 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpipkcM8/profile http://localhost:49272/getInfo.html) 07:12:50 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 07:12:51 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 07:12:51 INFO - PROCESS | 1654 | colorDepth:24 07:12:51 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 07:12:51 INFO - PROCESS | 1654 | __metrics 07:12:51 INFO - PROCESS | 1654 | 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" 07:12:51 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:12:52 INFO - TEST-INFO | 1654: exit 0 07:12:52 INFO - Browser initialized. 07:12:52 INFO - Running cycle 1/1 for dromaeo_css test... 07:12:52 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpipkcM8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:04 INFO - PROCESS | 1656 | RSS: Main: 217567232 07:13:04 INFO - PROCESS | 1656 | 07:13:55 INFO - PROCESS | 1656 | 1479136435706 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] 07:16:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 07:16:10 INFO - PROCESS | 1656 | RSS: Main: 362528768 07:16:10 INFO - PROCESS | 1656 | 07:19:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 07:19:13 INFO - PROCESS | 1656 | RSS: Main: 355573760 07:19:13 INFO - PROCESS | 1656 | 07:22:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 07:22:16 INFO - PROCESS | 1656 | RSS: Main: 353972224 07:22:16 INFO - PROCESS | 1656 | 07:25:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 07:25:19 INFO - PROCESS | 1656 | RSS: Main: 327909376 07:25:19 INFO - PROCESS | 1656 | 07:28:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 07:28:23 INFO - PROCESS | 1656 | RSS: Main: 315445248 07:28:23 INFO - PROCESS | 1656 | 07:31:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 07:31:27 INFO - PROCESS | 1656 | RSS: Main: 414695424 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | __start_tp_report 07:31:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 07:31:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 07:31:27 INFO - PROCESS | 1656 | |i|pagename|runs| 07:31:27 INFO - PROCESS | 1656 | |0;dojo.html;3142.9057128286154;3275.9508607370894;3278.5082237664355;3298.5052242163656;3328.251143492713;5587.692676902769;5604.831855044345;5626.578006649503;5635.520980716644;5677.574181936361;5594.524465420434;5669.319681638209;5678.914816277759;5682.829515114543;5718.428157184279;5106.923396149061;5121.641485096045;5148.922766158511;5177.870553236162;5203.661761985478;5044.924326135111;5065.366829146356;5071.695698258116;5248.160294352904;5303.496167864047;5120.667156634807;5183.999999999981;5196.974015129919;5203.895922081556;5214.217867319894;5213.817516386946;5221.947780522222;5247;5261.15821468567;5289.338832645919;5061.190209566452;5073.594112470993;5105.5149760772665;5198.116320225571;5213.244079608474;5536.695481748502;5555.638883472561;5601.663900166003;5609.551235901118;5627.2684551008515;6184.010558310693;6215.87568248637;6217.5336849735995;6263.217097862767;6280.371962803729;6272.65500397478;6295.244570651551;6350.110984462132;6352.301246862841;6353.174087368612;4744.905101898012;5635.6055076144685;5728.341240757283;5797.739101740432;5800.274965629296;6281.748730050746;6300.30696623371;6301.495880329563;6308.085327627468;6331.335209802996;6137.110119032715;6138.785142519991;6141.938580614136;6144.262688477322;6159.630422174684;5090.2364645303505;6108.450239478469;6147.631142131487;6150.446459818638;6155.6614386209185;5340.584745042567;6078.635281883013;6191.9690401547705;6235.376462353728;6238.469730072904;6171.074338849119;6205.627662340274;6236.095766113979;6263.3736626337;6273.905891411632;4788;4851.369321988119;4860.295257187759;4968.8757781055765;4981.526754958273;4388.210122178039;4395.780210989437;4418.16054949565;4432;4438.667099967515;5654.37801841797;5672.914906276409;5726.141078838207;5750.741216645261;5836.387179346192;6055.122007308915;6269.968650156811;6300.684965751694;6305.054241863757;6326.68366581669;5638.984982703153;5673.517750991129;5734.483896449339;5740.483356497852;5757.913631295534;4565.908681826345;5226.451222621646;5296.523312901781;5305.31030965972;5354.277172581729;6173.567850250529;6197.938020619917;6208.4722798562625;6264.749410023639;6275.497960163268;6235.625862448268;6277.1525844010475;6277.466415354746;6286.748530058747;6294.71673774672;6096.329403765493;6231.906521402092;6256.687165641791;6289.245290565162;6304.243490780952;5872.530197584098;5998.4601385875485;6039.305479869696;6108.554560543837;6114;6079.574429789872;6227.283862355887;6233.812985610439;6235.220597425322;6240.219972503437;5973.253343332083;6007.669578173242;6023.728932198149;6041;6061.636301821904;5872.765089396376;5938.257717785277;6068.575199735976;6091.725872335667;6127.3566275540425;6012.098185272244;6017.729202185825;6026.035834266497;6029.849253768691;6048.969755151372;5913.556483263858;6120.173776540289;6158.137860699595;6165.167702360303;6172.969135154295;5213.40045894727;5697.945880012211;5838.241028666246;5872.383399742966;5901.1443340714495 07:31:27 INFO - PROCESS | 1656 | |1;ext.html;481.3597914773352;485.1170869018388;499.7051739473712;512.3314075131954;529.4678847758003;24572.631410528833;30551.694483055162;38349.80825095874;39574;39627.40558891619;35212.64787352126;40529.18941621173;40857.97855053618;41189;41238.793806030924;9243.768905777342;9388.812223755522;9403.106704863027;9404.81190376192;9424.905750942506;8543.017552981393;8765.780855478632;9154.588043538024;9281.211097056725;9299.11658392455;9234.445933243993;9297.674581389652;9300.93039300479;9312.394694344846;9312.81374372512;7767.262110099531;7833.412494062968;8121.862939188518;9204.861927071066;9280.628774849032;9263.953680231623;9335.626574936994;9339.626414943394;9340.065993400674;9356.485393303366;38587.22825543502;38925.61074389248;38974.22051558981;39039.8048009761;39099.804500977596;3311.983440082808;3469.62216023509;3497.283056973314;3572.2319701264355;3645.2162785001087;28305.762570580846;29280.560791588145;29967.550486742715;30193.698063019307;30669;3133.8746450142194;3176.857041433141;3179.2528755742383;3213.389456003352;3217.356528694247;464344;464662;465232;465653.6717316392;468414;32278;56913.71543142341;57345;57440;57469.425305747245;33513;34411.4838277426;34742.47886281708;34770.130746731535;34777.82611086928;13312.534061308;13615;14277.42890284377;14441.205733684748;14486.85513144855;12136.453859576164;12231.449584768707;12231.571894983634;12233.082518811125;12250.081243906565;6956.234814170437;7590.582517961455;7632.1986191450205;7685.577293248925;7691.077070751546;3112.7042930921525;3206.246532064963;3214.7588930829816;3218.34024025079;3244;934.8644446555212;999.9950000249952;1028.1209565821264;1046.0480962324248;1051.4112097225577;28181.71818281791;29296.974605889118;29597.74542598819;29803.403931921675;29806.403871922874;1020.4132623741378;1038.9636362727267;1084.4848696868924;1085.3596378136779;1093.573506332531;14852.480163194236;15191.544253672408;15254.542363729106;15596;15679.608009799846;594215;967924;976346;977126;980385;57812;58465.41534584685;58527.82944341174;58674.59940504479;58678.11982820261;966.9468179250209;1161.2161790791183;1165.469711281348;1353.3977380065776;1373.6359864635879;22572.88713556422;30372.088837334915;31116.91090811811;31385.664437046213;31758.523622145225;55091.724541377036;55703.721481392335;55709.721451392485;55717.72141139268;55774.44225557693;617023;620824.8958755177;621361;623605;624676;26553.468930621664;26588.069417570292;26812;26900;26905.461890762468;45092;45437.545624544655;45634.54365456303;46013.539864602266;46025.30962035639;36863.89408317755;37385.43921841229;37757;38237;38736;37145.88562343134;37239.813800930824;37762.622373775914;37902;38214.426783597715;36495.63504364922;36873.262534749694;36906.44640330344;37355.626443735215;37502.06244843901;32113.678863212004;33274.33451330911;33732.83133584316;33907.321853563284;34064.31871362509;20324.43987174206;24750.504989900463;24841.50316993614;25198.496030079663;25276.873615631805 07:31:27 INFO - PROCESS | 1656 | |2;jquery.html;348.6461241839535;368.3939918833519;369.7504184675342;380.9790461524616;399.17769395046207;11897.405129743522;14960.700785984287;15646.217689115556;19517.707234391495;20249.380049596035;19245.903770481127;19439.930803805786;19913.3030343938;20146.59706805863;20185.394438166877;5701.087825947839;5956.434138756811;6010.308814486324;6029.155918171437;6035.124906888498;5488.313960754906;5845.912311315313;5922.851928701773;5924.081767326072;5929.703514824263;3667.413213885779;4088.5911408859174;4298.9785051074705;4341.826326946934;5851.502622277111;4290.206311832306;5620.550355971533;6450.548461607689;6468.902966455507;6540.05169250456;5436.619436639437;5650.6892120933335;5702.4582664647205;5715.085586306171;5717.857053573653;24226.51546969051;24406.38984025396;24734.752652473424;25199;25343.619845702146;2258.1306197114063;2320.1415476273887;2330.1686607756583;2423.3941514621347;2965.0808249442744;14901.552953411416;14912.62718432037;14991.775123373158;15049.172295523742;15650.843491565167;1767.6022894848684;1854.9258029678797;1858.7676540432446;1892.8201820827137;1922.4905400068992;91371;108125.91874081158;118943.21585176229;120853;121880.39059804734;10009.749756256006;10212.080912717742;10959.397233152253;11022.889771102185;11421.428928553705;13915.860841391659;13924.791128133085;13930.86069139316;13951.37218825135;13971.301434928213;8114.513129212266;8416.53709045996;8443.366747493963;8505.404621676547;8513.872291915626;8378.41351105429;8383.413161078666;8396.874046889301;8531.445456045338;8539.06070332263;6468.54720169593;6608.471322294205;6617.404433600999;6634.336566343327;6741.629210393377;1763.938262160818;1821.3261093395529;1825.853931685462;1827.2051657529018;1836.4582448177819;785.9921400786034;921.7557347302836;938.4932136646271;948.9762755931018;968.7868668892833;14847.331870065873;21361.465963351042;21508.462288442603;21663.35009949704;21679.34961951144;869.5478351257311;886.7295474880224;921.8985911549725;929.149827907457;936.8032713130319;9999.700008999742;11406.885931140749;11480.024197943101;11501.194916355942;11532.192746507832;608116;623171;625890;626471;638279;135180.3240983789;137593.3120334392;139230;139842;143745.62543745543;965.4062751407834;996.9202463802878;1005.9698209053887;1013.9442330671736;1014.5485259059648;16748.497545073667;18839.529011774815;19290.7106393407;19356.4193074208;19357.806421935602;40824.79587602043;50325.74837125791;52583.73708131435;53536.732316338166;55869.720651396485;81349.37301253823;82227.7665835025;83001.16998829934;83479.74780378178;84672;6436;7351.963240183979;7721.459497835098;7962.203779621991;7976.680932762857;8781.29749620016;11702.069158588738;12481.126321157431;12658.303793291458;12844.614661560168;9359.532023398939;10786.73033174177;11094.5562177512;11116.722081947693;11173.441327933733;10582.894171058191;10782.353058816496;10930.45347732626;11329.150313726668;11347.829782553103;10149.84775228387;10917.853625369222;11024.5038973248;11200.10399168081;11405.885941140814;7735.458517903693;7806.024246969129;7937.365010799238;8153.02163740355;8171.223733745284;5261.605379596469;7960.283574478325;10813;10903.672889813319;11124.499397527292 07:31:27 INFO - PROCESS | 1656 | |3;mootools.html;1311.7638825011497;1326.781081121615;1467.039089396445;1610.5973506623345;1842.4748946550235;7452.366548843348;7823.256790604891;8034.236747508985;8372.958135209316;8375.83248335033;8256.422050456455;8348.624311905964;8356.665733370673;8368.665253389872;8376.45553039052;2288.5422915416943;2324.0471406723245;2327.418145463634;2328.3364241191266;2330.6154484510034;2302.4013756423365;2348.96476552851;2353.4351755578623;2358;2359.4809141988735;1990.1740777577288;2301.827362947777;2303.9078436862505;2316.444053427182;2329.1382188590246;1941.7475728155391;2223.7442694090146;2256.311824893407;2290.793828555438;2295.747467778543;2302.861828290308;2326.325045966901;2329.918452854142;2331.358876309029;2333.5332933413383;7302.780916572511;8246.670133194724;8378.8324233515;8395.370347223983;8400.873986890201;4896.95103048972;5087.287779710843;5106.310648062538;5107.642465027449;5119.923201151985;3282.2450836307544;3508.982455087734;3553.058439513531;3561.9643803561894;3575;1019.5666841592367;1039.3555995282898;1071.844582535536;1073.9140868730483;1080.4543705428707;10788.406637634927;10879.619213327574;10936.781264374747;10938.835917461245;10947.124230061656;4231.42875711775;4364.738115713068;4452.131834292347;4472.619827314649;4490.797914093873;8601.924759405074;8605;8605.483670979762;8612.8708069379;8620.439671421338;5062.949370506248;5071.721055341918;5072.442031376546;5073.822416215415;5080.441151473335;4008.5390180129075;4256.489221293465;4380.386745855582;4415.735055896592;4773.355596994361;3605.9819700901326;3621.5654121505063;3689.3912504436466;3700.093477098128;3718.1076541630355;978.554757585297;1135.9034482069117;1140.3044143072718;1140.8402823604702;1152.6426807689643;862.6800994478458;883.5847151838625;892.9955350223337;910.2354022620898;911.5852287209303;5303.178007408857;5484.7531861066345;5545.584081193846;5606.271184746039;5620.269364982526;424.613601622522;449.03009499480964;450.54720006393364;451.7447642082213;455.63321526171086;3661.176235347079;3672.3756961316108;3699.611540788232;3720.776753394805;3820.1977584706965;9502.667406640734;10038.196944244442;10040.598376064876;10056.849147262797;10057.195424366033;9885.703428897144;9915.55380007887;9938.055884690943;9978.251631127694;10021.949890250502;7737.187595302412;7820.7653770388115;7841;7847.921520784719;7891.684332626745;5342.9465705344;5440.129579267298;5492.5605951524585;5525.668459892419;5550.8057217997175;9278.907210927804;9292.210162136294;9382;9430.62277508892;9439.244860411014;8664.046954834837;8994.640214391351;9076.863847042168;9363.485008324335;9368.203702685349;6652.40128388428;6797.762078327235;6798;6810.114685090906;6811.523193376416;7584.696612135674;7853.489523180975;7867.291943725092;7885.605719714106;7969.6015199240965;7059.964700176466;7129.572225666477;7134.928650713427;7156.606386648784;7160.641967901688;6936.549124306904;7040.542364746275;7049.7885063448175;7086.362227399352;7092.822679433056;6588.670566471753;6731.495137864582;6733.427658648874;6733.797986060426;6741;4711.976440117777;4730.834420795393;4821.276808478616;4864.221724524059;4923.0646177227645;4679;4784.258439941815;4851.9514804852915;4895;4988.6757360771435 07:31:27 INFO - PROCESS | 1656 | |4;prototype.html;298.85505529818033;302.18410292211036;307.8860821496046;311.2343634658739;312.99530507042414;5155.149400348942;5193.896122077556;5240.34495688039;5265.368155821307;5299.443558426367;4862.805487780484;4963.801447942078;5026.924596131061;5034.82378116766;5180.378354597454;3328.7170590499836;3970.424288478169;3983.800809959505;3984.482017337742;4016.8869126427107;3634.7637403568815;3957.1887763008513;3969.1543432303974;3984.7808370539756;3994.4208089826957;3829.6553310202075;3938.4289278054666;3970.8610198643055;3985.7010724195657;3987.900302492432;3423.400904841668;3685.1708365617524;3938.684905207605;3988.78061706606;3989.1622759220745;3448.9482657760154;3451.706604938583;3458.8962331130106;3460.480927860841;3467.7919324840336;4953.851384458472;5014.693241109057;5026.220935754964;5045.99080183961;5080.415752188509;3796.6772824309965;4135.524414692319;4547.636189104864;4553.612942899857;4555.79498922546;5400.838819653754;5409.323834520685;5418.810341638064;5430.972845135789;5447.782088716446;3382.6300348358955;3396.490526421032;3542.8485742133653;3830.9616903830884;3838.9808050959846;4246.723962942398;4803.823063349501;5571.415001424833;5674.262345895048;5686.640044381618;3139.795913265676;3387.271736576648;3786.95858638871;3838.5201849768778;3897.162110146332;3474.2530355973586;3640.399334109842;3932.9213415732097;3939.330313846653;3955.5870918739547;3862.4013277941963;4070.470838790938;4218.599233072853;4590.40324757786;4598.632109431237;4475.30632751924;4584.862454126382;5424.376196737426;5426.209350914189;5558.861028474321;1166.4571234708417;1182.9585964491203;1267.0370518406078;1275.4324325674986;1279.3731071774764;722.4112348436008;775.5269285735696;815.995920020408;851.7955690634203;883.6921356392575;4828.493008234155;4883.658143929962;4892.266160075946;4912.8280510182685;4928.260760885896;4334.544872788375;5209.869753256123;5293.894122117536;5372.70450125239;5380.461953804588;3910.608939106067;3997.4803275574573;4553.886152846206;4615.653825963066;4833.975830120898;2540.4919016196836;2748.8900443982416;2752.2431331383627;2761.2682639100653;2766.344309462898;4732.5977291929885;5394.870974834586;5400.702961337164;5410.781579552365;5411.783528658888;4447.688661793708;4669.909798319848;4791.5687588117235;5329.333833270841;5374.512282244292;4596.264597664424;4620.960283936088;4761.666683332133;5342.759575819097;5383.300170977748;4543.114092751882;4829.517048295143;5133.383993920754;5221.556167725634;5234.738263086907;4607.7004994675235;4870;5043.646944713835;5163.3545806774155;5173.715445650525;4962.848591144577;5382.604740890266;5412.618447882919;5416.783328666809;5418.729063546885;3483.6864682178725;3513.767334052729;3531.346700860349;3638.872639457606;3654.744167908221;5032.823851165192;5125.89793194465;5190.143626301769;5262.447443018428;5278.683279003272;5531.197976293495;5533.667979921218;5534.833954981357;5545.001899657939;5560.443955604407;4837.540433658797;5000.824971125993;5464.175077445724;5557.972210138923;5560.249366335493;4470.865874023784;4966.627502937222;5126.307948427064;5191.028862919945;5420.915816836728;4826.493218212037;4905.460399355997;5365.171345788821;5449.427810079885;5507.697076660822;4715.70318162508;5376.946230537645;5406.432324606017;5415.972920135374;5425.538829199404 07:31:27 INFO - PROCESS | 1656 | |5;yui.html;664.657701283839;707.8382678781209;726.444270133348;773.8104164479703;792.1128336263386;13676.316184190788;13711.31443427827;14034.157950522977;14171.929140354283;14260.144391336502;12536.934360579338;12659.683507912307;12660.936695316534;12664.556740514085;12682.985361171108;620.8230654263535;643.3020173112179;776.3672606825447;793.4208028139448;809.9676012959475;808.5552945879742;812.3176531713359;814.2386868278179;814.9837003259931;815.351795322717;859.3511898516612;859.8366310401004;860.5224100624151;861.0399404663787;861.577826864838;655.7049327802484;677.7322957431836;679.1544527063837;680.2279412866383;856.019857263433;500.9799608015675;603.2369053147728;624.2290770897937;625.4183609243402;634.6160572853404;13565.796513052312;13567.728645427036;13569.660758481019;13571.1857288563;13606.659833504242;12169.817452738216;12342.876571234263;12452.31512266825;12460.75078498425;12623.936880315632;10361.948190259076;11741.354225517593;11951.820722689166;12107.091968102339;12242.938785306105;4273.50854651716;4315.30955047194;4399.692021558492;4439.866803995886;4464.977675111603;71836.28163718348;88707.55646221792;88758;89562.39353701218;92135.53932230362;8973.865392019125;10050.59797608102;10174.084332410119;10453.425061621683;10630.990055944672;19624.90187549053;19698.40904772859;20008.39974800758;20015.199392024155;20034.398968030982;7324;7333.303336183054;7395.778126656209;7424.109106907205;7619.276168763959;4059.239746681264;4100.302948498763;4127.772972486482;4179.895502612399;4263.083437060985;3175.25381535339;3285.195127193853;3290.8683652654104;3297.4064668359924;3304.966950330466;603.8973374526341;609.6524980760923;613.2395829171884;623.1120653069448;625.7465726380823;580.8431723434649;582.775631381921;594.6194435561245;609.5123900879279;633.2527617411407;7497.362724168507;8356.061741345624;8704.260137888225;8723.563821808884;8752.56237188138;631.9778807741799;634.80321100459;660.6762686283687;684.852756657321;688.435482904014;3465.5494785678206;4398.846040388571;4500.186265987831;4544.2047641662575;4673.626109911199;67016.26946614278;91679;103419;112042.3193652096;115238.42380788042;70762.2923770766;71282;74275.62862185763;87174.69237961435;90484.54757726169;56740.43259567351;56876.431235687116;70849.2915070853;77193.84209236866;81991.77012344939;9318.301127415336;9594.232461403211;9707.66023189185;9836.114749672564;9849.212063034796;42288.78855605702;44021.55978440175;45493.8626534326;49148.788304527094;50952.98094038173;65974;70033.64983175052;74124.62937685277;74836;76336;9967.601295948081;11921.569411670655;12042.518299267931;12232.510699571918;12397.946174575261;13912.647050079064;13940.302984850514;13967.30163491801;14076.296185190495;14232.003759737134;10778.892211078102;12700.237985720887;12826.166299190896;12875.742485150431;12887.033472489413;11855.644330670093;11965.700857478632;12831.743365132832;13067.085304028627;13656.385462654403;12249.326287054297;12787.360631968178;12938.611841644766;12976.416061277454;13525.797113043509;10842.728931776768;10985.83521247166;11221.831672525082;11347.716307092389;11415.657530274106;11055.778884422427;11246.212765106364;11439.199256051996;11477.942610286895;11532.25040372373 07:31:27 INFO - PROCESS | 1656 | __end_tp_report 07:31:27 INFO - PROCESS | 1656 | __start_cc_report 07:31:27 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1494 07:31:27 INFO - PROCESS | 1656 | __end_cc_report 07:31:27 INFO - PROCESS | 1656 | __startTimestamp1479137487189__endTimestamp 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | ------- Summary: start ------- 07:31:27 INFO - PROCESS | 1656 | Number of tests: 6 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5688.36 Median:5872.77 stddev:662.35 (11.3%) stddev-sans-first:633.57 07:31:27 INFO - PROCESS | 1656 | Values: 3142.9 3276.0 3278.5 3298.5 3328.3 5587.7 5604.8 5626.6 5635.5 5677.6 5594.5 5669.3 5678.9 5682.8 5718.4 5106.9 5121.6 5148.9 5177.9 5203.7 5044.9 5065.4 5071.7 5248.2 5303.5 5120.7 5184.0 5197.0 5203.9 5214.2 5213.8 5221.9 5247.0 5261.2 5289.3 5061.2 5073.6 5105.5 5198.1 5213.2 5536.7 5555.6 5601.7 5609.6 5627.3 6184.0 6215.9 6217.5 6263.2 6280.4 6272.7 6295.2 6350.1 6352.3 6353.2 4744.9 5635.6 5728.3 5797.7 5800.3 6281.7 6300.3 6301.5 6308.1 6331.3 6137.1 6138.8 6141.9 6144.3 6159.6 5090.2 6108.5 6147.6 6150.4 6155.7 5340.6 6078.6 6192.0 6235.4 6238.5 6171.1 6205.6 6236.1 6263.4 6273.9 4788.0 4851.4 4860.3 4968.9 4981.5 4388.2 4395.8 4418.2 4432.0 4438.7 5654.4 5672.9 5726.1 5750.7 5836.4 6055.1 6270.0 6300.7 6305.1 6326.7 5639.0 5673.5 5734.5 5740.5 5757.9 4565.9 5226.5 5296.5 5305.3 5354.3 6173.6 6197.9 6208.5 6264.7 6275.5 6235.6 6277.2 6277.5 6286.7 6294.7 6096.3 6231.9 6256.7 6289.2 6304.2 5872.5 5998.5 6039.3 6108.6 6114.0 6079.6 6227.3 6233.8 6235.2 6240.2 5973.3 6007.7 6023.7 6041.0 6061.6 5872.8 5938.3 6068.6 6091.7 6127.4 6012.1 6017.7 6026.0 6029.8 6049.0 5913.6 6120.2 6158.1 6165.2 6173.0 5213.4 5697.9 5838.2 5872.4 5901.1 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:76094.18 Median:37759.81 stddev:187140.72 (495.6%) stddev-sans-first:187579.58 07:31:27 INFO - PROCESS | 1656 | Values: 481.4 485.1 499.7 512.3 529.5 24572.6 30551.7 38349.8 39574.0 39627.4 35212.6 40529.2 40858.0 41189.0 41238.8 9243.8 9388.8 9403.1 9404.8 9424.9 8543.0 8765.8 9154.6 9281.2 9299.1 9234.4 9297.7 9300.9 9312.4 9312.8 7767.3 7833.4 8121.9 9204.9 9280.6 9264.0 9335.6 9339.6 9340.1 9356.5 38587.2 38925.6 38974.2 39039.8 39099.8 3312.0 3469.6 3497.3 3572.2 3645.2 28305.8 29280.6 29967.6 30193.7 30669.0 3133.9 3176.9 3179.3 3213.4 3217.4 464344.0 464662.0 465232.0 465653.7 468414.0 32278.0 56913.7 57345.0 57440.0 57469.4 33513.0 34411.5 34742.5 34770.1 34777.8 13312.5 13615.0 14277.4 14441.2 14486.9 12136.5 12231.4 12231.6 12233.1 12250.1 6956.2 7590.6 7632.2 7685.6 7691.1 3112.7 3206.2 3214.8 3218.3 3244.0 934.9 1000.0 1028.1 1046.0 1051.4 28181.7 29297.0 29597.7 29803.4 29806.4 1020.4 1039.0 1084.5 1085.4 1093.6 14852.5 15191.5 15254.5 15596.0 15679.6 594215.0 967924.0 976346.0 977126.0 980385.0 57812.0 58465.4 58527.8 58674.6 58678.1 966.9 1161.2 1165.5 1353.4 1373.6 22572.9 30372.1 31116.9 31385.7 31758.5 55091.7 55703.7 55709.7 55717.7 55774.4 617023.0 620824.9 621361.0 623605.0 624676.0 26553.5 26588.1 26812.0 26900.0 26905.5 45092.0 45437.5 45634.5 46013.5 46025.3 36863.9 37385.4 37757.0 38237.0 38736.0 37145.9 37239.8 37762.6 37902.0 38214.4 36495.6 36873.3 36906.4 37355.6 37502.1 32113.7 33274.3 33732.8 33907.3 34064.3 20324.4 24750.5 24841.5 25198.5 25276.9 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:35825.97 Median:14154.35 stddev:104251.36 (736.5%) stddev-sans-first:104509.78 07:31:27 INFO - PROCESS | 1656 | Values: 348.6 368.4 369.8 381.0 399.2 11897.4 14960.7 15646.2 19517.7 20249.4 19245.9 19439.9 19913.3 20146.6 20185.4 5701.1 5956.4 6010.3 6029.2 6035.1 5488.3 5845.9 5922.9 5924.1 5929.7 3667.4 4088.6 4299.0 4341.8 5851.5 4290.2 5620.6 6450.5 6468.9 6540.1 5436.6 5650.7 5702.5 5715.1 5717.9 24226.5 24406.4 24734.8 25199.0 25343.6 2258.1 2320.1 2330.2 2423.4 2965.1 14901.6 14912.6 14991.8 15049.2 15650.8 1767.6 1854.9 1858.8 1892.8 1922.5 91371.0 108125.9 118943.2 120853.0 121880.4 10009.7 10212.1 10959.4 11022.9 11421.4 13915.9 13924.8 13930.9 13951.4 13971.3 8114.5 8416.5 8443.4 8505.4 8513.9 8378.4 8383.4 8396.9 8531.4 8539.1 6468.5 6608.5 6617.4 6634.3 6741.6 1763.9 1821.3 1825.9 1827.2 1836.5 786.0 921.8 938.5 949.0 968.8 14847.3 21361.5 21508.5 21663.4 21679.3 869.5 886.7 921.9 929.1 936.8 9999.7 11406.9 11480.0 11501.2 11532.2 608116.0 623171.0 625890.0 626471.0 638279.0 135180.3 137593.3 139230.0 139842.0 143745.6 965.4 996.9 1006.0 1013.9 1014.5 16748.5 18839.5 19290.7 19356.4 19357.8 40824.8 50325.7 52583.7 53536.7 55869.7 81349.4 82227.8 83001.2 83479.7 84672.0 6436.0 7352.0 7721.5 7962.2 7976.7 8781.3 11702.1 12481.1 12658.3 12844.6 9359.5 10786.7 11094.6 11116.7 11173.4 10582.9 10782.4 10930.5 11329.2 11347.8 10149.8 10917.9 11024.5 11200.1 11405.9 7735.5 7806.0 7937.4 8153.0 8171.2 5261.6 7960.3 10813.0 10903.7 11124.5 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5348.09 Median:5067.34 stddev:2961.39 (58.4%) stddev-sans-first:2954.16 07:31:27 INFO - PROCESS | 1656 | Values: 1311.8 1326.8 1467.0 1610.6 1842.5 7452.4 7823.3 8034.2 8373.0 8375.8 8256.4 8348.6 8356.7 8368.7 8376.5 2288.5 2324.0 2327.4 2328.3 2330.6 2302.4 2349.0 2353.4 2358.0 2359.5 1990.2 2301.8 2303.9 2316.4 2329.1 1941.7 2223.7 2256.3 2290.8 2295.7 2302.9 2326.3 2329.9 2331.4 2333.5 7302.8 8246.7 8378.8 8395.4 8400.9 4897.0 5087.3 5106.3 5107.6 5119.9 3282.2 3509.0 3553.1 3562.0 3575.0 1019.6 1039.4 1071.8 1073.9 1080.5 10788.4 10879.6 10936.8 10938.8 10947.1 4231.4 4364.7 4452.1 4472.6 4490.8 8601.9 8605.0 8605.5 8612.9 8620.4 5062.9 5071.7 5072.4 5073.8 5080.4 4008.5 4256.5 4380.4 4415.7 4773.4 3606.0 3621.6 3689.4 3700.1 3718.1 978.6 1135.9 1140.3 1140.8 1152.6 862.7 883.6 893.0 910.2 911.6 5303.2 5484.8 5545.6 5606.3 5620.3 424.6 449.0 450.5 451.7 455.6 3661.2 3672.4 3699.6 3720.8 3820.2 9502.7 10038.2 10040.6 10056.8 10057.2 9885.7 9915.6 9938.1 9978.3 10021.9 7737.2 7820.8 7841.0 7847.9 7891.7 5342.9 5440.1 5492.6 5525.7 5550.8 9278.9 9292.2 9382.0 9430.6 9439.2 8664.0 8994.6 9076.9 9363.5 9368.2 6652.4 6797.8 6798.0 6810.1 6811.5 7584.7 7853.5 7867.3 7885.6 7969.6 7060.0 7129.6 7134.9 7156.6 7160.6 6936.5 7040.5 7049.8 7086.4 7092.8 6588.7 6731.5 6733.4 6733.8 6741.0 4712.0 4730.8 4821.3 4864.2 4923.1 4679.0 4784.3 4852.0 4895.0 4988.7 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4291.29 Median:4827.49 stddev:1298.39 (26.9%) stddev-sans-first:1266.98 07:31:27 INFO - PROCESS | 1656 | Values: 298.9 302.2 307.9 311.2 313.0 5155.1 5193.9 5240.3 5265.4 5299.4 4862.8 4963.8 5026.9 5034.8 5180.4 3328.7 3970.4 3983.8 3984.5 4016.9 3634.8 3957.2 3969.2 3984.8 3994.4 3829.7 3938.4 3970.9 3985.7 3987.9 3423.4 3685.2 3938.7 3988.8 3989.2 3448.9 3451.7 3458.9 3460.5 3467.8 4953.9 5014.7 5026.2 5046.0 5080.4 3796.7 4135.5 4547.6 4553.6 4555.8 5400.8 5409.3 5418.8 5431.0 5447.8 3382.6 3396.5 3542.8 3831.0 3839.0 4246.7 4803.8 5571.4 5674.3 5686.6 3139.8 3387.3 3787.0 3838.5 3897.2 3474.3 3640.4 3932.9 3939.3 3955.6 3862.4 4070.5 4218.6 4590.4 4598.6 4475.3 4584.9 5424.4 5426.2 5558.9 1166.5 1183.0 1267.0 1275.4 1279.4 722.4 775.5 816.0 851.8 883.7 4828.5 4883.7 4892.3 4912.8 4928.3 4334.5 5209.9 5293.9 5372.7 5380.5 3910.6 3997.5 4553.9 4615.7 4834.0 2540.5 2748.9 2752.2 2761.3 2766.3 4732.6 5394.9 5400.7 5410.8 5411.8 4447.7 4669.9 4791.6 5329.3 5374.5 4596.3 4621.0 4761.7 5342.8 5383.3 4543.1 4829.5 5133.4 5221.6 5234.7 4607.7 4870.0 5043.6 5163.4 5173.7 4962.8 5382.6 5412.6 5416.8 5418.7 3483.7 3513.8 3531.3 3638.9 3654.7 5032.8 5125.9 5190.1 5262.4 5278.7 5531.2 5533.7 5534.8 5545.0 5560.4 4837.5 5000.8 5464.2 5558.0 5560.2 4470.9 4966.6 5126.3 5191.0 5420.9 4826.5 4905.5 5365.2 5449.4 5507.7 4715.7 5376.9 5406.4 5416.0 5425.5 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:18850.33 Median:4522.20 stddev:26830.04 (593.3%) stddev-sans-first:26870.56 07:31:27 INFO - PROCESS | 1656 | Values: 664.7 707.8 726.4 773.8 792.1 13676.3 13711.3 14034.2 14171.9 14260.1 12536.9 12659.7 12660.9 12664.6 12683.0 620.8 643.3 776.4 793.4 810.0 808.6 812.3 814.2 815.0 815.4 859.4 859.8 860.5 861.0 861.6 655.7 677.7 679.2 680.2 856.0 501.0 603.2 624.2 625.4 634.6 13565.8 13567.7 13569.7 13571.2 13606.7 12169.8 12342.9 12452.3 12460.8 12623.9 10361.9 11741.4 11951.8 12107.1 12242.9 4273.5 4315.3 4399.7 4439.9 4465.0 71836.3 88707.6 88758.0 89562.4 92135.5 8973.9 10050.6 10174.1 10453.4 10631.0 19624.9 19698.4 20008.4 20015.2 20034.4 7324.0 7333.3 7395.8 7424.1 7619.3 4059.2 4100.3 4127.8 4179.9 4263.1 3175.3 3285.2 3290.9 3297.4 3305.0 603.9 609.7 613.2 623.1 625.7 580.8 582.8 594.6 609.5 633.3 7497.4 8356.1 8704.3 8723.6 8752.6 632.0 634.8 660.7 684.9 688.4 3465.5 4398.8 4500.2 4544.2 4673.6 67016.3 91679.0 103419.0 112042.3 115238.4 70762.3 71282.0 74275.6 87174.7 90484.5 56740.4 56876.4 70849.3 77193.8 81991.8 9318.3 9594.2 9707.7 9836.1 9849.2 42288.8 44021.6 45493.9 49148.8 50953.0 65974.0 70033.6 74124.6 74836.0 76336.0 9967.6 11921.6 12042.5 12232.5 12397.9 13912.6 13940.3 13967.3 14076.3 14232.0 10778.9 12700.2 12826.2 12875.7 12887.0 11855.6 11965.7 12831.7 13067.1 13656.4 12249.3 12787.4 12938.6 12976.4 13525.8 10842.7 10985.8 11221.8 11347.7 11415.7 11055.8 11246.2 11439.2 11477.9 11532.3 07:31:27 INFO - PROCESS | 1656 | -------- Summary: end -------- 07:31:27 INFO - PROCESS | 1656 | 07:31:27 INFO - TEST-INFO | 1656: exit 0 07:31:27 INFO - TEST-OK | dromaeo_css | took 1119199ms 07:31:27 INFO - TEST-START | kraken 07:31:27 INFO - operating with platform_type : mac_ 07:31:27 INFO - Initialising browser for kraken test... 07:31:27 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpIS8d7B/profile http://localhost:49272/getInfo.html) 07:31:29 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 07:31:30 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 07:31:30 INFO - PROCESS | 1667 | colorDepth:24 07:31:30 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 07:31:30 INFO - PROCESS | 1667 | __metrics 07:31:30 INFO - PROCESS | 1667 | 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" 07:31:30 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:31:31 INFO - TEST-INFO | 1667: exit 0 07:31:31 INFO - Browser initialized. 07:31:31 INFO - Running cycle 1/1 for kraken test... 07:31:31 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpIS8d7B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:43 INFO - PROCESS | 1668 | RSS: Main: 217305088 07:31:43 INFO - PROCESS | 1668 | 07:31:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 07:31:46 INFO - PROCESS | 1668 | RSS: Main: 267087872 07:31:46 INFO - PROCESS | 1668 | 07:31:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 07:31:50 INFO - PROCESS | 1668 | RSS: Main: 276889600 07:31:50 INFO - PROCESS | 1668 | 07:31:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 07:31:54 INFO - PROCESS | 1668 | RSS: Main: 375083008 07:31:54 INFO - PROCESS | 1668 | 07:31:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 07:31:56 INFO - PROCESS | 1668 | RSS: Main: 277139456 07:31:56 INFO - PROCESS | 1668 | 07:31:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:31:59 INFO - PROCESS | 1668 | RSS: Main: 278204416 07:31:59 INFO - PROCESS | 1668 | 07:32:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 07:32:02 INFO - PROCESS | 1668 | RSS: Main: 318296064 07:32:02 INFO - PROCESS | 1668 | 07:32:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 07:32:06 INFO - PROCESS | 1668 | RSS: Main: 318648320 07:32:06 INFO - PROCESS | 1668 | 07:32:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 07:32:09 INFO - PROCESS | 1668 | RSS: Main: 317177856 07:32:09 INFO - PROCESS | 1668 | 07:32:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:32:12 INFO - PROCESS | 1668 | RSS: Main: 296263680 07:32:12 INFO - PROCESS | 1668 | 07:32:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:32:14 INFO - PROCESS | 1668 | RSS: Main: 295731200 07:32:14 INFO - PROCESS | 1668 | 07:32:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:32:17 INFO - PROCESS | 1668 | RSS: Main: 306814976 07:32:17 INFO - PROCESS | 1668 | 07:32:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:32:21 INFO - PROCESS | 1668 | RSS: Main: 316071936 07:32:21 INFO - PROCESS | 1668 | 07:32:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:32:24 INFO - PROCESS | 1668 | RSS: Main: 310054912 07:32:24 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 07:32:26 INFO - PROCESS | 1668 | RSS: Main: 354238464 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | __start_tp_report 07:32:26 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 07:32:26 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 07:32:26 INFO - PROCESS | 1668 | |i|pagename|runs| 07:32:26 INFO - PROCESS | 1668 | |0;ai-astar;113;115;115;119;161;169;112;113;114;114 07:32:26 INFO - PROCESS | 1668 | |1;audio-beat-detection;142;117;140;183;127;111;121;136;173;112 07:32:26 INFO - PROCESS | 1668 | |2;audio-dft;183;186;182;185;196;261;199;185;183;184 07:32:26 INFO - PROCESS | 1668 | |3;audio-fft;83;59;59;91;61;58;59;59;84;83 07:32:26 INFO - PROCESS | 1668 | |4;audio-oscillator;88;70;97;71;69;68;88;68;69;92 07:32:26 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;87;88;86;89;86;89;87;89;85 07:32:26 INFO - PROCESS | 1668 | |6;imaging-darkroom;95;98;98;97;99;97;123;161;109;98 07:32:26 INFO - PROCESS | 1668 | |7;imaging-desaturate;81;82;82;82;85;82;86;85;81;82 07:32:26 INFO - PROCESS | 1668 | |8;json-parse-financial;55;55;54;54;57;57;58;56;56;57 07:32:26 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;78;103;110;93;73;72;71;71;73 07:32:26 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;86;87;89;88;88;83;88;92;92;85 07:32:26 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;149;140;148;155;154;147;144;150;191;159 07:32:26 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;167;168;169;167;169;168;172;163;220;172 07:32:26 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;67;68;66;66;67;67;68;77;86;87 07:32:26 INFO - PROCESS | 1668 | __end_tp_report 07:32:26 INFO - PROCESS | 1668 | __start_cc_report 07:32:26 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2065 07:32:26 INFO - PROCESS | 1668 | __end_cc_report 07:32:26 INFO - PROCESS | 1668 | __startTimestamp1479137546971__endTimestamp 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | ------- Summary: start ------- 07:32:26 INFO - PROCESS | 1668 | Number of tests: 14 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:124.50 Median:115.00 stddev:21.51 (18.7%) stddev-sans-first:22.41 07:32:26 INFO - PROCESS | 1668 | Values: 113.0 115.0 115.0 119.0 161.0 169.0 112.0 113.0 114.0 114.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:138.00 stddev:24.72 (17.9%) stddev-sans-first:26.13 07:32:26 INFO - PROCESS | 1668 | Values: 142.0 117.0 140.0 183.0 127.0 111.0 121.0 136.0 173.0 112.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:194.40 Median:185.50 stddev:24.10 (13.0%) stddev-sans-first:25.21 07:32:26 INFO - PROCESS | 1668 | Values: 183.0 186.0 182.0 185.0 196.0 261.0 199.0 185.0 183.0 184.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:69.60 Median:72.00 stddev:13.67 (19.0%) stddev-sans-first:13.61 07:32:26 INFO - PROCESS | 1668 | Values: 83.0 59.0 59.0 91.0 61.0 58.0 59.0 59.0 84.0 83.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.50 stddev:11.70 (14.7%) stddev-sans-first:11.84 07:32:26 INFO - PROCESS | 1668 | Values: 88.0 70.0 97.0 71.0 69.0 68.0 88.0 68.0 69.0 92.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.50 stddev:1.51 (1.7%) stddev-sans-first:1.50 07:32:26 INFO - PROCESS | 1668 | Values: 89.0 87.0 88.0 86.0 89.0 86.0 89.0 87.0 89.0 85.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:97.50 stddev:20.61 (21.1%) stddev-sans-first:21.36 07:32:26 INFO - PROCESS | 1668 | Values: 95.0 98.0 98.0 97.0 99.0 97.0 123.0 161.0 109.0 98.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:82.00 stddev:1.81 (2.2%) stddev-sans-first:1.80 07:32:26 INFO - PROCESS | 1668 | Values: 81.0 82.0 82.0 82.0 85.0 82.0 86.0 85.0 81.0 82.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.37 (2.4%) stddev-sans-first:1.41 07:32:26 INFO - PROCESS | 1668 | Values: 55.0 55.0 54.0 54.0 57.0 57.0 58.0 56.0 56.0 57.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:72.50 stddev:15.55 (21.4%) stddev-sans-first:15.26 07:32:26 INFO - PROCESS | 1668 | Values: 64.0 78.0 103.0 110.0 93.0 73.0 72.0 71.0 71.0 73.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:88.00 stddev:2.82 (3.2%) stddev-sans-first:2.92 07:32:26 INFO - PROCESS | 1668 | Values: 86.0 87.0 89.0 88.0 88.0 83.0 88.0 92.0 92.0 85.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:152.00 stddev:14.21 (9.3%) stddev-sans-first:14.96 07:32:26 INFO - PROCESS | 1668 | Values: 149.0 140.0 148.0 155.0 154.0 147.0 144.0 150.0 191.0 159.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:169.00 stddev:16.54 (9.8%) stddev-sans-first:17.38 07:32:26 INFO - PROCESS | 1668 | Values: 167.0 168.0 169.0 167.0 169.0 168.0 172.0 163.0 220.0 172.0 07:32:26 INFO - PROCESS | 1668 | 07:32:26 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:68.00 stddev:8.33 (12.3%) stddev-sans-first:8.65 07:32:26 INFO - PROCESS | 1668 | Values: 67.0 68.0 66.0 66.0 67.0 67.0 68.0 77.0 86.0 87.0 07:32:26 INFO - PROCESS | 1668 | -------- Summary: end -------- 07:32:26 INFO - PROCESS | 1668 | 07:32:27 INFO - TEST-INFO | 1668: exit 0 07:32:27 INFO - TEST-OK | kraken | took 60067ms 07:32:27 INFO - SUITE-END | took 1179s 07:32:28 INFO - Completed test suite (00:19:39) 07:32:28 INFO - javascript benchmark 07:32:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3142.9057128286154, 3275.9508607370894, 3278.5082237664355, 3298.5052242163656, 3328.251143492713, 5587.692676902769, 5604.831855044345, 5626.578006649503, 5635.520980716644, 5677.574181936361, 5594.524465420434, 5669.319681638209, 5678.914816277759, 5682.829515114543, 5718.428157184279, 5106.923396149061, 5121.641485096045, 5148.922766158511, 5177.870553236162, 5203.661761985478, 5044.924326135111, 5065.366829146356, 5071.695698258116, 5248.160294352904, 5303.496167864047, 5120.667156634807, 5183.999999999981, 5196.974015129919, 5203.895922081556, 5214.217867319894, 5213.817516386946, 5221.947780522222, 5247.0, 5261.15821468567, 5289.338832645919, 5061.190209566452, 5073.594112470993, 5105.5149760772665, 5198.116320225571, 5213.244079608474, 5536.695481748502, 5555.638883472561, 5601.663900166003, 5609.551235901118, 5627.2684551008515, 6184.010558310693, 6215.87568248637, 6217.5336849735995, 6263.217097862767, 6280.371962803729, 6272.65500397478, 6295.244570651551, 6350.110984462132, 6352.301246862841, 6353.174087368612, 4744.905101898012, 5635.6055076144685, 5728.341240757283, 5797.739101740432, 5800.274965629296, 6281.748730050746, 6300.30696623371, 6301.495880329563, 6308.085327627468, 6331.335209802996, 6137.110119032715, 6138.785142519991, 6141.938580614136, 6144.262688477322, 6159.630422174684, 5090.2364645303505, 6108.450239478469, 6147.631142131487, 6150.446459818638, 6155.6614386209185, 5340.584745042567, 6078.635281883013, 6191.9690401547705, 6235.376462353728, 6238.469730072904, 6171.074338849119, 6205.627662340274, 6236.095766113979, 6263.3736626337, 6273.905891411632, 4788.0, 4851.369321988119, 4860.295257187759, 4968.8757781055765, 4981.526754958273, 4388.210122178039, 4395.780210989437, 4418.16054949565, 4432.0, 4438.667099967515, 5654.37801841797, 5672.914906276409, 5726.141078838207, 5750.741216645261, 5836.387179346192, 6055.122007308915, 6269.968650156811, 6300.684965751694, 6305.054241863757, 6326.68366581669, 5638.984982703153, 5673.517750991129, 5734.483896449339, 5740.483356497852, 5757.913631295534, 4565.908681826345, 5226.451222621646, 5296.523312901781, 5305.31030965972, 5354.277172581729, 6173.567850250529, 6197.938020619917, 6208.4722798562625, 6264.749410023639, 6275.497960163268, 6235.625862448268, 6277.1525844010475, 6277.466415354746, 6286.748530058747, 6294.71673774672, 6096.329403765493, 6231.906521402092, 6256.687165641791, 6289.245290565162, 6304.243490780952, 5872.530197584098, 5998.4601385875485, 6039.305479869696, 6108.554560543837, 6114.0, 6079.574429789872, 6227.283862355887, 6233.812985610439, 6235.220597425322, 6240.219972503437, 5973.253343332083, 6007.669578173242, 6023.728932198149, 6041.0, 6061.636301821904, 5872.765089396376, 5938.257717785277, 6068.575199735976, 6091.725872335667, 6127.3566275540425, 6012.098185272244, 6017.729202185825, 6026.035834266497, 6029.849253768691, 6048.969755151372, 5913.556483263858, 6120.173776540289, 6158.137860699595, 6165.167702360303, 6172.969135154295, 5213.40045894727, 5697.945880012211, 5838.241028666246, 5872.383399742966, 5901.1443340714495], "value": 5644.80149522364, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.3597914773352, 485.1170869018388, 499.7051739473712, 512.3314075131954, 529.4678847758003, 24572.631410528833, 30551.694483055162, 38349.80825095874, 39574.0, 39627.40558891619, 35212.64787352126, 40529.18941621173, 40857.97855053618, 41189.0, 41238.793806030924, 9243.768905777342, 9388.812223755522, 9403.106704863027, 9404.81190376192, 9424.905750942506, 8543.017552981393, 8765.780855478632, 9154.588043538024, 9281.211097056725, 9299.11658392455, 9234.445933243993, 9297.674581389652, 9300.93039300479, 9312.394694344846, 9312.81374372512, 7767.262110099531, 7833.412494062968, 8121.862939188518, 9204.861927071066, 9280.628774849032, 9263.953680231623, 9335.626574936994, 9339.626414943394, 9340.065993400674, 9356.485393303366, 38587.22825543502, 38925.61074389248, 38974.22051558981, 39039.8048009761, 39099.804500977596, 3311.983440082808, 3469.62216023509, 3497.283056973314, 3572.2319701264355, 3645.2162785001087, 28305.762570580846, 29280.560791588145, 29967.550486742715, 30193.698063019307, 30669.0, 3133.8746450142194, 3176.857041433141, 3179.2528755742383, 3213.389456003352, 3217.356528694247, 464344.0, 464662.0, 465232.0, 465653.6717316392, 468414.0, 32278.0, 56913.71543142341, 57345.0, 57440.0, 57469.425305747245, 33513.0, 34411.4838277426, 34742.47886281708, 34770.130746731535, 34777.82611086928, 13312.534061308, 13615.0, 14277.42890284377, 14441.205733684748, 14486.85513144855, 12136.453859576164, 12231.449584768707, 12231.571894983634, 12233.082518811125, 12250.081243906565, 6956.234814170437, 7590.582517961455, 7632.1986191450205, 7685.577293248925, 7691.077070751546, 3112.7042930921525, 3206.246532064963, 3214.7588930829816, 3218.34024025079, 3244.0, 934.8644446555212, 999.9950000249952, 1028.1209565821264, 1046.0480962324248, 1051.4112097225577, 28181.71818281791, 29296.974605889118, 29597.74542598819, 29803.403931921675, 29806.403871922874, 1020.4132623741378, 1038.9636362727267, 1084.4848696868924, 1085.3596378136779, 1093.573506332531, 14852.480163194236, 15191.544253672408, 15254.542363729106, 15596.0, 15679.608009799846, 594215.0, 967924.0, 976346.0, 977126.0, 980385.0, 57812.0, 58465.41534584685, 58527.82944341174, 58674.59940504479, 58678.11982820261, 966.9468179250209, 1161.2161790791183, 1165.469711281348, 1353.3977380065776, 1373.6359864635879, 22572.88713556422, 30372.088837334915, 31116.91090811811, 31385.664437046213, 31758.523622145225, 55091.724541377036, 55703.721481392335, 55709.721451392485, 55717.72141139268, 55774.44225557693, 617023.0, 620824.8958755177, 621361.0, 623605.0, 624676.0, 26553.468930621664, 26588.069417570292, 26812.0, 26900.0, 26905.461890762468, 45092.0, 45437.545624544655, 45634.54365456303, 46013.539864602266, 46025.30962035639, 36863.89408317755, 37385.43921841229, 37757.0, 38237.0, 38736.0, 37145.88562343134, 37239.813800930824, 37762.622373775914, 37902.0, 38214.426783597715, 36495.63504364922, 36873.262534749694, 36906.44640330344, 37355.626443735215, 37502.06244843901, 32113.678863212004, 33274.33451330911, 33732.83133584316, 33907.321853563284, 34064.31871362509, 20324.43987174206, 24750.504989900463, 24841.50316993614, 25198.496030079663, 25276.873615631805], "value": 18345.61806311352, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.6461241839535, 368.3939918833519, 369.7504184675342, 380.9790461524616, 399.17769395046207, 11897.405129743522, 14960.700785984287, 15646.217689115556, 19517.707234391495, 20249.380049596035, 19245.903770481127, 19439.930803805786, 19913.3030343938, 20146.59706805863, 20185.394438166877, 5701.087825947839, 5956.434138756811, 6010.308814486324, 6029.155918171437, 6035.124906888498, 5488.313960754906, 5845.912311315313, 5922.851928701773, 5924.081767326072, 5929.703514824263, 3667.413213885779, 4088.5911408859174, 4298.9785051074705, 4341.826326946934, 5851.502622277111, 4290.206311832306, 5620.550355971533, 6450.548461607689, 6468.902966455507, 6540.05169250456, 5436.619436639437, 5650.6892120933335, 5702.4582664647205, 5715.085586306171, 5717.857053573653, 24226.51546969051, 24406.38984025396, 24734.752652473424, 25199.0, 25343.619845702146, 2258.1306197114063, 2320.1415476273887, 2330.1686607756583, 2423.3941514621347, 2965.0808249442744, 14901.552953411416, 14912.62718432037, 14991.775123373158, 15049.172295523742, 15650.843491565167, 1767.6022894848684, 1854.9258029678797, 1858.7676540432446, 1892.8201820827137, 1922.4905400068992, 91371.0, 108125.91874081158, 118943.21585176229, 120853.0, 121880.39059804734, 10009.749756256006, 10212.080912717742, 10959.397233152253, 11022.889771102185, 11421.428928553705, 13915.860841391659, 13924.791128133085, 13930.86069139316, 13951.37218825135, 13971.301434928213, 8114.513129212266, 8416.53709045996, 8443.366747493963, 8505.404621676547, 8513.872291915626, 8378.41351105429, 8383.413161078666, 8396.874046889301, 8531.445456045338, 8539.06070332263, 6468.54720169593, 6608.471322294205, 6617.404433600999, 6634.336566343327, 6741.629210393377, 1763.938262160818, 1821.3261093395529, 1825.853931685462, 1827.2051657529018, 1836.4582448177819, 785.9921400786034, 921.7557347302836, 938.4932136646271, 948.9762755931018, 968.7868668892833, 14847.331870065873, 21361.465963351042, 21508.462288442603, 21663.35009949704, 21679.34961951144, 869.5478351257311, 886.7295474880224, 921.8985911549725, 929.149827907457, 936.8032713130319, 9999.700008999742, 11406.885931140749, 11480.024197943101, 11501.194916355942, 11532.192746507832, 608116.0, 623171.0, 625890.0, 626471.0, 638279.0, 135180.3240983789, 137593.3120334392, 139230.0, 139842.0, 143745.62543745543, 965.4062751407834, 996.9202463802878, 1005.9698209053887, 1013.9442330671736, 1014.5485259059648, 16748.497545073667, 18839.529011774815, 19290.7106393407, 19356.4193074208, 19357.806421935602, 40824.79587602043, 50325.74837125791, 52583.73708131435, 53536.732316338166, 55869.720651396485, 81349.37301253823, 82227.7665835025, 83001.16998829934, 83479.74780378178, 84672.0, 6436.0, 7351.963240183979, 7721.459497835098, 7962.203779621991, 7976.680932762857, 8781.29749620016, 11702.069158588738, 12481.126321157431, 12658.303793291458, 12844.614661560168, 9359.532023398939, 10786.73033174177, 11094.5562177512, 11116.722081947693, 11173.441327933733, 10582.894171058191, 10782.353058816496, 10930.45347732626, 11329.150313726668, 11347.829782553103, 10149.84775228387, 10917.853625369222, 11024.5038973248, 11200.10399168081, 11405.885941140814, 7735.458517903693, 7806.024246969129, 7937.365010799238, 8153.02163740355, 8171.223733745284, 5261.605379596469, 7960.283574478325, 10813.0, 10903.672889813319, 11124.499397527292], "value": 9506.445773219179, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.7638825011497, 1326.781081121615, 1467.039089396445, 1610.5973506623345, 1842.4748946550235, 7452.366548843348, 7823.256790604891, 8034.236747508985, 8372.958135209316, 8375.83248335033, 8256.422050456455, 8348.624311905964, 8356.665733370673, 8368.665253389872, 8376.45553039052, 2288.5422915416943, 2324.0471406723245, 2327.418145463634, 2328.3364241191266, 2330.6154484510034, 2302.4013756423365, 2348.96476552851, 2353.4351755578623, 2358.0, 2359.4809141988735, 1990.1740777577288, 2301.827362947777, 2303.9078436862505, 2316.444053427182, 2329.1382188590246, 1941.7475728155391, 2223.7442694090146, 2256.311824893407, 2290.793828555438, 2295.747467778543, 2302.861828290308, 2326.325045966901, 2329.918452854142, 2331.358876309029, 2333.5332933413383, 7302.780916572511, 8246.670133194724, 8378.8324233515, 8395.370347223983, 8400.873986890201, 4896.95103048972, 5087.287779710843, 5106.310648062538, 5107.642465027449, 5119.923201151985, 3282.2450836307544, 3508.982455087734, 3553.058439513531, 3561.9643803561894, 3575.0, 1019.5666841592367, 1039.3555995282898, 1071.844582535536, 1073.9140868730483, 1080.4543705428707, 10788.406637634927, 10879.619213327574, 10936.781264374747, 10938.835917461245, 10947.124230061656, 4231.42875711775, 4364.738115713068, 4452.131834292347, 4472.619827314649, 4490.797914093873, 8601.924759405074, 8605.0, 8605.483670979762, 8612.8708069379, 8620.439671421338, 5062.949370506248, 5071.721055341918, 5072.442031376546, 5073.822416215415, 5080.441151473335, 4008.5390180129075, 4256.489221293465, 4380.386745855582, 4415.735055896592, 4773.355596994361, 3605.9819700901326, 3621.5654121505063, 3689.3912504436466, 3700.093477098128, 3718.1076541630355, 978.554757585297, 1135.9034482069117, 1140.3044143072718, 1140.8402823604702, 1152.6426807689643, 862.6800994478458, 883.5847151838625, 892.9955350223337, 910.2354022620898, 911.5852287209303, 5303.178007408857, 5484.7531861066345, 5545.584081193846, 5606.271184746039, 5620.269364982526, 424.613601622522, 449.03009499480964, 450.54720006393364, 451.7447642082213, 455.63321526171086, 3661.176235347079, 3672.3756961316108, 3699.611540788232, 3720.776753394805, 3820.1977584706965, 9502.667406640734, 10038.196944244442, 10040.598376064876, 10056.849147262797, 10057.195424366033, 9885.703428897144, 9915.55380007887, 9938.055884690943, 9978.251631127694, 10021.949890250502, 7737.187595302412, 7820.7653770388115, 7841.0, 7847.921520784719, 7891.684332626745, 5342.9465705344, 5440.129579267298, 5492.5605951524585, 5525.668459892419, 5550.8057217997175, 9278.907210927804, 9292.210162136294, 9382.0, 9430.62277508892, 9439.244860411014, 8664.046954834837, 8994.640214391351, 9076.863847042168, 9363.485008324335, 9368.203702685349, 6652.40128388428, 6797.762078327235, 6798.0, 6810.114685090906, 6811.523193376416, 7584.696612135674, 7853.489523180975, 7867.291943725092, 7885.605719714106, 7969.6015199240965, 7059.964700176466, 7129.572225666477, 7134.928650713427, 7156.606386648784, 7160.641967901688, 6936.549124306904, 7040.542364746275, 7049.7885063448175, 7086.362227399352, 7092.822679433056, 6588.670566471753, 6731.495137864582, 6733.427658648874, 6733.797986060426, 6741.0, 4711.976440117777, 4730.834420795393, 4821.276808478616, 4864.221724524059, 4923.0646177227645, 4679.0, 4784.258439941815, 4851.9514804852915, 4895.0, 4988.6757360771435], "value": 4255.881342327104, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.85505529818033, 302.18410292211036, 307.8860821496046, 311.2343634658739, 312.99530507042414, 5155.149400348942, 5193.896122077556, 5240.34495688039, 5265.368155821307, 5299.443558426367, 4862.805487780484, 4963.801447942078, 5026.924596131061, 5034.82378116766, 5180.378354597454, 3328.7170590499836, 3970.424288478169, 3983.800809959505, 3984.482017337742, 4016.8869126427107, 3634.7637403568815, 3957.1887763008513, 3969.1543432303974, 3984.7808370539756, 3994.4208089826957, 3829.6553310202075, 3938.4289278054666, 3970.8610198643055, 3985.7010724195657, 3987.900302492432, 3423.400904841668, 3685.1708365617524, 3938.684905207605, 3988.78061706606, 3989.1622759220745, 3448.9482657760154, 3451.706604938583, 3458.8962331130106, 3460.480927860841, 3467.7919324840336, 4953.851384458472, 5014.693241109057, 5026.220935754964, 5045.99080183961, 5080.415752188509, 3796.6772824309965, 4135.524414692319, 4547.636189104864, 4553.612942899857, 4555.79498922546, 5400.838819653754, 5409.323834520685, 5418.810341638064, 5430.972845135789, 5447.782088716446, 3382.6300348358955, 3396.490526421032, 3542.8485742133653, 3830.9616903830884, 3838.9808050959846, 4246.723962942398, 4803.823063349501, 5571.415001424833, 5674.262345895048, 5686.640044381618, 3139.795913265676, 3387.271736576648, 3786.95858638871, 3838.5201849768778, 3897.162110146332, 3474.2530355973586, 3640.399334109842, 3932.9213415732097, 3939.330313846653, 3955.5870918739547, 3862.4013277941963, 4070.470838790938, 4218.599233072853, 4590.40324757786, 4598.632109431237, 4475.30632751924, 4584.862454126382, 5424.376196737426, 5426.209350914189, 5558.861028474321, 1166.4571234708417, 1182.9585964491203, 1267.0370518406078, 1275.4324325674986, 1279.3731071774764, 722.4112348436008, 775.5269285735696, 815.995920020408, 851.7955690634203, 883.6921356392575, 4828.493008234155, 4883.658143929962, 4892.266160075946, 4912.8280510182685, 4928.260760885896, 4334.544872788375, 5209.869753256123, 5293.894122117536, 5372.70450125239, 5380.461953804588, 3910.608939106067, 3997.4803275574573, 4553.886152846206, 4615.653825963066, 4833.975830120898, 2540.4919016196836, 2748.8900443982416, 2752.2431331383627, 2761.2682639100653, 2766.344309462898, 4732.5977291929885, 5394.870974834586, 5400.702961337164, 5410.781579552365, 5411.783528658888, 4447.688661793708, 4669.909798319848, 4791.5687588117235, 5329.333833270841, 5374.512282244292, 4596.264597664424, 4620.960283936088, 4761.666683332133, 5342.759575819097, 5383.300170977748, 4543.114092751882, 4829.517048295143, 5133.383993920754, 5221.556167725634, 5234.738263086907, 4607.7004994675235, 4870.0, 5043.646944713835, 5163.3545806774155, 5173.715445650525, 4962.848591144577, 5382.604740890266, 5412.618447882919, 5416.783328666809, 5418.729063546885, 3483.6864682178725, 3513.767334052729, 3531.346700860349, 3638.872639457606, 3654.744167908221, 5032.823851165192, 5125.89793194465, 5190.143626301769, 5262.447443018428, 5278.683279003272, 5531.197976293495, 5533.667979921218, 5534.833954981357, 5545.001899657939, 5560.443955604407, 4837.540433658797, 5000.824971125993, 5464.175077445724, 5557.972210138923, 5560.249366335493, 4470.865874023784, 4966.627502937222, 5126.307948427064, 5191.028862919945, 5420.915816836728, 4826.493218212037, 4905.460399355997, 5365.171345788821, 5449.427810079885, 5507.697076660822, 4715.70318162508, 5376.946230537645, 5406.432324606017, 5415.972920135374, 5425.538829199404], "value": 3878.200119337806, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.657701283839, 707.8382678781209, 726.444270133348, 773.8104164479703, 792.1128336263386, 13676.316184190788, 13711.31443427827, 14034.157950522977, 14171.929140354283, 14260.144391336502, 12536.934360579338, 12659.683507912307, 12660.936695316534, 12664.556740514085, 12682.985361171108, 620.8230654263535, 643.3020173112179, 776.3672606825447, 793.4208028139448, 809.9676012959475, 808.5552945879742, 812.3176531713359, 814.2386868278179, 814.9837003259931, 815.351795322717, 859.3511898516612, 859.8366310401004, 860.5224100624151, 861.0399404663787, 861.577826864838, 655.7049327802484, 677.7322957431836, 679.1544527063837, 680.2279412866383, 856.019857263433, 500.9799608015675, 603.2369053147728, 624.2290770897937, 625.4183609243402, 634.6160572853404, 13565.796513052312, 13567.728645427036, 13569.660758481019, 13571.1857288563, 13606.659833504242, 12169.817452738216, 12342.876571234263, 12452.31512266825, 12460.75078498425, 12623.936880315632, 10361.948190259076, 11741.354225517593, 11951.820722689166, 12107.091968102339, 12242.938785306105, 4273.50854651716, 4315.30955047194, 4399.692021558492, 4439.866803995886, 4464.977675111603, 71836.28163718348, 88707.55646221792, 88758.0, 89562.39353701218, 92135.53932230362, 8973.865392019125, 10050.59797608102, 10174.084332410119, 10453.425061621683, 10630.990055944672, 19624.90187549053, 19698.40904772859, 20008.39974800758, 20015.199392024155, 20034.398968030982, 7324.0, 7333.303336183054, 7395.778126656209, 7424.109106907205, 7619.276168763959, 4059.239746681264, 4100.302948498763, 4127.772972486482, 4179.895502612399, 4263.083437060985, 3175.25381535339, 3285.195127193853, 3290.8683652654104, 3297.4064668359924, 3304.966950330466, 603.8973374526341, 609.6524980760923, 613.2395829171884, 623.1120653069448, 625.7465726380823, 580.8431723434649, 582.775631381921, 594.6194435561245, 609.5123900879279, 633.2527617411407, 7497.362724168507, 8356.061741345624, 8704.260137888225, 8723.563821808884, 8752.56237188138, 631.9778807741799, 634.80321100459, 660.6762686283687, 684.852756657321, 688.435482904014, 3465.5494785678206, 4398.846040388571, 4500.186265987831, 4544.2047641662575, 4673.626109911199, 67016.26946614278, 91679.0, 103419.0, 112042.3193652096, 115238.42380788042, 70762.2923770766, 71282.0, 74275.62862185763, 87174.69237961435, 90484.54757726169, 56740.43259567351, 56876.431235687116, 70849.2915070853, 77193.84209236866, 81991.77012344939, 9318.301127415336, 9594.232461403211, 9707.66023189185, 9836.114749672564, 9849.212063034796, 42288.78855605702, 44021.55978440175, 45493.8626534326, 49148.788304527094, 50952.98094038173, 65974.0, 70033.64983175052, 74124.62937685277, 74836.0, 76336.0, 9967.601295948081, 11921.569411670655, 12042.518299267931, 12232.510699571918, 12397.946174575261, 13912.647050079064, 13940.302984850514, 13967.30163491801, 14076.296185190495, 14232.003759737134, 10778.892211078102, 12700.237985720887, 12826.166299190896, 12875.742485150431, 12887.033472489413, 11855.644330670093, 11965.700857478632, 12831.743365132832, 13067.085304028627, 13656.385462654403, 12249.326287054297, 12787.360631968178, 12938.611841644766, 12976.416061277454, 13525.797113043509, 10842.728931776768, 10985.83521247166, 11221.831672525082, 11347.716307092389, 11415.657530274106, 11055.778884422427, 11246.212765106364, 11439.199256051996, 11477.942610286895, 11532.25040372373], "value": 7004.201995044405, "unit": "score"}], "name": "dromaeo_css", "value": 6961.5230434914565, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 115.0, 119.0, 161.0, 169.0, 112.0, 113.0, 114.0, 114.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 140.0, 183.0, 127.0, 111.0, 121.0, 136.0, 173.0, 112.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 182.0, 185.0, 196.0, 261.0, 199.0, 185.0, 183.0, 184.0], "value": 194.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 91.0, 61.0, 58.0, 59.0, 59.0, 84.0, 83.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 97.0, 71.0, 69.0, 68.0, 88.0, 68.0, 69.0, 92.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 89.0, 86.0, 89.0, 87.0, 89.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 98.0, 97.0, 99.0, 97.0, 123.0, 161.0, 109.0, 98.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 82.0, 85.0, 82.0, 86.0, 85.0, 81.0, 82.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 54.0, 57.0, 57.0, 58.0, 56.0, 56.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 78.0, 103.0, 110.0, 93.0, 73.0, 72.0, 71.0, 71.0, 73.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 89.0, 88.0, 88.0, 83.0, 88.0, 92.0, 92.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 140.0, 148.0, 155.0, 154.0, 147.0, 144.0, 150.0, 191.0, 159.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 169.0, 167.0, 169.0, 168.0, 172.0, 163.0, 220.0, 172.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 66.0, 67.0, 67.0, 68.0, 77.0, 86.0, 87.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1504.1000000000001, "alertThreshold": 2.0}]} 07:32:28 INFO - Return code: 0 07:32:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:32:28 ERROR - # TBPL SUCCESS # 07:32:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:28 INFO - 2016-11-14 07:32:28.254 screenresolution[1672:9931] starting screenresolution argv=screenresolution get 07:32:28 INFO - 2016-11-14 07:32:28.258 screenresolution[1672:9931] Display 0: 1600x1200x32@60 07:32:28 INFO - 2016-11-14 07:32:28.264 screenresolution[1673:9934] starting screenresolution argv=screenresolution list 07:32:28 INFO - Available Modes on Display 0 07:32:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:28 INFO - Intel Iris: 07:32:28 INFO - Chipset Model: Intel Iris 07:32:28 INFO - Type: GPU 07:32:28 INFO - Bus: Built-In 07:32:28 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:28 INFO - Vendor: Intel (0x8086) 07:32:28 INFO - Device ID: 0x0a2e 07:32:28 INFO - Revision ID: 0x0009 07:32:28 INFO - Displays: 07:32:28 INFO - Display: 07:32:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:28 INFO - Display Serial Number: 1600x1200 60 07:32:28 INFO - Main Display: Yes 07:32:28 INFO - Mirror: Off 07:32:28 INFO - Online: Yes 07:32:28 INFO - Rotation: Supported 07:32:28 INFO - Return code: 0 07:32:28 INFO - Running post-action listener: _resource_record_post_action 07:32:28 INFO - [mozharness: 2016-11-14 15:32:28.502765Z] Finished run-tests step (success) 07:32:28 INFO - Running post-run listener: _resource_record_post_run 07:32:28 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38298624; Write bytes: 653852672; Read time: 526; Write time: 2071 07:32:28 INFO - TinderboxPrint: CPU usage
53.5% 07:32:28 INFO - TinderboxPrint: I/O read bytes / time
38,298,624 / 526 07:32:28 INFO - TinderboxPrint: I/O write bytes / time
653,852,672 / 2,071 07:32:28 INFO - TinderboxPrint: CPU idle
2,253.5 (46.5%) 07:32:28 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 07:32:28 INFO - TinderboxPrint: CPU user
1,624.7 (33.5%) 07:32:28 INFO - TinderboxPrint: Swap in / out
391,303,168 / 0 07:32:28 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350614016; Write bytes: 345180160; Read time: 27400; Write time: 640 07:32:28 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36172800; Write bytes: 297506816; Read time: 437; Write time: 1393 07:32:28 INFO - Running post-run listener: _upload_blobber_files 07:32:28 INFO - Blob upload gear active. 07:32:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:32:28 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 07:32:28 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'] 07:32:28 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 07:32:29 INFO - (blobuploader) - INFO - Open directory for files ... 07:32:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:32:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:30 INFO - (blobuploader) - INFO - Done attempting. 07:32:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:31 INFO - (blobuploader) - INFO - Done attempting. 07:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:34 INFO - (blobuploader) - INFO - Done attempting. 07:32:34 INFO - (blobuploader) - INFO - Iteration through files over. 07:32:34 INFO - Return code: 0 07:32:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:32:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:32:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a47e6e2888043c9369ca11437a5e22b14132c9ff9fe4012869d2c4488702e00a56c244a33d5588f197eaa655e8406dfa412d79550397c1c64ca121f274957f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffde5e2be8c9701beb7d86cb726d1b4b0e7c927e5d681c1860e88ed6d386d2be631fd6f744270a05cf99560738a96badfc9f38304c0fe1353c47c2a858d5206a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8af0b6726e3575301f19a723d807db4766e073aae93cdafcc486668274c885dce3d97ee4ef61b5b591f65cc880d8551e0c955e63a2e3c9c5308b7419f7e0adc"} 07:32:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:32:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:32:34 INFO - Contents: 07:32:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a47e6e2888043c9369ca11437a5e22b14132c9ff9fe4012869d2c4488702e00a56c244a33d5588f197eaa655e8406dfa412d79550397c1c64ca121f274957f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffde5e2be8c9701beb7d86cb726d1b4b0e7c927e5d681c1860e88ed6d386d2be631fd6f744270a05cf99560738a96badfc9f38304c0fe1353c47c2a858d5206a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8af0b6726e3575301f19a723d807db4766e073aae93cdafcc486668274c885dce3d97ee4ef61b5b591f65cc880d8551e0c955e63a2e3c9c5308b7419f7e0adc"} 07:32:34 INFO - Running post-run listener: copy_logs_to_upload_dir 07:32:34 INFO - Copying logs to upload dir... 07:32:34 INFO - mkdir: /builds/slave/test/build/upload/logs 07:32:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.717919 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-14 07:32:34.378632) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:32:34.382003) ========= 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.hsgKLVPcMG/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.iij93fB2qk/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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/8a47e6e2888043c9369ca11437a5e22b14132c9ff9fe4012869d2c4488702e00a56c244a33d5588f197eaa655e8406dfa412d79550397c1c64ca121f274957f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffde5e2be8c9701beb7d86cb726d1b4b0e7c927e5d681c1860e88ed6d386d2be631fd6f744270a05cf99560738a96badfc9f38304c0fe1353c47c2a858d5206a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8af0b6726e3575301f19a723d807db4766e073aae93cdafcc486668274c885dce3d97ee4ef61b5b591f65cc880d8551e0c955e63a2e3c9c5308b7419f7e0adc"} build_url:https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010846 build_url: 'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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/8a47e6e2888043c9369ca11437a5e22b14132c9ff9fe4012869d2c4488702e00a56c244a33d5588f197eaa655e8406dfa412d79550397c1c64ca121f274957f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffde5e2be8c9701beb7d86cb726d1b4b0e7c927e5d681c1860e88ed6d386d2be631fd6f744270a05cf99560738a96badfc9f38304c0fe1353c47c2a858d5206a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8af0b6726e3575301f19a723d807db4766e073aae93cdafcc486668274c885dce3d97ee4ef61b5b591f65cc880d8551e0c955e63a2e3c9c5308b7419f7e0adc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:32:34.414553) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:32:34.414882) ========= 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.hsgKLVPcMG/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.iij93fB2qk/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.005258 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:32:34.463184) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:32:34.463471) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:32:34.463774) ========= ========= Total master_lag: 0.17 =========