builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0258 starttime: 1467978454.39 results: success (0) buildid: 20160708025826 builduid: 5c6aadf132e14693959a9ab06c1dc8f0 revision: 401d1f8037f305ae0b9a09fd940343d87597a64a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.387789) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.388323) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.388637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.413826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.414175) ========= 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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-07-08 04:47:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 170M=0s 2016-07-08 04:47:34 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120336 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.556733) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.557069) ========= 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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.020747 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:34.605488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 04:47:34.605861) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 401d1f8037f305ae0b9a09fd940343d87597a64a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 401d1f8037f305ae0b9a09fd940343d87597a64a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Sx8uv6ECVX/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.9f9zEmql9u/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-07-08 04:47:34,682 truncating revision to first 12 chars 2016-07-08 04:47:34,682 Setting DEBUG logging. 2016-07-08 04:47:34,682 attempt 1/10 2016-07-08 04:47:34,682 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/401d1f8037f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 04:47:35,915 unpacking tar archive at: mozilla-central-401d1f8037f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.506870 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 04:47:36.136610) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:36.137075) ========= script_repo_revision: 401d1f8037f305ae0b9a09fd940343d87597a64a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:36.137565) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:36.137951) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 04:47:36.157185) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-08 04:47:36.157540) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Sx8uv6ECVX/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.9f9zEmql9u/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:47:36 INFO - MultiFileLogger online at 20160708 04:47:36 in /builds/slave/test 04:47:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:47:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:36 INFO - {'append_to_log': False, 04:47:36 INFO - 'base_work_dir': '/builds/slave/test', 04:47:36 INFO - 'blob_upload_branch': 'Firefox', 04:47:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:36 INFO - 'branch': 'Firefox', 04:47:36 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:36 INFO - 'config_files': ('talos/mac_config.py',), 04:47:36 INFO - 'default_actions': ('clobber', 04:47:36 INFO - 'read-buildbot-config', 04:47:36 INFO - 'download-and-extract', 04:47:36 INFO - 'populate-webroot', 04:47:36 INFO - 'create-virtualenv', 04:47:36 INFO - 'install', 04:47:36 INFO - 'run-tests'), 04:47:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:36 INFO - 'download_minidump_stackwalk': True, 04:47:36 INFO - 'download_symbols': 'ondemand', 04:47:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:36 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:36 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:36 INFO - 'installer_path': 'installer.exe', 04:47:36 INFO - 'log_level': 'info', 04:47:36 INFO - 'log_name': 'talos', 04:47:36 INFO - 'log_to_console': True, 04:47:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:47:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:47:36 INFO - 'opt_config_files': (), 04:47:36 INFO - 'pip_index': False, 04:47:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:36 INFO - 'cmd': ('bash', 04:47:36 INFO - '-c', 04:47:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:36 INFO - 'enabled': True, 04:47:36 INFO - 'halt_on_failure': False, 04:47:36 INFO - 'name': 'check_screen_resolution'},), 04:47:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:36 INFO - 'cmd': ('bash', 04:47:36 INFO - '-c', 04:47:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:36 INFO - 'enabled': True, 04:47:36 INFO - 'halt_on_failure': False, 04:47:36 INFO - 'name': 'check_screen_resolution'},), 04:47:36 INFO - 'run_cmd_checks_enabled': True, 04:47:36 INFO - 'sps_profile': False, 04:47:36 INFO - 'sps_profile_interval': 0, 04:47:36 INFO - 'suite': 'dromaeojs', 04:47:36 INFO - 'system_bits': '32', 04:47:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:36 INFO - 'title': 't-yosemite-r7-0258', 04:47:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:36 INFO - 'use_talos_json': True, 04:47:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:36 INFO - 'work_dir': 'build'} 04:47:36 INFO - ##### 04:47:36 INFO - ##### Running clobber step. 04:47:36 INFO - ##### 04:47:36 INFO - Running pre-action listener: _resource_record_pre_action 04:47:36 INFO - Running main action method: clobber 04:47:36 INFO - rmtree: /builds/slave/test/build 04:47:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:47:37 INFO - Running post-action listener: _resource_record_post_action 04:47:37 INFO - ##### 04:47:37 INFO - ##### Finished clobber step (success) 04:47:37 INFO - ##### 04:47:37 INFO - ##### 04:47:37 INFO - ##### Running read-buildbot-config step. 04:47:37 INFO - ##### 04:47:37 INFO - Running pre-action listener: _resource_record_pre_action 04:47:37 INFO - Running main action method: read_buildbot_config 04:47:37 INFO - Using buildbot properties: 04:47:37 INFO - { 04:47:37 INFO - "project": "", 04:47:37 INFO - "product": "firefox", 04:47:37 INFO - "script_repo_revision": "production", 04:47:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:47:37 INFO - "repository": "", 04:47:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:47:37 INFO - "buildid": "20160708025826", 04:47:37 INFO - "pgo_build": "False", 04:47:37 INFO - "basedir": "/builds/slave/test", 04:47:37 INFO - "buildnumber": 6, 04:47:37 INFO - "slavename": "t-yosemite-r7-0258", 04:47:37 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:47:37 INFO - "platform": "yosemite_r7", 04:47:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:47:37 INFO - "branch": "mozilla-central", 04:47:37 INFO - "repo_path": "mozilla-central", 04:47:37 INFO - "revision": "401d1f8037f305ae0b9a09fd940343d87597a64a", 04:47:37 INFO - "stage_platform": "macosx64", 04:47:37 INFO - "builduid": "5c6aadf132e14693959a9ab06c1dc8f0", 04:47:37 INFO - "slavebuilddir": "test" 04:47:37 INFO - } 04:47:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:47:37 INFO - Running post-action listener: _resource_record_post_action 04:47:37 INFO - ##### 04:47:37 INFO - ##### Finished read-buildbot-config step (success) 04:47:37 INFO - ##### 04:47:37 INFO - ##### 04:47:37 INFO - ##### Running download-and-extract step. 04:47:37 INFO - ##### 04:47:37 INFO - Running pre-action listener: _resource_record_pre_action 04:47:37 INFO - Running main action method: download_and_extract 04:47:37 INFO - mkdir: /builds/slave/test/build/tests 04:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:37 INFO - https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:47:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:47:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:47:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:47:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 04:47:39 INFO - Downloaded 1199 bytes. 04:47:39 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:47:39 INFO - Using the following test package requirements: 04:47:39 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:47:39 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:47:39 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:47:39 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'jsshell-mac.zip'], 04:47:39 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:47:39 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:47:39 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:47:39 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:47:39 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:47:39 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:47:39 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:47:39 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:47: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:39 INFO - https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:47:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:47:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:47:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:47:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:40 INFO - Downloaded 18806355 bytes. 04:47:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:47:44 INFO - Return code: 0 04:47:44 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:44 INFO - https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:47:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:47:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:47:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:47:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:46 INFO - Downloaded 18806355 bytes. 04:47:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:47:49 INFO - Return code: 0 04:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:49 INFO - https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:47:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:47:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:47:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:47:50 INFO - Downloaded 14403333 bytes. 04:47:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:47:51 INFO - Return code: 0 04:47: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:51 INFO - https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:47:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:47:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:47:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:47:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 04:47:56 INFO - Downloaded 112448525 bytes. 04:47:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:47:56 INFO - mkdir: /builds/slave/test/properties 04:47:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:56 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:56 INFO - Contents: 04:47:56 INFO - build_url:https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:47:57 INFO - Running post-action listener: _resource_record_post_action 04:47:57 INFO - Running post-action listener: set_extra_try_arguments 04:47:57 INFO - ##### 04:47:57 INFO - ##### Finished download-and-extract step (success) 04:47:57 INFO - ##### 04:47:57 INFO - ##### 04:47:57 INFO - ##### Running populate-webroot step. 04:47:57 INFO - ##### 04:47:57 INFO - Running pre-action listener: _resource_record_pre_action 04:47:57 INFO - Running main action method: populate_webroot 04:47:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:57 INFO - '--tppagecycles', 04:47:57 INFO - '7'], 04:47:57 INFO - 'tests': ['tsvgm']}}, 04:47:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['damp', 'tps']}, 04:47:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'tests': ['damp', 'tps']}, 04:47:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['dromaeo_dom']}, 04:47:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['basic_compositor_video']}, 04:47:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:47:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['a11yr', 04:47:57 INFO - 'ts_paint', 04:47:57 INFO - 'tpaint', 04:47:57 INFO - 'sessionrestore', 04:47:57 INFO - 'sessionrestore_no_auto_restore', 04:47:57 INFO - 'tabpaint']}, 04:47:57 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:57 INFO - 'ts_paint', 04:47:57 INFO - 'tpaint', 04:47:57 INFO - 'sessionrestore', 04:47:57 INFO - 'sessionrestore_no_auto_restore', 04:47:57 INFO - 'tabpaint']}, 04:47:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:57 INFO - 'ts_paint', 04:47:57 INFO - 'tpaint', 04:47:57 INFO - 'sessionrestore', 04:47:57 INFO - 'sessionrestore_no_auto_restore', 04:47:57 INFO - 'tabpaint']}, 04:47:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:57 INFO - 'ts_paint', 04:47:57 INFO - 'tpaint', 04:47:57 INFO - 'sessionrestore', 04:47:57 INFO - 'sessionrestore_no_auto_restore', 04:47:57 INFO - 'tabpaint']}, 04:47:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['a11yr', 04:47:57 INFO - 'ts_paint', 04:47:57 INFO - 'tpaint', 04:47:57 INFO - 'sessionrestore', 04:47:57 INFO - 'sessionrestore_no_auto_restore', 04:47:57 INFO - 'tabpaint']}, 04:47:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['a11yr', 04:47:57 INFO - 'ts_paint', 04:47:57 INFO - 'tpaint', 04:47:57 INFO - 'sessionrestore', 04:47:57 INFO - 'sessionrestore_no_auto_restore', 04:47:57 INFO - 'tabpaint']}, 04:47:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['tsvgx', 04:47:57 INFO - 'tsvgr_opacity', 04:47:57 INFO - 'tart', 04:47:57 INFO - 'tscrollx', 04:47:57 INFO - 'cart']}, 04:47:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:57 INFO - 'tsvgr_opacity', 04:47:57 INFO - 'tart', 04:47:57 INFO - 'tscrollx', 04:47:57 INFO - 'cart']}, 04:47:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'talos_options': ['--disable-e10s'], 04:47:57 INFO - 'tests': ['tp5o']}, 04:47:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'tests': ['tp5o']}, 04:47:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'talos_options': ['--disable-e10s', 04:47:57 INFO - '--xperf_path', 04:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:57 INFO - 'tests': ['tp5n']}, 04:47:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:57 INFO - 'talos_options': ['--xperf_path', 04:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:57 INFO - 'tests': ['tp5n']}}, 04:47:57 INFO - 'talos.zip': {'path': '', 04:47:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:57 INFO - Running post-action listener: _resource_record_post_action 04:47:57 INFO - ##### 04:47:57 INFO - ##### Finished populate-webroot step (success) 04:47:57 INFO - ##### 04:47:57 INFO - ##### 04:47:57 INFO - ##### Running create-virtualenv step. 04:47:57 INFO - ##### 04:47:57 INFO - Running pre-action listener: _resource_record_pre_action 04:47:57 INFO - Running main action method: create_virtualenv 04:47:57 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:57 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 04:47:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:57 INFO - Using real prefix '/tools/python27' 04:47:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:58 INFO - Installing distribute.............................................................................................................................................................................................done. 04:48:01 INFO - Installing pip.................done. 04:48:01 INFO - Return code: 0 04:48:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:48:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:01 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:01 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 04:48:01 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 04:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:01 INFO - 'HOME': '/Users/cltbld', 04:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:01 INFO - 'LOGNAME': 'cltbld', 04:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:01 INFO - 'MOZ_NO_REMOTE': '1', 04:48:01 INFO - 'NO_EM_RESTART': '1', 04:48:01 INFO - 'PAGER': '/bin/cat', 04:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:01 INFO - 'PWD': '/builds/slave/test', 04:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:01 INFO - 'SHELL': '/bin/bash', 04:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:01 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:01 INFO - 'USER': 'cltbld', 04:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:01 INFO - 'XPC_FLAGS': '0x0', 04:48:01 INFO - 'XPC_SERVICE_NAME': '0', 04:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:01 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 04:48:01 INFO - Cleaning up... 04:48:01 INFO - Return code: 0 04:48:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:48:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:01 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:01 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 04:48:01 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 04:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:01 INFO - 'HOME': '/Users/cltbld', 04:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:01 INFO - 'LOGNAME': 'cltbld', 04:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:01 INFO - 'MOZ_NO_REMOTE': '1', 04:48:01 INFO - 'NO_EM_RESTART': '1', 04:48:01 INFO - 'PAGER': '/bin/cat', 04:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:01 INFO - 'PWD': '/builds/slave/test', 04:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:01 INFO - 'SHELL': '/bin/bash', 04:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:01 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:01 INFO - 'USER': 'cltbld', 04:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:01 INFO - 'XPC_FLAGS': '0x0', 04:48:01 INFO - 'XPC_SERVICE_NAME': '0', 04:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:01 INFO - Downloading/unpacking psutil>=3.1.1 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:48:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:04 INFO - Installing collected packages: psutil 04:48:04 INFO - Running setup.py install for psutil 04:48:05 INFO - building 'psutil._psutil_osx' extension 04:48:05 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 04:48:05 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 04:48:05 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 04:48:05 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 04:48:05 INFO - building 'psutil._psutil_posix' extension 04:48:05 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 04:48:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:48:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:48:05 INFO - ^ 04:48:05 INFO - 1 warning generated. 04:48:05 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 04:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:05 INFO - Successfully installed psutil 04:48:05 INFO - Cleaning up... 04:48:05 INFO - Return code: 0 04:48:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:05 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:05 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 04:48:05 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 04:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:05 INFO - 'HOME': '/Users/cltbld', 04:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:05 INFO - 'LOGNAME': 'cltbld', 04:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:05 INFO - 'MOZ_NO_REMOTE': '1', 04:48:05 INFO - 'NO_EM_RESTART': '1', 04:48:05 INFO - 'PAGER': '/bin/cat', 04:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:05 INFO - 'PWD': '/builds/slave/test', 04:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:05 INFO - 'SHELL': '/bin/bash', 04:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:05 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:05 INFO - 'USER': 'cltbld', 04:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:05 INFO - 'XPC_FLAGS': '0x0', 04:48:05 INFO - 'XPC_SERVICE_NAME': '0', 04:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:48:06 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 04:48:06 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 04:48:06 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 04:48:06 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 04:48:06 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 04:48:06 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 04:48:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:48:08 INFO - Installing collected packages: mozsystemmonitor 04:48:08 INFO - Running setup.py install for mozsystemmonitor 04:48:08 INFO - Successfully installed mozsystemmonitor 04:48:08 INFO - Cleaning up... 04:48:08 INFO - Return code: 0 04:48:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:48: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:08 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:08 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 04:48:08 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 04:48:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:08 INFO - 'HOME': '/Users/cltbld', 04:48:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:08 INFO - 'LOGNAME': 'cltbld', 04:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:08 INFO - 'MOZ_NO_REMOTE': '1', 04:48:08 INFO - 'NO_EM_RESTART': '1', 04:48:08 INFO - 'PAGER': '/bin/cat', 04:48:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:08 INFO - 'PWD': '/builds/slave/test', 04:48:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:08 INFO - 'SHELL': '/bin/bash', 04:48:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:08 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:08 INFO - 'USER': 'cltbld', 04:48:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:08 INFO - 'XPC_FLAGS': '0x0', 04:48:08 INFO - 'XPC_SERVICE_NAME': '0', 04:48:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:09 INFO - Downloading/unpacking jsonschema==2.5.1 04:48:09 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 04:48:09 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 04:48:09 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 04:48:09 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 04:48:09 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 04:48:09 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 04:48:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:48:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:48:11 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 04:48:11 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 04:48:11 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 04:48:11 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 04:48:11 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 04:48:11 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 04:48:11 INFO - Downloading functools32-3.2.3-2.tar.gz 04:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:48:12 INFO - warning: no files found matching '*.txt' 04:48:12 INFO - no previously-included directories found matching 'build' 04:48:12 INFO - no previously-included directories found matching 'dist' 04:48:12 INFO - no previously-included directories found matching '.git*' 04:48:12 INFO - Installing collected packages: jsonschema, functools32 04:48:12 INFO - Running setup.py install for functools32 04:48:12 INFO - warning: no files found matching '*.txt' 04:48:12 INFO - no previously-included directories found matching 'build' 04:48:12 INFO - no previously-included directories found matching 'dist' 04:48:12 INFO - no previously-included directories found matching '.git*' 04:48:12 INFO - Successfully installed jsonschema functools32 04:48:12 INFO - Cleaning up... 04:48:12 INFO - Return code: 0 04:48:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:48:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:12 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:12 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 04:48:12 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 04:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:12 INFO - 'HOME': '/Users/cltbld', 04:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:12 INFO - 'LOGNAME': 'cltbld', 04:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:12 INFO - 'MOZ_NO_REMOTE': '1', 04:48:12 INFO - 'NO_EM_RESTART': '1', 04:48:12 INFO - 'PAGER': '/bin/cat', 04:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:12 INFO - 'PWD': '/builds/slave/test', 04:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:12 INFO - 'SHELL': '/bin/bash', 04:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:12 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:12 INFO - 'USER': 'cltbld', 04:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:12 INFO - 'XPC_FLAGS': '0x0', 04:48:12 INFO - 'XPC_SERVICE_NAME': '0', 04:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:12 INFO - Downloading/unpacking blobuploader==1.2.4 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:15 INFO - Downloading blobuploader-1.2.4.tar.gz 04:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:48:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:48:15 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 04:48:15 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 04:48:15 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 04:48:15 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 04:48:15 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 04:48:15 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 04:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:48:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:48:15 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 04:48:15 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 04:48:15 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 04:48:15 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 04:48:15 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 04:48:15 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 04:48:16 INFO - Downloading docopt-0.6.1.tar.gz 04:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:48:16 INFO - Installing collected packages: blobuploader, requests, docopt 04:48:16 INFO - Running setup.py install for blobuploader 04:48:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:48:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:48:16 INFO - Running setup.py install for requests 04:48:16 INFO - Running setup.py install for docopt 04:48:17 INFO - Successfully installed blobuploader requests docopt 04:48:17 INFO - Cleaning up... 04:48:17 INFO - Return code: 0 04:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:17 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:17 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 04:48:17 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 04:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:17 INFO - 'HOME': '/Users/cltbld', 04:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:17 INFO - 'LOGNAME': 'cltbld', 04:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:17 INFO - 'MOZ_NO_REMOTE': '1', 04:48:17 INFO - 'NO_EM_RESTART': '1', 04:48:17 INFO - 'PAGER': '/bin/cat', 04:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:17 INFO - 'PWD': '/builds/slave/test', 04:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:17 INFO - 'SHELL': '/bin/bash', 04:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:17 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:17 INFO - 'USER': 'cltbld', 04:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:17 INFO - 'XPC_FLAGS': '0x0', 04:48:17 INFO - 'XPC_SERVICE_NAME': '0', 04:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:17 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-bSGP9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:17 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-JimOM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:17 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-TVtSpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:17 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-0DLxGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:17 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-pJJunu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-vSkB0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-3yghUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-xHVgN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-G5yty1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-wO2Zyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Wxj7vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-vWLtaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-GvHC8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ImHx2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:18 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-xkhEaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:19 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-OJkRx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:19 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-JGDaHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:48:19 INFO - Running setup.py install for manifestparser 04:48:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Running setup.py install for mozcrash 04:48:19 INFO - Running setup.py install for mozdebug 04:48:19 INFO - Running setup.py install for mozdevice 04:48:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Running setup.py install for mozfile 04:48:20 INFO - Running setup.py install for mozhttpd 04:48:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Running setup.py install for mozinfo 04:48:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Running setup.py install for mozInstall 04:48:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Running setup.py install for mozleak 04:48:20 INFO - Running setup.py install for mozlog 04:48:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Running setup.py install for moznetwork 04:48:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Running setup.py install for mozprocess 04:48:21 INFO - Running setup.py install for mozprofile 04:48:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:48:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:48:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:48:21 INFO - Running setup.py install for mozrunner 04:48:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:48:21 INFO - Running setup.py install for mozscreenshot 04:48:21 INFO - Running setup.py install for moztest 04:48:21 INFO - Running setup.py install for mozversion 04:48:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:48:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:48:21 INFO - Cleaning up... 04:48:21 INFO - Return code: 0 04:48:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:21 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:21 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 04:48:21 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 04:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:21 INFO - 'HOME': '/Users/cltbld', 04:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:21 INFO - 'LOGNAME': 'cltbld', 04:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:21 INFO - 'MOZ_NO_REMOTE': '1', 04:48:21 INFO - 'NO_EM_RESTART': '1', 04:48:21 INFO - 'PAGER': '/bin/cat', 04:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:21 INFO - 'PWD': '/builds/slave/test', 04:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:21 INFO - 'SHELL': '/bin/bash', 04:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:21 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:21 INFO - 'USER': 'cltbld', 04:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:21 INFO - 'XPC_FLAGS': '0x0', 04:48:21 INFO - 'XPC_SERVICE_NAME': '0', 04:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-oL04Q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-jBty4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-yuTHzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-XMH0sK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-T6fjuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-boqiyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-YP48rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:22 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QvA4kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:23 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-zUTwrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:23 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-mfdgjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-7HPtzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:23 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-FWh4wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:23 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-GUmcR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:23 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-vXP84K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-4tbHZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:23 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-_jKpKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:23 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)) 04:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:23 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-zj8rlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:24 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)) 04:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:24 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)) 04:48:24 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)) 04:48:24 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)) 04:48:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:27 INFO - Downloading blessings-1.6.tar.gz 04:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:48:27 INFO - Installing collected packages: blessings 04:48:27 INFO - Running setup.py install for blessings 04:48:27 INFO - Successfully installed blessings 04:48:27 INFO - Cleaning up... 04:48:27 INFO - Return code: 0 04:48:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:48:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:28 INFO - Reading from file tmpfile_stdout 04:48:28 INFO - Current package versions: 04:48:28 INFO - blessings == 1.6 04:48:28 INFO - blobuploader == 1.2.4 04:48:28 INFO - docopt == 0.6.1 04:48:28 INFO - functools32 == 3.2.3-2 04:48:28 INFO - jsonschema == 2.5.1 04:48:28 INFO - manifestparser == 1.1 04:48:28 INFO - mozInstall == 1.12 04:48:28 INFO - mozcrash == 0.17 04:48:28 INFO - mozdebug == 0.1 04:48:28 INFO - mozdevice == 0.48 04:48:28 INFO - mozfile == 1.2 04:48:28 INFO - mozhttpd == 0.7 04:48:28 INFO - mozinfo == 0.9 04:48:28 INFO - mozleak == 0.1 04:48:28 INFO - mozlog == 3.2 04:48:28 INFO - moznetwork == 0.27 04:48:28 INFO - mozprocess == 0.23 04:48:28 INFO - mozprofile == 0.28 04:48:28 INFO - mozrunner == 6.12 04:48:28 INFO - mozscreenshot == 0.1 04:48:28 INFO - mozsystemmonitor == 0.3 04:48:28 INFO - moztest == 0.7 04:48:28 INFO - mozversion == 1.4 04:48:28 INFO - psutil == 3.1.1 04:48:28 INFO - requests == 1.2.3 04:48:28 INFO - wsgiref == 0.1.2 04:48:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:28 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:28 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 04:48:28 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 04:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:28 INFO - 'HOME': '/Users/cltbld', 04:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:28 INFO - 'LOGNAME': 'cltbld', 04:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:28 INFO - 'MOZ_NO_REMOTE': '1', 04:48:28 INFO - 'NO_EM_RESTART': '1', 04:48:28 INFO - 'PAGER': '/bin/cat', 04:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:28 INFO - 'PWD': '/builds/slave/test', 04:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:28 INFO - 'SHELL': '/bin/bash', 04:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:28 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:28 INFO - 'USER': 'cltbld', 04:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:28 INFO - 'XPC_FLAGS': '0x0', 04:48:28 INFO - 'XPC_SERVICE_NAME': '0', 04:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 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)) 04:48:28 INFO - Cleaning up... 04:48:28 INFO - Return code: 0 04:48:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:48:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:28 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 0x10ff22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899bd8bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, '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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 04:48:28 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 04:48:28 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 04:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:28 INFO - 'HOME': '/Users/cltbld', 04:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:28 INFO - 'LOGNAME': 'cltbld', 04:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:28 INFO - 'MOZ_NO_REMOTE': '1', 04:48:28 INFO - 'NO_EM_RESTART': '1', 04:48:28 INFO - 'PAGER': '/bin/cat', 04:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:28 INFO - 'PWD': '/builds/slave/test', 04:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:28 INFO - 'SHELL': '/bin/bash', 04:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:48:28 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:28 INFO - 'USER': 'cltbld', 04:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:28 INFO - 'XPC_FLAGS': '0x0', 04:48:28 INFO - 'XPC_SERVICE_NAME': '0', 04:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:48:28 INFO - Cleaning up... 04:48:28 INFO - Return code: 0 04:48:28 INFO - Running post-action listener: _resource_record_post_action 04:48:28 INFO - Running post-action listener: _start_resource_monitoring 04:48:28 INFO - Starting resource monitoring. 04:48:28 INFO - ##### 04:48:28 INFO - ##### Finished create-virtualenv step (success) 04:48:28 INFO - ##### 04:48:28 INFO - ##### 04:48:28 INFO - ##### Running install step. 04:48:28 INFO - ##### 04:48:28 INFO - Running pre-action listener: _resource_record_pre_action 04:48:28 INFO - Running main action method: install 04:48:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:29 INFO - Reading from file tmpfile_stdout 04:48:29 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:29 INFO - Reading from file tmpfile_stdout 04:48:29 INFO - Output received: 04:48:29 INFO - Usage: mozinstall [options] installer 04:48:29 INFO - Options: 04:48:29 INFO - -h, --help show this help message and exit 04:48:29 INFO - -d DEST, --destination=DEST 04:48:29 INFO - Directory to install application into. [default: 04:48:29 INFO - "/builds/slave/test"] 04:48:29 INFO - --app=APP Application being installed. [default: firefox] 04:48:29 INFO - mkdir: /builds/slave/test/build/application 04:48:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:49:03 INFO - Reading from file tmpfile_stdout 04:49:03 INFO - Output received: 04:49:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:49:03 INFO - Running post-action listener: _resource_record_post_action 04:49:03 INFO - ##### 04:49:03 INFO - ##### Finished install step (success) 04:49:03 INFO - ##### 04:49:03 INFO - ##### 04:49:03 INFO - ##### Running run-tests step. 04:49:03 INFO - ##### 04:49:03 INFO - Running pre-action listener: _resource_record_pre_action 04:49:03 INFO - Running main action method: run_tests 04:49:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:03 INFO - 2016-07-08 04:49:03.433 screenresolution[3188:32157] starting screenresolution argv=screenresolution get 04:49:03 INFO - 2016-07-08 04:49:03.436 screenresolution[3188:32157] Display 0: 1600x1200x32@60 04:49:03 INFO - 2016-07-08 04:49:03.441 screenresolution[3189:32159] starting screenresolution argv=screenresolution list 04:49:03 INFO - Available Modes on Display 0 04:49:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:03 INFO - Intel Iris: 04:49:03 INFO - Chipset Model: Intel Iris 04:49:03 INFO - Type: GPU 04:49:03 INFO - Bus: Built-In 04:49:03 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:03 INFO - Vendor: Intel (0x8086) 04:49:03 INFO - Device ID: 0x0a2e 04:49:03 INFO - Revision ID: 0x0009 04:49:03 INFO - Displays: 04:49:03 INFO - Display: 04:49:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:03 INFO - Display Serial Number: 1600x1200 60 04:49:03 INFO - Main Display: Yes 04:49:03 INFO - Mirror: Off 04:49:03 INFO - Online: Yes 04:49:03 INFO - Rotation: Supported 04:49:03 INFO - Return code: 0 04:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:49:03 INFO - Python 2.7.3 04:49:03 INFO - Return code: 0 04:49:03 INFO - grabbing minidump binary from tooltool 04:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:03 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 0x10fe86d30>, '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 0x10fbebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:49:03 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 04:49:03 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 04:49:03 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 04:49:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:49:03 INFO - Return code: 0 04:49:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:49:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:49:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:49:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:49:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 04:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 04:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sx8uv6ECVX/Render', 04:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:03 INFO - 'HOME': '/Users/cltbld', 04:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:03 INFO - 'LOGNAME': 'cltbld', 04:49:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:49:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:03 INFO - 'MOZ_NO_REMOTE': '1', 04:49:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:49:03 INFO - 'NO_EM_RESTART': '1', 04:49:03 INFO - 'PAGER': '/bin/cat', 04:49:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:03 INFO - 'PWD': '/builds/slave/test', 04:49:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:03 INFO - 'SHELL': '/bin/bash', 04:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9f9zEmql9u/Listeners', 04:49:03 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 04:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:03 INFO - 'USER': 'cltbld', 04:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:03 INFO - 'XPC_FLAGS': '0x0', 04:49:03 INFO - 'XPC_SERVICE_NAME': '0', 04:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 04:49:04 INFO - mozversion application_buildid: 20160708025826 04:49:04 INFO - mozversion application_changeset: 401d1f8037f305ae0b9a09fd940343d87597a64a 04:49:04 INFO - mozversion application_display_name: Nightly 04:49:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:49:04 INFO - mozversion application_name: Firefox 04:49:04 INFO - mozversion application_remotingname: firefox 04:49:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:49:04 INFO - mozversion application_vendor: Mozilla 04:49:04 INFO - mozversion application_version: 50.0a1 04:49:04 INFO - mozversion platform_buildid: 20160708025826 04:49:04 INFO - mozversion platform_changeset: 401d1f8037f305ae0b9a09fd940343d87597a64a 04:49:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:49:04 INFO - mozversion platform_version: 50.0a1 04:49:04 INFO - using testdate: 1467978544 04:49:04 INFO - actual date: 1467978544 04:49:04 INFO - starting webserver on 'localhost:59403' 04:49:04 INFO - SUITE-START | Running 2 tests 04:49:04 INFO - TEST-START | dromaeo_css 04:49:04 INFO - operating with platform_type : mac_ 04:49:04 INFO - Initialising browser for dromaeo_css test... 04:49:04 INFO - TEST-INFO | started process 3199 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp4pmW_C/profile http://localhost:59403/getInfo.html) 04:49:06 INFO - PROCESS | 3199 | __metrics Screen width/height:1600/1200 04:49:06 INFO - PROCESS | 3199 | colorDepth:24 04:49:06 INFO - PROCESS | 3199 | Browser inner width/height: 1024/689 04:49:06 INFO - PROCESS | 3199 | __metrics 04:49:06 INFO - PROCESS | 3199 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:49:06 INFO - PROCESS | 3199 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:49:07 INFO - TEST-INFO | 3199: exit 0 04:49:07 INFO - Browser initialized. 04:49:07 INFO - Running cycle 1/1 for dromaeo_css test... 04:49:07 INFO - TEST-INFO | started process 3201 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp4pmW_C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:19 INFO - PROCESS | 3201 | RSS: Main: 243847168 04:49:19 INFO - PROCESS | 3201 | 04:50:10 INFO - PROCESS | 3201 | 1467978610754 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] 04:52:21 INFO - PROCESS | 3201 | Cycle 1(1): loaded http://localhost:59403/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59403/tests/dromaeo/cssquery-ext.html) 04:52:22 INFO - PROCESS | 3201 | RSS: Main: 346193920 04:52:22 INFO - PROCESS | 3201 | 04:55:25 INFO - PROCESS | 3201 | Cycle 1(1): loaded http://localhost:59403/tests/dromaeo/cssquery-ext.html (next: http://localhost:59403/tests/dromaeo/cssquery-jquery.html) 04:55:25 INFO - PROCESS | 3201 | RSS: Main: 379154432 04:55:25 INFO - PROCESS | 3201 | 04:58:27 INFO - PROCESS | 3201 | Cycle 1(1): loaded http://localhost:59403/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59403/tests/dromaeo/cssquery-mootools.html) 04:58:28 INFO - PROCESS | 3201 | RSS: Main: 369360896 04:58:28 INFO - PROCESS | 3201 | 05:01:31 INFO - PROCESS | 3201 | Cycle 1(1): loaded http://localhost:59403/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59403/tests/dromaeo/cssquery-prototype.html) 05:01:31 INFO - PROCESS | 3201 | RSS: Main: 369410048 05:01:31 INFO - PROCESS | 3201 | 05:04:35 INFO - PROCESS | 3201 | Cycle 1(1): loaded http://localhost:59403/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59403/tests/dromaeo/cssquery-yui.html) 05:04:36 INFO - PROCESS | 3201 | RSS: Main: 342519808 05:04:36 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | Cycle 1(1): loaded http://localhost:59403/tests/dromaeo/cssquery-yui.html (next: http://localhost:59403/tests/dromaeo/cssquery-dojo.html) 05:07:39 INFO - PROCESS | 3201 | RSS: Main: 392904704 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | __start_tp_report 05:07:39 INFO - PROCESS | 3201 | _x_x_mozilla_page_load 05:07:39 INFO - PROCESS | 3201 | _x_x_mozilla_page_load_details 05:07:39 INFO - PROCESS | 3201 | |i|pagename|runs| 05:07:39 INFO - PROCESS | 3201 | |0;dojo.html;3300.620428650705;3339.181900434394;3342.131045928058;3343.5956898102804;3384.8476818543163;4678.649101317402;5555.972220138894;5662.094064949609;5790.30516338039;5792.391798861122;5697.230873832032;5753.395893431192;5754.625949313302;5765.625234359774;5857.238558987326;5076.619253555979;5095.159298715707;5168.379794424655;5178.585713142958;5265.710385928773;5234.659747116425;5276.314079169702;5281.102212623844;5307.814226502073;5326.840194794143;5090.592752579785;5097.464766199532;5122.667026643257;5137.74311284438;5210.19242017486;5116.437191908887;5134.0758663440565;5158.200478925773;5196.610254230948;5286.365636123652;5145.588352931756;5159.045576568309;5159.22611608258;5221.321228240343;5243.161094224944;5562.666240025612;5632.605717599769;5761.279840019997;5785.103308987113;5822.3886491918165;5753.884922301572;6144.662043587601;6190.257169139687;6198.504119670461;6248.96875515624;6235.220597425322;6301.14934483848;6311.96844015777;6331.8733625327695;6343.968280158616;5643.858903527363;5836.358000619928;5837.941620583782;5842.2405087338375;5849.122631605208;6201;6257.155284036597;6278.058291256348;6292.653904035231;6293.1504246927;6167.907481387782;6192.659403732838;6198.845028874224;6202.4417802398;6209.813705588839;6130.846728831726;6149.446549810538;6245.812625621239;6275.811725648238;6292.339304372975;6137.775380989496;6179.412955769195;6205.131281620576;6214.285357183891;6271.78048768291;6261.96869015652;6268.153799237136;6276.686165691788;6279.0895320178315;6280.497560195174;4814.518548145227;4859.854204373803;4911.778969946361;4918.778654960535;4947.282644016597;4374.103308821684;4380.474343078851;4433.202023635777;4459.175052615276;4483.887902802456;5781.797637082682;5799.5650326225705;5817.796377126865;5828.3006039274715;5885.087811389241;6102.156009074812;6121.57491608479;6227.377262273736;6311.431971122621;6316.494680425647;5776.249087618667;5839.532837373085;5865.765369385262;5883.235179426647;5891.96890544153;5423.484768946943;5425.972870135703;5427.158790387496;5427.83716488506;5428.67427954324;6205.937940620536;6323.557350985387;6326.588771729823;6343.429091381798;6353;6122.489639783254;6137.591499645514;6202.100695399052;6204.937950620617;6214.471769899428;5397.568194544348;6180.042093475518;6181.289151747426;6277.686115694289;6287.339829317856;6177.7837775677635;6220.626762394271;6223.719932603134;6246.968765156327;6254.687265636791;6084;6159.969200153971;6232.38473965453;6268.1851359323;6297.874042519033;4996.675216110941;5807.82576522705;6132.110843927695;6135.509159267159;6143.354947730602;5011.624128190328;6111.877762444815;6116.66358350295;6117.816465506042;6129.601575897553;4604.723716577016;5736.082226843685;5846.970765146147;5914.053751399755;5974.940250597612;6092.8781424372155;6118.676751226283;6147.569670123048;6151.108089326933;6157.353477884937;5773.740181691918;5962.19510366095;5965.791197308074;5970.194023806905;6013.098035294741 05:07:39 INFO - PROCESS | 3201 | |1;ext.html;467.50678034673405;481.3333533056716;512.108930460998;531.4711861697609;592.9614575052618;29476.85261573692;40681.38977915334;41638.16723665525;41680.791596042014;44087.77956110223;42872.356914646225;43250.56749432505;43338.783306083424;43349.133017339635;43448.78275608626;8629;9603.183729382994;9791.412515249072;9793.216542676604;9799.118079372854;8670.34972377071;11569.13231507636;11803.704907377341;11805.937465628092;12053.517859285617;10839.83740243897;11853.703657408547;11928.343941083236;11946.522139114424;11962.222455540406;8689.131086891324;10989.835152472719;11800.704982375424;11809.940950295193;11826.172167948203;11344.773104537946;11597;11623;11834.112441566916;11839.94080029603;41362.37956430656;41685.58314416878;41734.582654173675;41748;41790.58209417897;3471.1669199392218;3572.517710109128;3579.2756749754612;3765.002274397287;4172.415861779354;30437.320693526257;30448.086557403312;30612.846935765403;30736.385272294654;32172.678273217436;3310.718051769758;3346.247094403764;3375.5274261603395;3402.8638854445794;3461.2558299965626;502436;505066;506440.99999999633;507518;508649;58188;58417.5395615113;58498.12252816211;58517.70741146267;58615.82768344609;62186;62894.68552657207;62982;63017;63034.36965630285;15653.37386504527;15872;15884.60288492797;15900.045997240202;15915;11066.612668556561;11409.25839820409;12058.879411205775;12297.385130743427;12359.443825027894;15746;15928.840711592968;15954.76067858983;15966.840331596535;15988.600284992735;6952.652367381712;6956.165260168812;7077.787666370017;7106.786796396013;7141.143062832493;903.742433406476;905.4250550900226;955.2310390136007;992.374803873555;1038.787048655024;27840.443191136063;30179.396412072074;30320.84839575788;31004.844975774977;31044;1007.6171054999054;1126.8816774238587;1133.9092872570175;1148.1352733773583;1223.5411720604773;13678.726425471634;15813.05121692702;16015;16070.357185712674;16260.837391626168;672232;685305.5734721294;687088;688342;688457.5577122082;59151;60642.696786515786;60686.482837928525;60861;60999.695001524706;1239.6343078791779;1287.665207046156;1397.7204559088223;1398.8181536400407;1421.133108803629;27343.58984615272;30276.69723302827;31251.84374078115;32868.50697239591;33592.1601959953;51999.480005200974;52114.47885521248;52368.47631523636;52406.73796630993;52513;625326;627946;629230;630365;631342.6865731467;26076.8696156518;26458.603120952816;26558.601620975314;27550;40369.59630403658;47432.05135897332;48230.27654585254;48680.2697959538;48735;49206.75396622994;35382.76160334376;39185.608143918194;40124;40585.18829623332;41236.38145427881;36127.55489780379;39309.01727456837;39932.80201593957;40026.79986600048;40619.390709139974;38370;38841.41737873878;39096.6090339093;39280.80359598184;39287.2142557153;28292.575611365435;28809.711902880703;32914;34318.050260483884;34751;25184.49631007406;25323.49353012966;25334.1133060342;25587.36031599225;25996.8700156498 05:07:39 INFO - PROCESS | 3201 | |2;jquery.html;357.11257525050263;379.75695554844896;388.30493416783975;388.82502873706824;413.6112054668611;15703;15795.210239488022;20893.686594701092;21007.68488472672;21062.57874842506;20501.97490125495;20936.790632093675;21320.36038918831;21823.454413639665;21827.236046738366;5210;5811.331696854852;6019.759209631609;6039.305479869805;6053.303870054956;5094.414142373619;5976.73104710289;6073.60521566099;6077.665728384937;6083.452489275965;5696.344920334152;5944.375840536746;5972.970135149319;5982.700864956757;6009.699515024253;5654.067078931971;5949.702514874239;5955.166276721263;5959.731812068424;5975.25309336333;5149.970005998778;5178.663386879841;5180.274761533388;5180.792768289264;5186.507281808223;27061.594076088677;27080.59379109315;27198.864005679847;27530.76111574964;27592;2233.452804062999;2281.6121259385777;2320.5938960681815;2323.512062466884;2499.6750422445148;15460.458883939009;16315.428959986462;16567.17164141788;16903.901246418944;16951.49145525636;1849.3619701203063;1909.8663093583455;1916.425072478251;1920.6254780317843;1933.9323123690745;106943;108484;108982.45508772484;109973.45013274883;110001.44999275032;10451.216158788122;10452.111570516592;10829;10839.295445795999;10989.230753847154;13280.203187808762;13406.994475414383;13407.994400420008;13409.932950335186;13456.990725695612;6966.268541803038;8296;8513.31893448535;8524.360672949553;8568.400211985101;8577.699780507652;8588.398812083095;8593.78515537104;8718.651253949898;8720.607572659246;6630.138082049302;6719.529632925648;6737.730490780314;6775.966120169368;6843.8860318554935;1591.299828075643;1793.9551511212323;1797.96616945257;1834.0829585207396;1848.3068849181557;910.1581037540249;912.452528482905;947.3984020147118;947.4268067819008;990.2721499697716;21202.893985529972;21985.900704964686;22304.107835686715;22621.982010809552;22952.540949180922;967.4147140979746;974.2692980264801;990.2424645146468;1025;1042.921780866438;10760.816310205872;11390.088792896548;12128.818067728991;12200.755984880252;12207.572734954412;567393;613557;619871;619967;622396;113567.43216283865;114048;114443;114967.42516287365;116919.2462113069;926.77294062955;937.779621788879;957.5403806173076;959.8032403357295;994.04571611254;19784;20228.79771202328;20229;20240.69638955446;20364;50188;52259.47740522546;52438.737806310724;55433.72283138559;56080;77646.83529747171;78136.60931695305;79784.60107699424;80470.390592189;82812;8131.674733010614;8160.5103693778565;8284.83430331402;8317.792055198668;8568.785780355542;12236.265824050586;12428.316442595744;12966.870331296566;13295.002874784623;13369.465221391036;10514.748120582371;11405.771884562428;11669;11670.94164529172;11695.298282103102;11292.830607540729;11567.479463424335;11584.710382240512;11621.76756464883;11852.288862668267;11716.297022178696;11746.647600571654;11760;11825.93566579012;11854.229475084092;8146.592670366339;8286.70996515119;8299.29455996247;8448.028476725256;8548.102449242988;11256.155788316073;11354.091672666333;11520.82718759236;11606.013488853205;11879 05:07:39 INFO - PROCESS | 3201 | |3;mootools.html;1393.0596847128186;1488.3748825493292;1545.6831349573336;1785.8928464292144;1957.3736404350614;7932.444728868974;8721.78195545111;8975.326850486215;8987.011428742833;9103.499307538099;8657.350698697606;8917.68788092417;8933.910660893389;8939.910600893989;8948.8657670135;2094;2170.924017659382;2171.023039632164;2324.395657129146;2357.870317132557;2157.7194964654573;2180.6183917814396;2198.351486311542;2396.628522579003;2398.0647547456506;2325.3837733000682;2332.5334933013382;2334.4630734930893;2334.7898689117974;2341.309313752447;2294.3117064880553;2337.742848286687;2348.7416384197722;2350.8001819845163;2351.0713268259115;2084.6351888419617;2285.405794493427;2349;2350.976490235093;2357.39886328985;8539.573021348908;8606.44058136219;8614.784630384227;8621.13788621139;8635.309175265964;4301.440812694361;5385.030694474993;5388.272583201257;5390;5390.730463476811;3482.320947415255;3535.462073997819;3573.8556865880523;3638.2359704462096;3647.74465787395;918.527222875045;1090.4057288777635;1109.046220250584;1112.6495154026536;1116.564539829467;12130.029597632169;12131.939340303243;12166.452509637;12166.96580790634;12188.81716774249;4601.585857272795;4708.905821883612;4878.853634390974;4880.170371036861;4894.975525122351;10152.137068349273;10219.693409197736;10232.334898231591;10235.846462303072;10252.794944101226;5420.07858664028;5423.403425623178;5427.9185812212845;5431.131019037014;5441.884413695183;4391.934120988188;4905.509449055137;4925.630577706686;4962.20604703253;4968.478309777422;3749.8687545935763;3952.091019065631;4073.06319546506;4107.774072426046;4138.937915931264;1270.917390369623;1293.6701141208932;1295.643697983062;1303.758804621148;1310.1287643717012;862.779991102265;931.4411353188033;937.8499440089689;965.2374624047064;976.3702411944256;5986.042233242747;6029.909551356734;6125.111858780541;6259.561830671901;6281.623102613858;938.2259635800492;944.428620684476;947.5262368815593;973.9318247722591;991.6876184002016;4047.291723948297;4093.488313960755;4093.7543747375253;4179.958200418017;4182.519010313792;10805.351678899291;10957.780844383067;10965.95823396776;11118.999290063934;11140.721481963015;11006.944965275123;11023.779524409467;11032.779344413068;11047.72380690473;11107.333559986426;8021.639026243833;8278.379121565908;8405.453645513144;8434.15658434164;8445.071042185355;6096.695165241809;6119.541034422347;6210.7515699371515;6239.781607643892;6248.219257511588;10248.026437488426;10306.381617102998;10421.68734937953;10441.947790261303;10456.47717614101;10070.446125462877;10216.1827053837;10484.790304194026;10581.36511809294;10598.364098154136;7146.428285737233;7421.925780742124;7770.766876993699;7930.841383172419;7942.960285198537;8313.625886835229;8526.74419767408;8566.828663426571;8642.654293828176;8650.870236946577;7522.51103678281;7573.129090154703;7712.614369281626;7821.6089195541135;7855.450118491651;7411.221821708643;7522.511036782592;7543.698252069856;7696.692132314644;7715.382769378324;7149.82125446868;7154.320339567733;7225.855482890418;7233.240509746401;7241.348278654947;5001.924971125509;5116.283720279238;5118.97440512795;5192.636515443883;5214.42641309448;5179.533841954242;5186.429492755869;5350;5381.865453363541;5397.811076612456 05:07:39 INFO - PROCESS | 3201 | |4;prototype.html;298.4582981887872;304.09834839700284;311.5949265954259;312.4298155865546;316.66117254537653;4010.7192496525254;4650.837220697268;4846.636502262327;4857.514248575145;4954.504549545044;4268.335349213801;4803.943132510842;5036.330744705303;5225.921611175835;5234.7644356003975;3995.740276882008;4005.7997100144876;4023.8189281482255;4037.3742069979053;4067.2678917794783;3352.296017836245;3916.8445308634023;4039.9596004040022;4045.1505183911413;4072.2669919414493;3877.6510114089724;3972.860949866755;3985.302572049894;4030.3349947258666;4059.4519739835187;3451.032911240594;3963.365861462152;4012.3780813974026;4014.3978403239457;4017.2969730297373;2846.402255526342;3367.562216911786;3538.2755278435716;3546.733994950389;3554.680078792895;4129.584193246954;4252.213340532012;4758.00081982784;5130;5163.380394352665;4562;4565.977170114162;4568.588827005586;4576.885577860547;4578.633709303248;4539.931901021488;4944.777485013183;5508.807191748269;5563.248961390242;5610;3178.936421271562;3714.882516965331;3809.923801523954;3840.7503512271614;3855.8264878080554;4499.775011249457;5038.748062596856;5165.560927321182;5397.622166448311;5580.804671836466;3351.128706536318;3658.6707196352454;3698.871844087579;3801.2777572261184;3837.347650899354;3766.794625719743;3885.6400259908805;3889.4360317753767;3890.513685789276;3903.765774053566;4007.779572123561;4494.775261236925;4526.569975852354;4540.909181816345;4553.726776393427;5100.974495127501;5201.323827902348;5232.581393488512;5492.780288788404;5596.42564420363;1123.7696272264166;1318.1432069155126;1322.2529270961875;1322.5848342273866;1326.907116501855;714.8748968930416;768.7808974442257;801.4309840013656;813.1054642682535;816.8243827577099;3666.4500324950936;4270.668878274951;4292.892677683083;4320.632746216606;4416.381706561084;3774.471573979645;4495.955040449554;4662.622062747955;4666.673332866667;4716.316134160596;4012.518497780227;4128.566500517462;4283;4318.935215971763;4412.462833870473;2042.4571408596394;2096.9475763106043;2257.3340864445026;2303.700518932528;2304.4354133237466;4156.363182215561;4347.021920068023;4508.368828364031;4695.906081878344;4785.59322457588;4337.132573485315;4427.605304329126;4596.908061838744;4746.5253474652945;4850.975745121323;3813.466114743938;4442.579214005263;4739.3838800955655;4895.388076490439;4929.359183306147;3748.2128752962185;4361.040571074359;4454.331850222493;4630.467496237976;4640.141573808856;4033.6571391432058;4309.91380172401;4341.630961368319;4550.817967281272;4734.857954261378;4168.979155104205;5306.575473961997;5586.944130558642;5693.971530142323;5794.565407594363;2917.693642167542;3678.301122786662;3709.795961222159;3773.6226177507992;3795.842268108143;5207;5665;5684.857878553069;5713.085906254979;5731;4879.95120048795;5162.97418512905;5553;5564.276644036249;5572;5270.446603106619;5538.224648549126;5540.69526176048;5603.38362780102;5627.380988091389;4534.750588717652;5458.727063646881;5472.233887255867;5472.370677372153;5475;4849.636277279147;5407.972960135174;5564.721763911869;5719.198452487453;5754.683492407958;4549.27211646135;5558.861028474321;5572.554195664256;5585.441455854382;5631.7047079172835 05:07:39 INFO - PROCESS | 3201 | |5;yui.html;689.9931000689992;751.1474476469209;772.7720322504861;776.8446310737854;850.8255807559449;14926.776098358534;16334.01995880248;16341.18294085294;16344.509664710078;16356.591085222875;14866.925665371686;14907.776383354258;15014.174220417872;15033.849661503382;15059.698806023873;706.9399101076389;728.2353528794771;882.2897567458209;902.2195800632438;902.3097330542108;909.2271569166189;910.0626354854511;911.3392790227066;914.0128661046087;916.2761418479427;961.8749562556893;973.6640858903702;974.493263502978;978.188103873783;979.294907666479;748.2816496163689;932.47315266874;952.1383148250844;964.4599024546242;966.5891995901749;713.386487620651;714.6498215874236;716.9139703235645;718.5760401363168;718.881384571545;12067.577634782829;14983.250837458083;15018.474353397687;15052.849471505255;15074.24628768568;13776.862231377658;13884.09753366038;13958.325000999945;14047.297635118306;14333.641658958506;12726.618201453972;12734.235945843278;12851.421686024152;12889;13096.672583185402;4359.193549193409;4414.359917811899;4548.295014272793;4584.88537786558;4732.1482133216;110358;111244.33133503005;111404.32893506603;111637.44181279122;112015.99999999919;9172.541372931326;11100.333979961228;11202.551897923991;11361.659150225507;11421.086313094933;19629.31297404584;21351.893240533696;22043.559128817335;22102;22103.226387076375;7666.463347565728;7798.415118866108;7835.804104897423;7836.568988705562;8003.599820008976;4217.831286748557;4251.936220956688;4367.148406060787;4370.562943705668;4499.392582001388;3427.6572342765526;3527.2945410917405;3549.148204430918;3549.716022718176;3553.218291975761;524.4912434938104;636.2937139774846;639.48840927258;655.5574986883835;655.8327626455224;514.5703337712977;529.7774934527507;668.4518694670421;679.7145199016425;693.3621068616884;9420.387674801115;9598.376105553256;9645.276604254708;9647.469389183661;9779.364341317934;579.8434422705845;661.9470442364599;670.367758786781;674.7301079568211;686.5034027122329;4443.2224360736745;4674.117869926314;4702.200625893606;4709.929351059737;4799.856004319876;99695.50152249192;122899.3855030737;134295.32852335676;155390;167006;138396.30801845927;140789.32950821312;142084.28957855146;143276;145319;117343;122707;123024.38487807504;123428.6799961997;125761.74238257683;8662.393632445668;8703.608337624948;8780.78048048804;8817.735467935972;9209.907900920905;42611;54713.72643136759;64157;65328.67335663291;66546.66726666335;78418.21581784337;85337;90502;91377;96458;11746.882531174579;11764.235324703866;11820.290782553075;11998;12052.276863388224;10430.843537346795;13062.281574513492;13470.326483675974;13477.797833032317;13520;8252.593073905522;12471.875281247072;14217.928910355382;14239.928800355932;14597.416103355747;14213.076150050214;14231.573052808433;14377.64055898611;14526.709465810412;14534.273286335852;13876.791848122084;14047.719045618825;14158.221297828719;14415.279236037946;14507.564773056825;10156.339837910513;11316.434178290889;11516.827247591125;11528.538858445569;11898.98858597029;10123.292036808396;11250.774984500427;11332;11588.88411115878;11600.477978490819 05:07:39 INFO - PROCESS | 3201 | __end_tp_report 05:07:39 INFO - PROCESS | 3201 | __start_cc_report 05:07:39 INFO - PROCESS | 3201 | _x_x_mozilla_cycle_collect,1129 05:07:39 INFO - PROCESS | 3201 | __end_cc_report 05:07:39 INFO - PROCESS | 3201 | __startTimestamp1467979659413__endTimestamp 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | ------- Summary: start ------- 05:07:39 INFO - PROCESS | 3201 | Number of tests: 6 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | [#0] dojo.html Cycles:165 Average:5717.13 Median:5885.09 stddev:657.04 (11.2%) stddev-sans-first:631.12 05:07:39 INFO - PROCESS | 3201 | Values: 3300.6 3339.2 3342.1 3343.6 3384.8 4678.6 5556.0 5662.1 5790.3 5792.4 5697.2 5753.4 5754.6 5765.6 5857.2 5076.6 5095.2 5168.4 5178.6 5265.7 5234.7 5276.3 5281.1 5307.8 5326.8 5090.6 5097.5 5122.7 5137.7 5210.2 5116.4 5134.1 5158.2 5196.6 5286.4 5145.6 5159.0 5159.2 5221.3 5243.2 5562.7 5632.6 5761.3 5785.1 5822.4 5753.9 6144.7 6190.3 6198.5 6249.0 6235.2 6301.1 6312.0 6331.9 6344.0 5643.9 5836.4 5837.9 5842.2 5849.1 6201.0 6257.2 6278.1 6292.7 6293.2 6167.9 6192.7 6198.8 6202.4 6209.8 6130.8 6149.4 6245.8 6275.8 6292.3 6137.8 6179.4 6205.1 6214.3 6271.8 6262.0 6268.2 6276.7 6279.1 6280.5 4814.5 4859.9 4911.8 4918.8 4947.3 4374.1 4380.5 4433.2 4459.2 4483.9 5781.8 5799.6 5817.8 5828.3 5885.1 6102.2 6121.6 6227.4 6311.4 6316.5 5776.2 5839.5 5865.8 5883.2 5892.0 5423.5 5426.0 5427.2 5427.8 5428.7 6205.9 6323.6 6326.6 6343.4 6353.0 6122.5 6137.6 6202.1 6204.9 6214.5 5397.6 6180.0 6181.3 6277.7 6287.3 6177.8 6220.6 6223.7 6247.0 6254.7 6084.0 6160.0 6232.4 6268.2 6297.9 4996.7 5807.8 6132.1 6135.5 6143.4 5011.6 6111.9 6116.7 6117.8 6129.6 4604.7 5736.1 5847.0 5914.1 5974.9 6092.9 6118.7 6147.6 6151.1 6157.4 5773.7 5962.2 5965.8 5970.2 6013.1 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | [#1] ext.html Cycles:180 Average:73739.06 Median:38605.71 stddev:163492.22 (423.5%) stddev-sans-first:163858.30 05:07:39 INFO - PROCESS | 3201 | Values: 467.5 481.3 512.1 531.5 593.0 29476.9 40681.4 41638.2 41680.8 44087.8 42872.4 43250.6 43338.8 43349.1 43448.8 8629.0 9603.2 9791.4 9793.2 9799.1 8670.3 11569.1 11803.7 11805.9 12053.5 10839.8 11853.7 11928.3 11946.5 11962.2 8689.1 10989.8 11800.7 11809.9 11826.2 11344.8 11597.0 11623.0 11834.1 11839.9 41362.4 41685.6 41734.6 41748.0 41790.6 3471.2 3572.5 3579.3 3765.0 4172.4 30437.3 30448.1 30612.8 30736.4 32172.7 3310.7 3346.2 3375.5 3402.9 3461.3 502436.0 505066.0 506441.0 507518.0 508649.0 58188.0 58417.5 58498.1 58517.7 58615.8 62186.0 62894.7 62982.0 63017.0 63034.4 15653.4 15872.0 15884.6 15900.0 15915.0 11066.6 11409.3 12058.9 12297.4 12359.4 15746.0 15928.8 15954.8 15966.8 15988.6 6952.7 6956.2 7077.8 7106.8 7141.1 903.7 905.4 955.2 992.4 1038.8 27840.4 30179.4 30320.8 31004.8 31044.0 1007.6 1126.9 1133.9 1148.1 1223.5 13678.7 15813.1 16015.0 16070.4 16260.8 672232.0 685305.6 687088.0 688342.0 688457.6 59151.0 60642.7 60686.5 60861.0 60999.7 1239.6 1287.7 1397.7 1398.8 1421.1 27343.6 30276.7 31251.8 32868.5 33592.2 51999.5 52114.5 52368.5 52406.7 52513.0 625326.0 627946.0 629230.0 630365.0 631342.7 26076.9 26458.6 26558.6 27550.0 40369.6 47432.1 48230.3 48680.3 48735.0 49206.8 35382.8 39185.6 40124.0 40585.2 41236.4 36127.6 39309.0 39932.8 40026.8 40619.4 38370.0 38841.4 39096.6 39280.8 39287.2 28292.6 28809.7 32914.0 34318.1 34751.0 25184.5 25323.5 25334.1 25587.4 25996.9 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | [#2] jquery.html Cycles:180 Average:35133.12 Median:2411.59 stddev:100980.19 (4187.3%) stddev-sans-first:101229.71 05:07:39 INFO - PROCESS | 3201 | Values: 357.1 379.8 388.3 388.8 413.6 15703.0 15795.2 20893.7 21007.7 21062.6 20502.0 20936.8 21320.4 21823.5 21827.2 5210.0 5811.3 6019.8 6039.3 6053.3 5094.4 5976.7 6073.6 6077.7 6083.5 5696.3 5944.4 5973.0 5982.7 6009.7 5654.1 5949.7 5955.2 5959.7 5975.3 5150.0 5178.7 5180.3 5180.8 5186.5 27061.6 27080.6 27198.9 27530.8 27592.0 2233.5 2281.6 2320.6 2323.5 2499.7 15460.5 16315.4 16567.2 16903.9 16951.5 1849.4 1909.9 1916.4 1920.6 1933.9 106943.0 108484.0 108982.5 109973.5 110001.4 10451.2 10452.1 10829.0 10839.3 10989.2 13280.2 13407.0 13408.0 13409.9 13457.0 6966.3 8296.0 8513.3 8524.4 8568.4 8577.7 8588.4 8593.8 8718.7 8720.6 6630.1 6719.5 6737.7 6776.0 6843.9 1591.3 1794.0 1798.0 1834.1 1848.3 910.2 912.5 947.4 947.4 990.3 21202.9 21985.9 22304.1 22622.0 22952.5 967.4 974.3 990.2 1025.0 1042.9 10760.8 11390.1 12128.8 12200.8 12207.6 567393.0 613557.0 619871.0 619967.0 622396.0 113567.4 114048.0 114443.0 114967.4 116919.2 926.8 937.8 957.5 959.8 994.0 19784.0 20228.8 20229.0 20240.7 20364.0 50188.0 52259.5 52438.7 55433.7 56080.0 77646.8 78136.6 79784.6 80470.4 82812.0 8131.7 8160.5 8284.8 8317.8 8568.8 12236.3 12428.3 12966.9 13295.0 13369.5 10514.7 11405.8 11669.0 11670.9 11695.3 11292.8 11567.5 11584.7 11621.8 11852.3 11716.3 11746.6 11760.0 11825.9 11854.2 8146.6 8286.7 8299.3 8448.0 8548.1 11256.2 11354.1 11520.8 11606.0 11879.0 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | [#3] mootools.html Cycles:180 Average:5844.24 Median:4900.24 stddev:3310.70 (67.6%) stddev-sans-first:3303.08 05:07:39 INFO - PROCESS | 3201 | Values: 1393.1 1488.4 1545.7 1785.9 1957.4 7932.4 8721.8 8975.3 8987.0 9103.5 8657.4 8917.7 8933.9 8939.9 8948.9 2094.0 2170.9 2171.0 2324.4 2357.9 2157.7 2180.6 2198.4 2396.6 2398.1 2325.4 2332.5 2334.5 2334.8 2341.3 2294.3 2337.7 2348.7 2350.8 2351.1 2084.6 2285.4 2349.0 2351.0 2357.4 8539.6 8606.4 8614.8 8621.1 8635.3 4301.4 5385.0 5388.3 5390.0 5390.7 3482.3 3535.5 3573.9 3638.2 3647.7 918.5 1090.4 1109.0 1112.6 1116.6 12130.0 12131.9 12166.5 12167.0 12188.8 4601.6 4708.9 4878.9 4880.2 4895.0 10152.1 10219.7 10232.3 10235.8 10252.8 5420.1 5423.4 5427.9 5431.1 5441.9 4391.9 4905.5 4925.6 4962.2 4968.5 3749.9 3952.1 4073.1 4107.8 4138.9 1270.9 1293.7 1295.6 1303.8 1310.1 862.8 931.4 937.8 965.2 976.4 5986.0 6029.9 6125.1 6259.6 6281.6 938.2 944.4 947.5 973.9 991.7 4047.3 4093.5 4093.8 4180.0 4182.5 10805.4 10957.8 10966.0 11119.0 11140.7 11006.9 11023.8 11032.8 11047.7 11107.3 8021.6 8278.4 8405.5 8434.2 8445.1 6096.7 6119.5 6210.8 6239.8 6248.2 10248.0 10306.4 10421.7 10441.9 10456.5 10070.4 10216.2 10484.8 10581.4 10598.4 7146.4 7421.9 7770.8 7930.8 7943.0 8313.6 8526.7 8566.8 8642.7 8650.9 7522.5 7573.1 7712.6 7821.6 7855.5 7411.2 7522.5 7543.7 7696.7 7715.4 7149.8 7154.3 7225.9 7233.2 7241.3 5001.9 5116.3 5119.0 5192.6 5214.4 5179.5 5186.4 5350.0 5381.9 5397.8 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | [#4] prototype.html Cycles:180 Average:4185.50 Median:4497.87 stddev:1287.95 (28.6%) stddev-sans-first:1258.09 05:07:39 INFO - PROCESS | 3201 | Values: 298.5 304.1 311.6 312.4 316.7 4010.7 4650.8 4846.6 4857.5 4954.5 4268.3 4803.9 5036.3 5225.9 5234.8 3995.7 4005.8 4023.8 4037.4 4067.3 3352.3 3916.8 4040.0 4045.2 4072.3 3877.7 3972.9 3985.3 4030.3 4059.5 3451.0 3963.4 4012.4 4014.4 4017.3 2846.4 3367.6 3538.3 3546.7 3554.7 4129.6 4252.2 4758.0 5130.0 5163.4 4562.0 4566.0 4568.6 4576.9 4578.6 4539.9 4944.8 5508.8 5563.2 5610.0 3178.9 3714.9 3809.9 3840.8 3855.8 4499.8 5038.7 5165.6 5397.6 5580.8 3351.1 3658.7 3698.9 3801.3 3837.3 3766.8 3885.6 3889.4 3890.5 3903.8 4007.8 4494.8 4526.6 4540.9 4553.7 5101.0 5201.3 5232.6 5492.8 5596.4 1123.8 1318.1 1322.3 1322.6 1326.9 714.9 768.8 801.4 813.1 816.8 3666.5 4270.7 4292.9 4320.6 4416.4 3774.5 4496.0 4662.6 4666.7 4716.3 4012.5 4128.6 4283.0 4318.9 4412.5 2042.5 2096.9 2257.3 2303.7 2304.4 4156.4 4347.0 4508.4 4695.9 4785.6 4337.1 4427.6 4596.9 4746.5 4851.0 3813.5 4442.6 4739.4 4895.4 4929.4 3748.2 4361.0 4454.3 4630.5 4640.1 4033.7 4309.9 4341.6 4550.8 4734.9 4169.0 5306.6 5586.9 5694.0 5794.6 2917.7 3678.3 3709.8 3773.6 3795.8 5207.0 5665.0 5684.9 5713.1 5731.0 4880.0 5163.0 5553.0 5564.3 5572.0 5270.4 5538.2 5540.7 5603.4 5627.4 4534.8 5458.7 5472.2 5472.4 5475.0 4849.6 5408.0 5564.7 5719.2 5754.7 4549.3 5558.9 5572.6 5585.4 5631.7 05:07:39 INFO - PROCESS | 3201 | 05:07:39 INFO - PROCESS | 3201 | [#5] yui.html Cycles:180 Average:25059.53 Median:3551.47 stddev:40401.23 (1137.6%) stddev-sans-first:40473.13 05:07:39 INFO - PROCESS | 3201 | Values: 690.0 751.1 772.8 776.8 850.8 14926.8 16334.0 16341.2 16344.5 16356.6 14866.9 14907.8 15014.2 15033.8 15059.7 706.9 728.2 882.3 902.2 902.3 909.2 910.1 911.3 914.0 916.3 961.9 973.7 974.5 978.2 979.3 748.3 932.5 952.1 964.5 966.6 713.4 714.6 716.9 718.6 718.9 12067.6 14983.3 15018.5 15052.8 15074.2 13776.9 13884.1 13958.3 14047.3 14333.6 12726.6 12734.2 12851.4 12889.0 13096.7 4359.2 4414.4 4548.3 4584.9 4732.1 110358.0 111244.3 111404.3 111637.4 112016.0 9172.5 11100.3 11202.6 11361.7 11421.1 19629.3 21351.9 22043.6 22102.0 22103.2 7666.5 7798.4 7835.8 7836.6 8003.6 4217.8 4251.9 4367.1 4370.6 4499.4 3427.7 3527.3 3549.1 3549.7 3553.2 524.5 636.3 639.5 655.6 655.8 514.6 529.8 668.5 679.7 693.4 9420.4 9598.4 9645.3 9647.5 9779.4 579.8 661.9 670.4 674.7 686.5 4443.2 4674.1 4702.2 4709.9 4799.9 99695.5 122899.4 134295.3 155390.0 167006.0 138396.3 140789.3 142084.3 143276.0 145319.0 117343.0 122707.0 123024.4 123428.7 125761.7 8662.4 8703.6 8780.8 8817.7 9209.9 42611.0 54713.7 64157.0 65328.7 66546.7 78418.2 85337.0 90502.0 91377.0 96458.0 11746.9 11764.2 11820.3 11998.0 12052.3 10430.8 13062.3 13470.3 13477.8 13520.0 8252.6 12471.9 14217.9 14239.9 14597.4 14213.1 14231.6 14377.6 14526.7 14534.3 13876.8 14047.7 14158.2 14415.3 14507.6 10156.3 11316.4 11516.8 11528.5 11899.0 10123.3 11250.8 11332.0 11588.9 11600.5 05:07:39 INFO - PROCESS | 3201 | -------- Summary: end -------- 05:07:39 INFO - PROCESS | 3201 | 05:07:40 INFO - TEST-INFO | 3201: exit 0 05:07:40 INFO - TEST-OK | dromaeo_css | took 1116055ms 05:07:40 INFO - TEST-START | kraken 05:07:40 INFO - operating with platform_type : mac_ 05:07:40 INFO - Initialising browser for kraken test... 05:07:40 INFO - TEST-INFO | started process 3314 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpCE3tWO/profile http://localhost:59403/getInfo.html) 05:07:42 INFO - PROCESS | 3314 | __metrics Screen width/height:1600/1200 05:07:42 INFO - PROCESS | 3314 | colorDepth:24 05:07:42 INFO - PROCESS | 3314 | Browser inner width/height: 1024/689 05:07:42 INFO - PROCESS | 3314 | __metrics 05:07:42 INFO - PROCESS | 3314 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:07:42 INFO - PROCESS | 3314 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:42 INFO - TEST-INFO | 3314: exit 0 05:07:42 INFO - Browser initialized. 05:07:42 INFO - Running cycle 1/1 for kraken test... 05:07:42 INFO - TEST-INFO | started process 3315 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpCE3tWO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:55 INFO - PROCESS | 3315 | RSS: Main: 243703808 05:07:55 INFO - PROCESS | 3315 | 05:07:57 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59403/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:58 INFO - PROCESS | 3315 | RSS: Main: 294166528 05:07:58 INFO - PROCESS | 3315 | 05:08:01 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59403/tests/kraken/driver.html?testName=audio-dft) 05:08:02 INFO - PROCESS | 3315 | RSS: Main: 304599040 05:08:02 INFO - PROCESS | 3315 | 05:08:05 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59403/tests/kraken/driver.html?testName=audio-fft) 05:08:06 INFO - PROCESS | 3315 | RSS: Main: 389541888 05:08:06 INFO - PROCESS | 3315 | 05:08:08 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59403/tests/kraken/driver.html?testName=audio-oscillator) 05:08:09 INFO - PROCESS | 3315 | RSS: Main: 291127296 05:08:09 INFO - PROCESS | 3315 | 05:08:11 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59403/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:08:12 INFO - PROCESS | 3315 | RSS: Main: 294780928 05:08:12 INFO - PROCESS | 3315 | 05:08:14 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59403/tests/kraken/driver.html?testName=imaging-darkroom) 05:08:15 INFO - PROCESS | 3315 | RSS: Main: 332296192 05:08:15 INFO - PROCESS | 3315 | 05:08:18 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59403/tests/kraken/driver.html?testName=imaging-desaturate) 05:08:19 INFO - PROCESS | 3315 | RSS: Main: 332996608 05:08:19 INFO - PROCESS | 3315 | 05:08:21 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59403/tests/kraken/driver.html?testName=json-parse-financial) 05:08:22 INFO - PROCESS | 3315 | RSS: Main: 334118912 05:08:22 INFO - PROCESS | 3315 | 05:08:24 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59403/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:08:24 INFO - PROCESS | 3315 | RSS: Main: 311492608 05:08:24 INFO - PROCESS | 3315 | 05:08:26 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:08:27 INFO - PROCESS | 3315 | RSS: Main: 318480384 05:08:27 INFO - PROCESS | 3315 | 05:08:29 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:30 INFO - PROCESS | 3315 | RSS: Main: 315887616 05:08:30 INFO - PROCESS | 3315 | 05:08:32 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:33 INFO - PROCESS | 3315 | RSS: Main: 324878336 05:08:33 INFO - PROCESS | 3315 | 05:08:36 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:37 INFO - PROCESS | 3315 | RSS: Main: 326414336 05:08:37 INFO - PROCESS | 3315 | 05:08:38 INFO - PROCESS | 3315 | Cycle 1(1): loaded http://localhost:59403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59403/tests/kraken/driver.html?testName=ai-astar) 05:08:39 INFO - PROCESS | 3315 | RSS: Main: 369451008 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | __start_tp_report 05:08:39 INFO - PROCESS | 3315 | _x_x_mozilla_page_load 05:08:39 INFO - PROCESS | 3315 | _x_x_mozilla_page_load_details 05:08:39 INFO - PROCESS | 3315 | |i|pagename|runs| 05:08:39 INFO - PROCESS | 3315 | |0;ai-astar;100;111;110;116;115;112;113;112;112;118 05:08:39 INFO - PROCESS | 3315 | |1;audio-beat-detection;167;136;105;191;140;135;127;107;158;105 05:08:39 INFO - PROCESS | 3315 | |2;audio-dft;186;204;264;230;185;183;202;256;181;195 05:08:39 INFO - PROCESS | 3315 | |3;audio-fft;84;76;58;110;56;57;64;65;84;81 05:08:39 INFO - PROCESS | 3315 | |4;audio-oscillator;83;93;94;71;71;69;95;70;68;106 05:08:39 INFO - PROCESS | 3315 | |5;imaging-gaussian-blur;88;85;88;86;87;85;88;89;88;85 05:08:39 INFO - PROCESS | 3315 | |6;imaging-darkroom;93;93;93;94;92;94;94;101;151;125 05:08:39 INFO - PROCESS | 3315 | |7;imaging-desaturate;82;82;83;85;81;83;82;101;137;85 05:08:39 INFO - PROCESS | 3315 | |8;json-parse-financial;56;53;53;55;55;54;54;53;55;54 05:08:39 INFO - PROCESS | 3315 | |9;json-stringify-tinderbox;65;69;77;77;72;70;71;72;71;71 05:08:39 INFO - PROCESS | 3315 | |10;stanford-crypto-aes;82;80;85;85;83;81;85;85;83;80 05:08:39 INFO - PROCESS | 3315 | |11;stanford-crypto-ccm;143;145;143;135;135;134;134;133;178;136 05:08:39 INFO - PROCESS | 3315 | |12;stanford-crypto-pbkdf2;164;162;172;161;161;204;162;163;163;161 05:08:39 INFO - PROCESS | 3315 | |13;stanford-crypto-sha256-iterative;66;66;66;67;67;78;84;85;82;84 05:08:39 INFO - PROCESS | 3315 | __end_tp_report 05:08:39 INFO - PROCESS | 3315 | __start_cc_report 05:08:39 INFO - PROCESS | 3315 | _x_x_mozilla_cycle_collect,2151 05:08:39 INFO - PROCESS | 3315 | __end_cc_report 05:08:39 INFO - PROCESS | 3315 | __startTimestamp1467979719129__endTimestamp 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | ------- Summary: start ------- 05:08:39 INFO - PROCESS | 3315 | Number of tests: 14 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.50 stddev:4.84 (4.3%) stddev-sans-first:2.59 05:08:39 INFO - PROCESS | 3315 | Values: 100.0 111.0 110.0 116.0 115.0 112.0 113.0 112.0 112.0 118.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:138.00 stddev:28.52 (20.7%) stddev-sans-first:28.12 05:08:39 INFO - PROCESS | 3315 | Values: 167.0 136.0 105.0 191.0 140.0 135.0 127.0 107.0 158.0 105.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#2] audio-dft Cycles:10 Average:208.60 Median:203.00 stddev:30.75 (15.1%) stddev-sans-first:31.51 05:08:39 INFO - PROCESS | 3315 | Values: 186.0 204.0 264.0 230.0 185.0 183.0 202.0 256.0 181.0 195.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#3] audio-fft Cycles:10 Average:73.50 Median:70.50 stddev:16.99 (24.1%) stddev-sans-first:17.59 05:08:39 INFO - PROCESS | 3315 | Values: 84.0 76.0 58.0 110.0 56.0 57.0 64.0 65.0 84.0 81.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:77.00 stddev:13.99 (18.2%) stddev-sans-first:14.84 05:08:39 INFO - PROCESS | 3315 | Values: 83.0 93.0 94.0 71.0 71.0 69.0 95.0 70.0 68.0 106.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.52 (1.7%) stddev-sans-first:1.56 05:08:39 INFO - PROCESS | 3315 | Values: 88.0 85.0 88.0 86.0 87.0 85.0 88.0 89.0 88.0 85.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:93.00 stddev:19.60 (21.1%) stddev-sans-first:20.45 05:08:39 INFO - PROCESS | 3315 | Values: 93.0 93.0 93.0 94.0 92.0 94.0 94.0 101.0 151.0 125.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#7] imaging-desaturate Cycles:10 Average:90.10 Median:82.50 stddev:17.48 (21.2%) stddev-sans-first:18.30 05:08:39 INFO - PROCESS | 3315 | Values: 82.0 82.0 83.0 85.0 81.0 83.0 82.0 101.0 137.0 85.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.03 (1.9%) stddev-sans-first:0.87 05:08:39 INFO - PROCESS | 3315 | Values: 56.0 53.0 53.0 55.0 55.0 54.0 54.0 53.0 55.0 54.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:3.54 (4.9%) stddev-sans-first:2.86 05:08:39 INFO - PROCESS | 3315 | Values: 65.0 69.0 77.0 77.0 72.0 70.0 71.0 72.0 71.0 71.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:84.00 stddev:2.08 (2.5%) stddev-sans-first:2.18 05:08:39 INFO - PROCESS | 3315 | Values: 82.0 80.0 85.0 85.0 83.0 81.0 85.0 85.0 83.0 80.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:139.50 stddev:13.53 (9.7%) stddev-sans-first:14.34 05:08:39 INFO - PROCESS | 3315 | Values: 143.0 145.0 143.0 135.0 135.0 134.0 134.0 133.0 178.0 136.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:163.00 stddev:13.30 (8.2%) stddev-sans-first:14.05 05:08:39 INFO - PROCESS | 3315 | Values: 164.0 162.0 172.0 161.0 161.0 204.0 162.0 163.0 163.0 161.0 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - PROCESS | 3315 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:80.00 stddev:8.75 (10.9%) stddev-sans-first:8.72 05:08:39 INFO - PROCESS | 3315 | Values: 66.0 66.0 66.0 67.0 67.0 78.0 84.0 85.0 82.0 84.0 05:08:39 INFO - PROCESS | 3315 | -------- Summary: end -------- 05:08:39 INFO - PROCESS | 3315 | 05:08:39 INFO - TEST-INFO | 3315: exit 0 05:08:39 INFO - TEST-OK | kraken | took 59880ms 05:08:39 INFO - SUITE-END | took 1175s 05:08:40 INFO - Completed test suite (00:19:36) 05:08:40 INFO - javascript benchmark 05:08:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.620428650705, 3339.181900434394, 3342.131045928058, 3343.5956898102804, 3384.8476818543163, 4678.649101317402, 5555.972220138894, 5662.094064949609, 5790.30516338039, 5792.391798861122, 5697.230873832032, 5753.395893431192, 5754.625949313302, 5765.625234359774, 5857.238558987326, 5076.619253555979, 5095.159298715707, 5168.379794424655, 5178.585713142958, 5265.710385928773, 5234.659747116425, 5276.314079169702, 5281.102212623844, 5307.814226502073, 5326.840194794143, 5090.592752579785, 5097.464766199532, 5122.667026643257, 5137.74311284438, 5210.19242017486, 5116.437191908887, 5134.0758663440565, 5158.200478925773, 5196.610254230948, 5286.365636123652, 5145.588352931756, 5159.045576568309, 5159.22611608258, 5221.321228240343, 5243.161094224944, 5562.666240025612, 5632.605717599769, 5761.279840019997, 5785.103308987113, 5822.3886491918165, 5753.884922301572, 6144.662043587601, 6190.257169139687, 6198.504119670461, 6248.96875515624, 6235.220597425322, 6301.14934483848, 6311.96844015777, 6331.8733625327695, 6343.968280158616, 5643.858903527363, 5836.358000619928, 5837.941620583782, 5842.2405087338375, 5849.122631605208, 6201.0, 6257.155284036597, 6278.058291256348, 6292.653904035231, 6293.1504246927, 6167.907481387782, 6192.659403732838, 6198.845028874224, 6202.4417802398, 6209.813705588839, 6130.846728831726, 6149.446549810538, 6245.812625621239, 6275.811725648238, 6292.339304372975, 6137.775380989496, 6179.412955769195, 6205.131281620576, 6214.285357183891, 6271.78048768291, 6261.96869015652, 6268.153799237136, 6276.686165691788, 6279.0895320178315, 6280.497560195174, 4814.518548145227, 4859.854204373803, 4911.778969946361, 4918.778654960535, 4947.282644016597, 4374.103308821684, 4380.474343078851, 4433.202023635777, 4459.175052615276, 4483.887902802456, 5781.797637082682, 5799.5650326225705, 5817.796377126865, 5828.3006039274715, 5885.087811389241, 6102.156009074812, 6121.57491608479, 6227.377262273736, 6311.431971122621, 6316.494680425647, 5776.249087618667, 5839.532837373085, 5865.765369385262, 5883.235179426647, 5891.96890544153, 5423.484768946943, 5425.972870135703, 5427.158790387496, 5427.83716488506, 5428.67427954324, 6205.937940620536, 6323.557350985387, 6326.588771729823, 6343.429091381798, 6353.0, 6122.489639783254, 6137.591499645514, 6202.100695399052, 6204.937950620617, 6214.471769899428, 5397.568194544348, 6180.042093475518, 6181.289151747426, 6277.686115694289, 6287.339829317856, 6177.7837775677635, 6220.626762394271, 6223.719932603134, 6246.968765156327, 6254.687265636791, 6084.0, 6159.969200153971, 6232.38473965453, 6268.1851359323, 6297.874042519033, 4996.675216110941, 5807.82576522705, 6132.110843927695, 6135.509159267159, 6143.354947730602, 5011.624128190328, 6111.877762444815, 6116.66358350295, 6117.816465506042, 6129.601575897553, 4604.723716577016, 5736.082226843685, 5846.970765146147, 5914.053751399755, 5974.940250597612, 6092.8781424372155, 6118.676751226283, 6147.569670123048, 6151.108089326933, 6157.353477884937, 5773.740181691918, 5962.19510366095, 5965.791197308074, 5970.194023806905, 6013.098035294741], "value": 5675.693283207405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.50678034673405, 481.3333533056716, 512.108930460998, 531.4711861697609, 592.9614575052618, 29476.85261573692, 40681.38977915334, 41638.16723665525, 41680.791596042014, 44087.77956110223, 42872.356914646225, 43250.56749432505, 43338.783306083424, 43349.133017339635, 43448.78275608626, 8629.0, 9603.183729382994, 9791.412515249072, 9793.216542676604, 9799.118079372854, 8670.34972377071, 11569.13231507636, 11803.704907377341, 11805.937465628092, 12053.517859285617, 10839.83740243897, 11853.703657408547, 11928.343941083236, 11946.522139114424, 11962.222455540406, 8689.131086891324, 10989.835152472719, 11800.704982375424, 11809.940950295193, 11826.172167948203, 11344.773104537946, 11597.0, 11623.0, 11834.112441566916, 11839.94080029603, 41362.37956430656, 41685.58314416878, 41734.582654173675, 41748.0, 41790.58209417897, 3471.1669199392218, 3572.517710109128, 3579.2756749754612, 3765.002274397287, 4172.415861779354, 30437.320693526257, 30448.086557403312, 30612.846935765403, 30736.385272294654, 32172.678273217436, 3310.718051769758, 3346.247094403764, 3375.5274261603395, 3402.8638854445794, 3461.2558299965626, 502436.0, 505066.0, 506440.99999999633, 507518.0, 508649.0, 58188.0, 58417.5395615113, 58498.12252816211, 58517.70741146267, 58615.82768344609, 62186.0, 62894.68552657207, 62982.0, 63017.0, 63034.36965630285, 15653.37386504527, 15872.0, 15884.60288492797, 15900.045997240202, 15915.0, 11066.612668556561, 11409.25839820409, 12058.879411205775, 12297.385130743427, 12359.443825027894, 15746.0, 15928.840711592968, 15954.76067858983, 15966.840331596535, 15988.600284992735, 6952.652367381712, 6956.165260168812, 7077.787666370017, 7106.786796396013, 7141.143062832493, 903.742433406476, 905.4250550900226, 955.2310390136007, 992.374803873555, 1038.787048655024, 27840.443191136063, 30179.396412072074, 30320.84839575788, 31004.844975774977, 31044.0, 1007.6171054999054, 1126.8816774238587, 1133.9092872570175, 1148.1352733773583, 1223.5411720604773, 13678.726425471634, 15813.05121692702, 16015.0, 16070.357185712674, 16260.837391626168, 672232.0, 685305.5734721294, 687088.0, 688342.0, 688457.5577122082, 59151.0, 60642.696786515786, 60686.482837928525, 60861.0, 60999.695001524706, 1239.6343078791779, 1287.665207046156, 1397.7204559088223, 1398.8181536400407, 1421.133108803629, 27343.58984615272, 30276.69723302827, 31251.84374078115, 32868.50697239591, 33592.1601959953, 51999.480005200974, 52114.47885521248, 52368.47631523636, 52406.73796630993, 52513.0, 625326.0, 627946.0, 629230.0, 630365.0, 631342.6865731467, 26076.8696156518, 26458.603120952816, 26558.601620975314, 27550.0, 40369.59630403658, 47432.05135897332, 48230.27654585254, 48680.2697959538, 48735.0, 49206.75396622994, 35382.76160334376, 39185.608143918194, 40124.0, 40585.18829623332, 41236.38145427881, 36127.55489780379, 39309.01727456837, 39932.80201593957, 40026.79986600048, 40619.390709139974, 38370.0, 38841.41737873878, 39096.6090339093, 39280.80359598184, 39287.2142557153, 28292.575611365435, 28809.711902880703, 32914.0, 34318.050260483884, 34751.0, 25184.49631007406, 25323.49353012966, 25334.1133060342, 25587.36031599225, 25996.8700156498], "value": 20541.0181402741, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.11257525050263, 379.75695554844896, 388.30493416783975, 388.82502873706824, 413.6112054668611, 15703.0, 15795.210239488022, 20893.686594701092, 21007.68488472672, 21062.57874842506, 20501.97490125495, 20936.790632093675, 21320.36038918831, 21823.454413639665, 21827.236046738366, 5210.0, 5811.331696854852, 6019.759209631609, 6039.305479869805, 6053.303870054956, 5094.414142373619, 5976.73104710289, 6073.60521566099, 6077.665728384937, 6083.452489275965, 5696.344920334152, 5944.375840536746, 5972.970135149319, 5982.700864956757, 6009.699515024253, 5654.067078931971, 5949.702514874239, 5955.166276721263, 5959.731812068424, 5975.25309336333, 5149.970005998778, 5178.663386879841, 5180.274761533388, 5180.792768289264, 5186.507281808223, 27061.594076088677, 27080.59379109315, 27198.864005679847, 27530.76111574964, 27592.0, 2233.452804062999, 2281.6121259385777, 2320.5938960681815, 2323.512062466884, 2499.6750422445148, 15460.458883939009, 16315.428959986462, 16567.17164141788, 16903.901246418944, 16951.49145525636, 1849.3619701203063, 1909.8663093583455, 1916.425072478251, 1920.6254780317843, 1933.9323123690745, 106943.0, 108484.0, 108982.45508772484, 109973.45013274883, 110001.44999275032, 10451.216158788122, 10452.111570516592, 10829.0, 10839.295445795999, 10989.230753847154, 13280.203187808762, 13406.994475414383, 13407.994400420008, 13409.932950335186, 13456.990725695612, 6966.268541803038, 8296.0, 8513.31893448535, 8524.360672949553, 8568.400211985101, 8577.699780507652, 8588.398812083095, 8593.78515537104, 8718.651253949898, 8720.607572659246, 6630.138082049302, 6719.529632925648, 6737.730490780314, 6775.966120169368, 6843.8860318554935, 1591.299828075643, 1793.9551511212323, 1797.96616945257, 1834.0829585207396, 1848.3068849181557, 910.1581037540249, 912.452528482905, 947.3984020147118, 947.4268067819008, 990.2721499697716, 21202.893985529972, 21985.900704964686, 22304.107835686715, 22621.982010809552, 22952.540949180922, 967.4147140979746, 974.2692980264801, 990.2424645146468, 1025.0, 1042.921780866438, 10760.816310205872, 11390.088792896548, 12128.818067728991, 12200.755984880252, 12207.572734954412, 567393.0, 613557.0, 619871.0, 619967.0, 622396.0, 113567.43216283865, 114048.0, 114443.0, 114967.42516287365, 116919.2462113069, 926.77294062955, 937.779621788879, 957.5403806173076, 959.8032403357295, 994.04571611254, 19784.0, 20228.79771202328, 20229.0, 20240.69638955446, 20364.0, 50188.0, 52259.47740522546, 52438.737806310724, 55433.72283138559, 56080.0, 77646.83529747171, 78136.60931695305, 79784.60107699424, 80470.390592189, 82812.0, 8131.674733010614, 8160.5103693778565, 8284.83430331402, 8317.792055198668, 8568.785780355542, 12236.265824050586, 12428.316442595744, 12966.870331296566, 13295.002874784623, 13369.465221391036, 10514.748120582371, 11405.771884562428, 11669.0, 11670.94164529172, 11695.298282103102, 11292.830607540729, 11567.479463424335, 11584.710382240512, 11621.76756464883, 11852.288862668267, 11716.297022178696, 11746.647600571654, 11760.0, 11825.93566579012, 11854.229475084092, 8146.592670366339, 8286.70996515119, 8299.29455996247, 8448.028476725256, 8548.102449242988, 11256.155788316073, 11354.091672666333, 11520.82718759236, 11606.013488853205, 11879.0], "value": 9843.479320021474, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.0596847128186, 1488.3748825493292, 1545.6831349573336, 1785.8928464292144, 1957.3736404350614, 7932.444728868974, 8721.78195545111, 8975.326850486215, 8987.011428742833, 9103.499307538099, 8657.350698697606, 8917.68788092417, 8933.910660893389, 8939.910600893989, 8948.8657670135, 2094.0, 2170.924017659382, 2171.023039632164, 2324.395657129146, 2357.870317132557, 2157.7194964654573, 2180.6183917814396, 2198.351486311542, 2396.628522579003, 2398.0647547456506, 2325.3837733000682, 2332.5334933013382, 2334.4630734930893, 2334.7898689117974, 2341.309313752447, 2294.3117064880553, 2337.742848286687, 2348.7416384197722, 2350.8001819845163, 2351.0713268259115, 2084.6351888419617, 2285.405794493427, 2349.0, 2350.976490235093, 2357.39886328985, 8539.573021348908, 8606.44058136219, 8614.784630384227, 8621.13788621139, 8635.309175265964, 4301.440812694361, 5385.030694474993, 5388.272583201257, 5390.0, 5390.730463476811, 3482.320947415255, 3535.462073997819, 3573.8556865880523, 3638.2359704462096, 3647.74465787395, 918.527222875045, 1090.4057288777635, 1109.046220250584, 1112.6495154026536, 1116.564539829467, 12130.029597632169, 12131.939340303243, 12166.452509637, 12166.96580790634, 12188.81716774249, 4601.585857272795, 4708.905821883612, 4878.853634390974, 4880.170371036861, 4894.975525122351, 10152.137068349273, 10219.693409197736, 10232.334898231591, 10235.846462303072, 10252.794944101226, 5420.07858664028, 5423.403425623178, 5427.9185812212845, 5431.131019037014, 5441.884413695183, 4391.934120988188, 4905.509449055137, 4925.630577706686, 4962.20604703253, 4968.478309777422, 3749.8687545935763, 3952.091019065631, 4073.06319546506, 4107.774072426046, 4138.937915931264, 1270.917390369623, 1293.6701141208932, 1295.643697983062, 1303.758804621148, 1310.1287643717012, 862.779991102265, 931.4411353188033, 937.8499440089689, 965.2374624047064, 976.3702411944256, 5986.042233242747, 6029.909551356734, 6125.111858780541, 6259.561830671901, 6281.623102613858, 938.2259635800492, 944.428620684476, 947.5262368815593, 973.9318247722591, 991.6876184002016, 4047.291723948297, 4093.488313960755, 4093.7543747375253, 4179.958200418017, 4182.519010313792, 10805.351678899291, 10957.780844383067, 10965.95823396776, 11118.999290063934, 11140.721481963015, 11006.944965275123, 11023.779524409467, 11032.779344413068, 11047.72380690473, 11107.333559986426, 8021.639026243833, 8278.379121565908, 8405.453645513144, 8434.15658434164, 8445.071042185355, 6096.695165241809, 6119.541034422347, 6210.7515699371515, 6239.781607643892, 6248.219257511588, 10248.026437488426, 10306.381617102998, 10421.68734937953, 10441.947790261303, 10456.47717614101, 10070.446125462877, 10216.1827053837, 10484.790304194026, 10581.36511809294, 10598.364098154136, 7146.428285737233, 7421.925780742124, 7770.766876993699, 7930.841383172419, 7942.960285198537, 8313.625886835229, 8526.74419767408, 8566.828663426571, 8642.654293828176, 8650.870236946577, 7522.51103678281, 7573.129090154703, 7712.614369281626, 7821.6089195541135, 7855.450118491651, 7411.221821708643, 7522.511036782592, 7543.698252069856, 7696.692132314644, 7715.382769378324, 7149.82125446868, 7154.320339567733, 7225.855482890418, 7233.240509746401, 7241.348278654947, 5001.924971125509, 5116.283720279238, 5118.97440512795, 5192.636515443883, 5214.42641309448, 5179.533841954242, 5186.429492755869, 5350.0, 5381.865453363541, 5397.811076612456], "value": 4670.572365644323, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.4582981887872, 304.09834839700284, 311.5949265954259, 312.4298155865546, 316.66117254537653, 4010.7192496525254, 4650.837220697268, 4846.636502262327, 4857.514248575145, 4954.504549545044, 4268.335349213801, 4803.943132510842, 5036.330744705303, 5225.921611175835, 5234.7644356003975, 3995.740276882008, 4005.7997100144876, 4023.8189281482255, 4037.3742069979053, 4067.2678917794783, 3352.296017836245, 3916.8445308634023, 4039.9596004040022, 4045.1505183911413, 4072.2669919414493, 3877.6510114089724, 3972.860949866755, 3985.302572049894, 4030.3349947258666, 4059.4519739835187, 3451.032911240594, 3963.365861462152, 4012.3780813974026, 4014.3978403239457, 4017.2969730297373, 2846.402255526342, 3367.562216911786, 3538.2755278435716, 3546.733994950389, 3554.680078792895, 4129.584193246954, 4252.213340532012, 4758.00081982784, 5130.0, 5163.380394352665, 4562.0, 4565.977170114162, 4568.588827005586, 4576.885577860547, 4578.633709303248, 4539.931901021488, 4944.777485013183, 5508.807191748269, 5563.248961390242, 5610.0, 3178.936421271562, 3714.882516965331, 3809.923801523954, 3840.7503512271614, 3855.8264878080554, 4499.775011249457, 5038.748062596856, 5165.560927321182, 5397.622166448311, 5580.804671836466, 3351.128706536318, 3658.6707196352454, 3698.871844087579, 3801.2777572261184, 3837.347650899354, 3766.794625719743, 3885.6400259908805, 3889.4360317753767, 3890.513685789276, 3903.765774053566, 4007.779572123561, 4494.775261236925, 4526.569975852354, 4540.909181816345, 4553.726776393427, 5100.974495127501, 5201.323827902348, 5232.581393488512, 5492.780288788404, 5596.42564420363, 1123.7696272264166, 1318.1432069155126, 1322.2529270961875, 1322.5848342273866, 1326.907116501855, 714.8748968930416, 768.7808974442257, 801.4309840013656, 813.1054642682535, 816.8243827577099, 3666.4500324950936, 4270.668878274951, 4292.892677683083, 4320.632746216606, 4416.381706561084, 3774.471573979645, 4495.955040449554, 4662.622062747955, 4666.673332866667, 4716.316134160596, 4012.518497780227, 4128.566500517462, 4283.0, 4318.935215971763, 4412.462833870473, 2042.4571408596394, 2096.9475763106043, 2257.3340864445026, 2303.700518932528, 2304.4354133237466, 4156.363182215561, 4347.021920068023, 4508.368828364031, 4695.906081878344, 4785.59322457588, 4337.132573485315, 4427.605304329126, 4596.908061838744, 4746.5253474652945, 4850.975745121323, 3813.466114743938, 4442.579214005263, 4739.3838800955655, 4895.388076490439, 4929.359183306147, 3748.2128752962185, 4361.040571074359, 4454.331850222493, 4630.467496237976, 4640.141573808856, 4033.6571391432058, 4309.91380172401, 4341.630961368319, 4550.817967281272, 4734.857954261378, 4168.979155104205, 5306.575473961997, 5586.944130558642, 5693.971530142323, 5794.565407594363, 2917.693642167542, 3678.301122786662, 3709.795961222159, 3773.6226177507992, 3795.842268108143, 5207.0, 5665.0, 5684.857878553069, 5713.085906254979, 5731.0, 4879.95120048795, 5162.97418512905, 5553.0, 5564.276644036249, 5572.0, 5270.446603106619, 5538.224648549126, 5540.69526176048, 5603.38362780102, 5627.380988091389, 4534.750588717652, 5458.727063646881, 5472.233887255867, 5472.370677372153, 5475.0, 4849.636277279147, 5407.972960135174, 5564.721763911869, 5719.198452487453, 5754.683492407958, 4549.27211646135, 5558.861028474321, 5572.554195664256, 5585.441455854382, 5631.7047079172835], "value": 3782.8999229475276, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.9931000689992, 751.1474476469209, 772.7720322504861, 776.8446310737854, 850.8255807559449, 14926.776098358534, 16334.01995880248, 16341.18294085294, 16344.509664710078, 16356.591085222875, 14866.925665371686, 14907.776383354258, 15014.174220417872, 15033.849661503382, 15059.698806023873, 706.9399101076389, 728.2353528794771, 882.2897567458209, 902.2195800632438, 902.3097330542108, 909.2271569166189, 910.0626354854511, 911.3392790227066, 914.0128661046087, 916.2761418479427, 961.8749562556893, 973.6640858903702, 974.493263502978, 978.188103873783, 979.294907666479, 748.2816496163689, 932.47315266874, 952.1383148250844, 964.4599024546242, 966.5891995901749, 713.386487620651, 714.6498215874236, 716.9139703235645, 718.5760401363168, 718.881384571545, 12067.577634782829, 14983.250837458083, 15018.474353397687, 15052.849471505255, 15074.24628768568, 13776.862231377658, 13884.09753366038, 13958.325000999945, 14047.297635118306, 14333.641658958506, 12726.618201453972, 12734.235945843278, 12851.421686024152, 12889.0, 13096.672583185402, 4359.193549193409, 4414.359917811899, 4548.295014272793, 4584.88537786558, 4732.1482133216, 110358.0, 111244.33133503005, 111404.32893506603, 111637.44181279122, 112015.99999999919, 9172.541372931326, 11100.333979961228, 11202.551897923991, 11361.659150225507, 11421.086313094933, 19629.31297404584, 21351.893240533696, 22043.559128817335, 22102.0, 22103.226387076375, 7666.463347565728, 7798.415118866108, 7835.804104897423, 7836.568988705562, 8003.599820008976, 4217.831286748557, 4251.936220956688, 4367.148406060787, 4370.562943705668, 4499.392582001388, 3427.6572342765526, 3527.2945410917405, 3549.148204430918, 3549.716022718176, 3553.218291975761, 524.4912434938104, 636.2937139774846, 639.48840927258, 655.5574986883835, 655.8327626455224, 514.5703337712977, 529.7774934527507, 668.4518694670421, 679.7145199016425, 693.3621068616884, 9420.387674801115, 9598.376105553256, 9645.276604254708, 9647.469389183661, 9779.364341317934, 579.8434422705845, 661.9470442364599, 670.367758786781, 674.7301079568211, 686.5034027122329, 4443.2224360736745, 4674.117869926314, 4702.200625893606, 4709.929351059737, 4799.856004319876, 99695.50152249192, 122899.3855030737, 134295.32852335676, 155390.0, 167006.0, 138396.30801845927, 140789.32950821312, 142084.28957855146, 143276.0, 145319.0, 117343.0, 122707.0, 123024.38487807504, 123428.6799961997, 125761.74238257683, 8662.393632445668, 8703.608337624948, 8780.78048048804, 8817.735467935972, 9209.907900920905, 42611.0, 54713.72643136759, 64157.0, 65328.67335663291, 66546.66726666335, 78418.21581784337, 85337.0, 90502.0, 91377.0, 96458.0, 11746.882531174579, 11764.235324703866, 11820.290782553075, 11998.0, 12052.276863388224, 10430.843537346795, 13062.281574513492, 13470.326483675974, 13477.797833032317, 13520.0, 8252.593073905522, 12471.875281247072, 14217.928910355382, 14239.928800355932, 14597.416103355747, 14213.076150050214, 14231.573052808433, 14377.64055898611, 14526.709465810412, 14534.273286335852, 13876.791848122084, 14047.719045618825, 14158.221297828719, 14415.279236037946, 14507.564773056825, 10156.339837910513, 11316.434178290889, 11516.827247591125, 11528.538858445569, 11898.98858597029, 10123.292036808396, 11250.774984500427, 11332.0, 11588.88411115878, 11600.477978490819], "value": 7891.829576917006, "unit": "score"}], "name": "dromaeo_css", "value": 7368.326347141987, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 110.0, 116.0, 115.0, 112.0, 113.0, 112.0, 112.0, 118.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 136.0, 105.0, 191.0, 140.0, 135.0, 127.0, 107.0, 158.0, 105.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 204.0, 264.0, 230.0, 185.0, 183.0, 202.0, 256.0, 181.0, 195.0], "value": 208.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 76.0, 58.0, 110.0, 56.0, 57.0, 64.0, 65.0, 84.0, 81.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 94.0, 71.0, 71.0, 69.0, 95.0, 70.0, 68.0, 106.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 87.0, 85.0, 88.0, 89.0, 88.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 92.0, 94.0, 94.0, 101.0, 151.0, 125.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 85.0, 81.0, 83.0, 82.0, 101.0, 137.0, 85.0], "value": 90.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 55.0, 55.0, 54.0, 54.0, 53.0, 55.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 77.0, 77.0, 72.0, 70.0, 71.0, 72.0, 71.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 85.0, 85.0, 83.0, 81.0, 85.0, 85.0, 83.0, 80.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 145.0, 143.0, 135.0, 135.0, 134.0, 134.0, 133.0, 178.0, 136.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 172.0, 161.0, 161.0, 204.0, 162.0, 163.0, 163.0, 161.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 67.0, 78.0, 84.0, 85.0, 82.0, 84.0], "value": 74.5, "unit": "score"}], "name": "kraken", "value": 1485.1, "alertThreshold": 2.0}]} 05:08:40 INFO - Return code: 0 05:08:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:08:40 INFO - # TBPL SUCCESS # 05:08:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:08:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:08:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:08:40 INFO - 2016-07-08 05:08:40.394 screenresolution[3325:34186] starting screenresolution argv=screenresolution get 05:08:40 INFO - 2016-07-08 05:08:40.397 screenresolution[3325:34186] Display 0: 1600x1200x32@60 05:08:40 INFO - 2016-07-08 05:08:40.403 screenresolution[3326:34189] starting screenresolution argv=screenresolution list 05:08:40 INFO - Available Modes on Display 0 05:08:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:08:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:08:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:08:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:08:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:08:40 INFO - Intel Iris: 05:08:40 INFO - Chipset Model: Intel Iris 05:08:40 INFO - Type: GPU 05:08:40 INFO - Bus: Built-In 05:08:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:08:40 INFO - Vendor: Intel (0x8086) 05:08:40 INFO - Device ID: 0x0a2e 05:08:40 INFO - Revision ID: 0x0009 05:08:40 INFO - Displays: 05:08:40 INFO - Display: 05:08:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:08:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:08:40 INFO - Display Serial Number: 1600x1200 60 05:08:40 INFO - Main Display: Yes 05:08:40 INFO - Mirror: Off 05:08:40 INFO - Online: Yes 05:08:40 INFO - Rotation: Supported 05:08:40 INFO - Return code: 0 05:08:40 INFO - Running post-action listener: _resource_record_post_action 05:08:40 INFO - ##### 05:08:40 INFO - ##### Finished run-tests step (success) 05:08:40 INFO - ##### 05:08:40 INFO - Running post-run listener: _resource_record_post_run 05:08:40 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 253952; Write bytes: 706646528; Read time: 2; Write time: 1897 05:08:40 INFO - TinderboxPrint: CPU usage
53.6% 05:08:40 INFO - TinderboxPrint: I/O read bytes / time
253,952 / 2 05:08:40 INFO - TinderboxPrint: I/O write bytes / time
706,646,528 / 1,897 05:08:40 INFO - TinderboxPrint: CPU idle
2,249.5 (46.4%) 05:08:40 INFO - TinderboxPrint: CPU system
971.3 (20.0%) 05:08:40 INFO - TinderboxPrint: CPU user
1,624.6 (33.5%) 05:08:40 INFO - TinderboxPrint: Swap in / out
406,548,480 / 0 05:08:40 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398557696; Write bytes: 392980480; Read time: 29438; Write time: 789 05:08:40 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 253952; Write bytes: 296016384; Read time: 2; Write time: 1061 05:08:41 INFO - Running post-run listener: _upload_blobber_files 05:08:41 INFO - Blob upload gear active. 05:08:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:41 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 05:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:08:41 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:08:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:08:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:42 INFO - (blobuploader) - INFO - Done attempting. 05:08:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:08:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:43 INFO - (blobuploader) - INFO - Done attempting. 05:08:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:08:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:08:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:45 INFO - (blobuploader) - INFO - Done attempting. 05:08:45 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:45 INFO - Return code: 0 05:08:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3720d25cbeb3216457807acadac0958d14cc68c9f77f4b1abb4f74ca60c13a200bee5f8c0496b29dff7c88217a8e94054b9a639e43bec555db0681997aa5d7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e792ee7937a3de6215797ff49e2448d1885a21b6a5697345935fbe84d07ca2d7f77eb774a4dbd7c0ddc8e0db5c4de52f934fd7680edcde2a662766ecef601e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22fdc8100eb57f3d330ab09f2e11692ced2690b7cc5988ae899fb36b29ca707f76d7cafc49ed8ae1dfc01beda14f2f463f877dde93dd42b1b7a16039e9709ed3"} 05:08:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:45 INFO - Contents: 05:08:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3720d25cbeb3216457807acadac0958d14cc68c9f77f4b1abb4f74ca60c13a200bee5f8c0496b29dff7c88217a8e94054b9a639e43bec555db0681997aa5d7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e792ee7937a3de6215797ff49e2448d1885a21b6a5697345935fbe84d07ca2d7f77eb774a4dbd7c0ddc8e0db5c4de52f934fd7680edcde2a662766ecef601e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22fdc8100eb57f3d330ab09f2e11692ced2690b7cc5988ae899fb36b29ca707f76d7cafc49ed8ae1dfc01beda14f2f463f877dde93dd42b1b7a16039e9709ed3"} 05:08:45 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:45 INFO - Copying logs to upload dir... 05:08:45 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.322323 ========= master_lag: 1.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-08 05:08:46.526058) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 05:08:46.529122) ========= 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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3720d25cbeb3216457807acadac0958d14cc68c9f77f4b1abb4f74ca60c13a200bee5f8c0496b29dff7c88217a8e94054b9a639e43bec555db0681997aa5d7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e792ee7937a3de6215797ff49e2448d1885a21b6a5697345935fbe84d07ca2d7f77eb774a4dbd7c0ddc8e0db5c4de52f934fd7680edcde2a662766ecef601e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22fdc8100eb57f3d330ab09f2e11692ced2690b7cc5988ae899fb36b29ca707f76d7cafc49ed8ae1dfc01beda14f2f463f877dde93dd42b1b7a16039e9709ed3"} build_url:https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/QeQoMqswQIm2FAoQhQ462Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3720d25cbeb3216457807acadac0958d14cc68c9f77f4b1abb4f74ca60c13a200bee5f8c0496b29dff7c88217a8e94054b9a639e43bec555db0681997aa5d7e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e792ee7937a3de6215797ff49e2448d1885a21b6a5697345935fbe84d07ca2d7f77eb774a4dbd7c0ddc8e0db5c4de52f934fd7680edcde2a662766ecef601e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22fdc8100eb57f3d330ab09f2e11692ced2690b7cc5988ae899fb36b29ca707f76d7cafc49ed8ae1dfc01beda14f2f463f877dde93dd42b1b7a16039e9709ed3"}' ========= master_lag: 0.44 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 05:08:46.984715) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:08:46.985080) ========= 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.Sx8uv6ECVX/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.9f9zEmql9u/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005231 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:08:47.011873) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 05:08:47.012224) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 05:08:47.012595) ========= ========= Total master_lag: 1.59 =========