builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0145 starttime: 1467980347.19 results: success (0) buildid: 20160708025926 builduid: 30cfd91d14ac4f9e9d33b653b9495404 revision: 45682df2d2d45e5a8385fd842579e661a4b60bc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.186756) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.187204) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.187476) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.226009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.226366) ========= 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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 165M=0s 2016-07-08 05:19:07 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.329499) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.329823) ========= 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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.031926 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:07.391349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 05:19:07.391677) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45682df2d2d45e5a8385fd842579e661a4b60bc5 --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 45682df2d2d45e5a8385fd842579e661a4b60bc5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6jgXNYhPzh/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.nK5xqMqTAM/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:07,467 truncating revision to first 12 chars 2016-07-08 05:19:07,467 Setting DEBUG logging. 2016-07-08 05:19:07,467 attempt 1/10 2016-07-08 05:19:07,467 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45682df2d2d4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 05:19:08,828 unpacking tar archive at: mozilla-central-45682df2d2d4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.639084 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 05:19:09.052818) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:09.053199) ========= script_repo_revision: 45682df2d2d45e5a8385fd842579e661a4b60bc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:09.053637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:09.053933) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 05:19:09.071830) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-08 05:19:09.072213) ========= /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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:09 INFO - MultiFileLogger online at 20160708 05:19:09 in /builds/slave/test 05:19:09 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 05:19:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:19:09 INFO - {'append_to_log': False, 05:19:09 INFO - 'base_work_dir': '/builds/slave/test', 05:19:09 INFO - 'blob_upload_branch': 'Firefox', 05:19:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:19:09 INFO - 'branch': 'Firefox', 05:19:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:19:09 INFO - 'config_files': ('talos/mac_config.py',), 05:19:09 INFO - 'default_actions': ('clobber', 05:19:09 INFO - 'read-buildbot-config', 05:19:09 INFO - 'download-and-extract', 05:19:09 INFO - 'populate-webroot', 05:19:09 INFO - 'create-virtualenv', 05:19:09 INFO - 'install', 05:19:09 INFO - 'run-tests'), 05:19:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:19:09 INFO - 'download_minidump_stackwalk': True, 05:19:09 INFO - 'download_symbols': 'ondemand', 05:19:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:19:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:19:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:19:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:19:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:19:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:19:09 INFO - 'installer_path': 'installer.exe', 05:19:09 INFO - 'log_level': 'info', 05:19:09 INFO - 'log_name': 'talos', 05:19:09 INFO - 'log_to_console': True, 05:19:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:19:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:19:09 INFO - 'opt_config_files': (), 05:19:09 INFO - 'pip_index': False, 05:19:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:09 INFO - 'cmd': ('bash', 05:19:09 INFO - '-c', 05:19:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:09 INFO - 'enabled': True, 05:19:09 INFO - 'halt_on_failure': False, 05:19:09 INFO - 'name': 'check_screen_resolution'},), 05:19:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:09 INFO - 'cmd': ('bash', 05:19:09 INFO - '-c', 05:19:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:09 INFO - 'enabled': True, 05:19:09 INFO - 'halt_on_failure': False, 05:19:09 INFO - 'name': 'check_screen_resolution'},), 05:19:09 INFO - 'run_cmd_checks_enabled': True, 05:19:09 INFO - 'sps_profile': False, 05:19:09 INFO - 'sps_profile_interval': 0, 05:19:09 INFO - 'suite': 'dromaeojs', 05:19:09 INFO - 'system_bits': '32', 05:19:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:19:09 INFO - 'title': 't-yosemite-r7-0145', 05:19:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:19:09 INFO - 'use_talos_json': True, 05:19:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:19:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:19:09 INFO - 'work_dir': 'build'} 05:19:09 INFO - ##### 05:19:09 INFO - ##### Running clobber step. 05:19:09 INFO - ##### 05:19:09 INFO - Running pre-action listener: _resource_record_pre_action 05:19:09 INFO - Running main action method: clobber 05:19:09 INFO - rmtree: /builds/slave/test/build 05:19:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:19:11 INFO - Running post-action listener: _resource_record_post_action 05:19:11 INFO - ##### 05:19:11 INFO - ##### Finished clobber step (success) 05:19:11 INFO - ##### 05:19:11 INFO - ##### 05:19:11 INFO - ##### Running read-buildbot-config step. 05:19:11 INFO - ##### 05:19:11 INFO - Running pre-action listener: _resource_record_pre_action 05:19:11 INFO - Running main action method: read_buildbot_config 05:19:11 INFO - Using buildbot properties: 05:19:11 INFO - { 05:19:11 INFO - "project": "", 05:19:11 INFO - "product": "firefox", 05:19:11 INFO - "script_repo_revision": "production", 05:19:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:19:11 INFO - "repository": "", 05:19:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:19:11 INFO - "buildid": "20160708025926", 05:19:11 INFO - "pgo_build": "False", 05:19:11 INFO - "basedir": "/builds/slave/test", 05:19:11 INFO - "buildnumber": 40, 05:19:11 INFO - "slavename": "t-yosemite-r7-0145", 05:19:11 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:19:11 INFO - "platform": "yosemite_r7", 05:19:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:19:11 INFO - "branch": "mozilla-central", 05:19:11 INFO - "repo_path": "mozilla-central", 05:19:11 INFO - "revision": "45682df2d2d45e5a8385fd842579e661a4b60bc5", 05:19:11 INFO - "stage_platform": "macosx64", 05:19:11 INFO - "builduid": "30cfd91d14ac4f9e9d33b653b9495404", 05:19:11 INFO - "slavebuilddir": "test" 05:19:11 INFO - } 05:19:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:19:11 INFO - Running post-action listener: _resource_record_post_action 05:19:11 INFO - ##### 05:19:11 INFO - ##### Finished read-buildbot-config step (success) 05:19:11 INFO - ##### 05:19:11 INFO - ##### 05:19:11 INFO - ##### Running download-and-extract step. 05:19:11 INFO - ##### 05:19:11 INFO - Running pre-action listener: _resource_record_pre_action 05:19:11 INFO - Running main action method: download_and_extract 05:19:11 INFO - mkdir: /builds/slave/test/build/tests 05:19:11 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')]} 05:19:11 INFO - https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:19:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:19:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:19:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:12 INFO - Downloaded 1199 bytes. 05:19:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:19:12 INFO - Using the following test package requirements: 05:19:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:19:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:19:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:19:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'jsshell-mac.zip'], 05:19:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:19:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:19:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:19:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:19:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:19:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:19:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:19:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:19: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')]} 05:19:12 INFO - https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:19:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:19:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:19:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:13 INFO - Downloaded 18806406 bytes. 05:19:13 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'] 05:19:13 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 05:19:16 INFO - Return code: 0 05:19:16 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 05:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:16 INFO - https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:19:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:19:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:19:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:17 INFO - Downloaded 18806406 bytes. 05:19:17 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'] 05:19:17 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 05:19:21 INFO - Return code: 0 05:19: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')]} 05:19:21 INFO - https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:19:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:19:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:19:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:22 INFO - Downloaded 14403333 bytes. 05:19:22 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'] 05:19:22 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 05:19:22 INFO - Return code: 0 05:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:22 INFO - https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:19:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:19:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:19:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:19:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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 05:19:26 INFO - Downloaded 112481429 bytes. 05:19:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:19:26 INFO - mkdir: /builds/slave/test/properties 05:19:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:19:26 INFO - Writing to file /builds/slave/test/properties/build_url 05:19:26 INFO - Contents: 05:19:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:19:26 INFO - Running post-action listener: _resource_record_post_action 05:19:26 INFO - Running post-action listener: set_extra_try_arguments 05:19:26 INFO - ##### 05:19:26 INFO - ##### Finished download-and-extract step (success) 05:19:26 INFO - ##### 05:19:26 INFO - ##### 05:19:26 INFO - ##### Running populate-webroot step. 05:19:26 INFO - ##### 05:19:26 INFO - Running pre-action listener: _resource_record_pre_action 05:19:26 INFO - Running main action method: populate_webroot 05:19:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:19:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:19:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:19:26 INFO - '--tppagecycles', 05:19:26 INFO - '7'], 05:19:26 INFO - 'tests': ['tsvgm']}}, 05:19:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:19:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:19:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:19:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:19:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['damp', 'tps']}, 05:19:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'tests': ['damp', 'tps']}, 05:19:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['dromaeo_dom']}, 05:19:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:19:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['basic_compositor_video']}, 05:19:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:19:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['a11yr', 05:19:26 INFO - 'ts_paint', 05:19:26 INFO - 'tpaint', 05:19:26 INFO - 'sessionrestore', 05:19:26 INFO - 'sessionrestore_no_auto_restore', 05:19:26 INFO - 'tabpaint']}, 05:19:26 INFO - 'other-e10s': {'tests': ['a11yr', 05:19:26 INFO - 'ts_paint', 05:19:26 INFO - 'tpaint', 05:19:26 INFO - 'sessionrestore', 05:19:26 INFO - 'sessionrestore_no_auto_restore', 05:19:26 INFO - 'tabpaint']}, 05:19:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:19:26 INFO - 'ts_paint', 05:19:26 INFO - 'tpaint', 05:19:26 INFO - 'sessionrestore', 05:19:26 INFO - 'sessionrestore_no_auto_restore', 05:19:26 INFO - 'tabpaint']}, 05:19:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:19:26 INFO - 'ts_paint', 05:19:26 INFO - 'tpaint', 05:19:26 INFO - 'sessionrestore', 05:19:26 INFO - 'sessionrestore_no_auto_restore', 05:19:26 INFO - 'tabpaint']}, 05:19:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['a11yr', 05:19:26 INFO - 'ts_paint', 05:19:26 INFO - 'tpaint', 05:19:26 INFO - 'sessionrestore', 05:19:26 INFO - 'sessionrestore_no_auto_restore', 05:19:26 INFO - 'tabpaint']}, 05:19:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['a11yr', 05:19:26 INFO - 'ts_paint', 05:19:26 INFO - 'tpaint', 05:19:26 INFO - 'sessionrestore', 05:19:26 INFO - 'sessionrestore_no_auto_restore', 05:19:26 INFO - 'tabpaint']}, 05:19:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['tsvgx', 05:19:26 INFO - 'tsvgr_opacity', 05:19:26 INFO - 'tart', 05:19:26 INFO - 'tscrollx', 05:19:26 INFO - 'cart']}, 05:19:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:19:26 INFO - 'tsvgr_opacity', 05:19:26 INFO - 'tart', 05:19:26 INFO - 'tscrollx', 05:19:26 INFO - 'cart']}, 05:19:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'talos_options': ['--disable-e10s'], 05:19:26 INFO - 'tests': ['tp5o']}, 05:19:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'tests': ['tp5o']}, 05:19:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'talos_options': ['--disable-e10s', 05:19:26 INFO - '--xperf_path', 05:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:26 INFO - 'tests': ['tp5n']}, 05:19:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:26 INFO - 'talos_options': ['--xperf_path', 05:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:26 INFO - 'tests': ['tp5n']}}, 05:19:26 INFO - 'talos.zip': {'path': '', 05:19:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:19:26 INFO - Running post-action listener: _resource_record_post_action 05:19:26 INFO - ##### 05:19:26 INFO - ##### Finished populate-webroot step (success) 05:19:26 INFO - ##### 05:19:26 INFO - ##### 05:19:26 INFO - ##### Running create-virtualenv step. 05:19:26 INFO - ##### 05:19:26 INFO - Running pre-action listener: _resource_record_pre_action 05:19:26 INFO - Running main action method: create_virtualenv 05:19:26 INFO - Creating virtualenv /builds/slave/test/build/venv 05:19:26 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 05:19:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:19:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:19:26 INFO - Using real prefix '/tools/python27' 05:19:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:19:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:19:30 INFO - Installing pip.................done. 05:19:30 INFO - Return code: 0 05:19:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:30 INFO - 'HOME': '/Users/cltbld', 05:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:30 INFO - 'LOGNAME': 'cltbld', 05:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:30 INFO - 'MOZ_NO_REMOTE': '1', 05:19:30 INFO - 'NO_EM_RESTART': '1', 05:19:30 INFO - 'PAGER': '/bin/cat', 05:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:30 INFO - 'PWD': '/builds/slave/test', 05:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:30 INFO - 'SHELL': '/bin/bash', 05:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:30 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:30 INFO - 'USER': 'cltbld', 05:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:30 INFO - 'XPC_FLAGS': '0x0', 05:19:30 INFO - 'XPC_SERVICE_NAME': '0', 05:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:30 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 05:19:30 INFO - Cleaning up... 05:19:30 INFO - Return code: 0 05:19:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:30 INFO - 'HOME': '/Users/cltbld', 05:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:30 INFO - 'LOGNAME': 'cltbld', 05:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:30 INFO - 'MOZ_NO_REMOTE': '1', 05:19:30 INFO - 'NO_EM_RESTART': '1', 05:19:30 INFO - 'PAGER': '/bin/cat', 05:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:30 INFO - 'PWD': '/builds/slave/test', 05:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:30 INFO - 'SHELL': '/bin/bash', 05:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:30 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:30 INFO - 'USER': 'cltbld', 05:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:30 INFO - 'XPC_FLAGS': '0x0', 05:19:30 INFO - 'XPC_SERVICE_NAME': '0', 05:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:31 INFO - Downloading/unpacking psutil>=3.1.1 05:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:19:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:34 INFO - Installing collected packages: psutil 05:19:34 INFO - Running setup.py install for psutil 05:19:34 INFO - building 'psutil._psutil_osx' extension 05:19:34 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 05:19:34 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 05:19:34 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 05:19:34 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 05:19:35 INFO - building 'psutil._psutil_posix' extension 05:19:35 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 05:19:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:19:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:19:35 INFO - ^ 05:19:35 INFO - 1 warning generated. 05:19:35 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 05:19:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:35 INFO - Successfully installed psutil 05:19:35 INFO - Cleaning up... 05:19:35 INFO - Return code: 0 05:19:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:19:35 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')]} 05:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:35 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')]} 05:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:35 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 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:35 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 05:19:35 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 05:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:35 INFO - 'HOME': '/Users/cltbld', 05:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:35 INFO - 'LOGNAME': 'cltbld', 05:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:35 INFO - 'MOZ_NO_REMOTE': '1', 05:19:35 INFO - 'NO_EM_RESTART': '1', 05:19:35 INFO - 'PAGER': '/bin/cat', 05:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:35 INFO - 'PWD': '/builds/slave/test', 05:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:35 INFO - 'SHELL': '/bin/bash', 05:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:35 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:35 INFO - 'USER': 'cltbld', 05:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:35 INFO - 'XPC_FLAGS': '0x0', 05:19:35 INFO - 'XPC_SERVICE_NAME': '0', 05:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:19:35 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 05:19:35 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 05:19:35 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 05:19:35 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 05:19:35 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 05:19:35 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 05:19:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:19:38 INFO - Installing collected packages: mozsystemmonitor 05:19:38 INFO - Running setup.py install for mozsystemmonitor 05:19:38 INFO - Successfully installed mozsystemmonitor 05:19:38 INFO - Cleaning up... 05:19:38 INFO - Return code: 0 05:19:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:38 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 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:38 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 05:19:38 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 05:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:38 INFO - 'HOME': '/Users/cltbld', 05:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:38 INFO - 'LOGNAME': 'cltbld', 05:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:38 INFO - 'MOZ_NO_REMOTE': '1', 05:19:38 INFO - 'NO_EM_RESTART': '1', 05:19:38 INFO - 'PAGER': '/bin/cat', 05:19:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:38 INFO - 'PWD': '/builds/slave/test', 05:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:38 INFO - 'SHELL': '/bin/bash', 05:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:38 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:38 INFO - 'USER': 'cltbld', 05:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:38 INFO - 'XPC_FLAGS': '0x0', 05:19:38 INFO - 'XPC_SERVICE_NAME': '0', 05:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:38 INFO - Downloading/unpacking jsonschema==2.5.1 05:19:38 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 05:19:38 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 05:19:38 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 05:19:38 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 05:19:38 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 05:19:38 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 05:19:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:19:41 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 05:19:41 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 05:19:41 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 05:19:41 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 05:19:41 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 05:19:41 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 05:19:41 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:41 INFO - warning: no files found matching '*.txt' 05:19:41 INFO - no previously-included directories found matching 'build' 05:19:41 INFO - no previously-included directories found matching 'dist' 05:19:41 INFO - no previously-included directories found matching '.git*' 05:19:41 INFO - Installing collected packages: jsonschema, functools32 05:19:41 INFO - Running setup.py install for functools32 05:19:41 INFO - warning: no files found matching '*.txt' 05:19:41 INFO - no previously-included directories found matching 'build' 05:19:41 INFO - no previously-included directories found matching 'dist' 05:19:41 INFO - no previously-included directories found matching '.git*' 05:19:41 INFO - Successfully installed jsonschema functools32 05:19:41 INFO - Cleaning up... 05:19:41 INFO - Return code: 0 05:19:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:19:41 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')]} 05:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:41 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')]} 05:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:41 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 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:41 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 05:19:41 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 05:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:41 INFO - 'HOME': '/Users/cltbld', 05:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:41 INFO - 'LOGNAME': 'cltbld', 05:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:41 INFO - 'MOZ_NO_REMOTE': '1', 05:19:41 INFO - 'NO_EM_RESTART': '1', 05:19:41 INFO - 'PAGER': '/bin/cat', 05:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:41 INFO - 'PWD': '/builds/slave/test', 05:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:41 INFO - 'SHELL': '/bin/bash', 05:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:41 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:41 INFO - 'USER': 'cltbld', 05:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:41 INFO - 'XPC_FLAGS': '0x0', 05:19:41 INFO - 'XPC_SERVICE_NAME': '0', 05:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:42 INFO - Downloading/unpacking blobuploader==1.2.4 05:19:42 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 05:19:42 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 05:19:42 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 05:19:42 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 05:19:42 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 05:19:42 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 05:19:44 INFO - Downloading blobuploader-1.2.4.tar.gz 05:19:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:19:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:19:44 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 05:19:44 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 05:19:44 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 05:19:44 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 05:19:44 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 05:19:44 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 05:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19:45 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 05:19:45 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 05:19:45 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 05:19:45 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 05:19:45 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 05:19:45 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 05:19:45 INFO - Downloading docopt-0.6.1.tar.gz 05:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:45 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:45 INFO - Running setup.py install for blobuploader 05:19:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:46 INFO - Running setup.py install for requests 05:19:46 INFO - Running setup.py install for docopt 05:19:46 INFO - Successfully installed blobuploader requests docopt 05:19:46 INFO - Cleaning up... 05:19:46 INFO - Return code: 0 05:19:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:46 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 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:46 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 05:19:46 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 05:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:46 INFO - 'HOME': '/Users/cltbld', 05:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:46 INFO - 'LOGNAME': 'cltbld', 05:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:46 INFO - 'MOZ_NO_REMOTE': '1', 05:19:46 INFO - 'NO_EM_RESTART': '1', 05:19:46 INFO - 'PAGER': '/bin/cat', 05:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:46 INFO - 'PWD': '/builds/slave/test', 05:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:46 INFO - 'SHELL': '/bin/bash', 05:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:46 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:46 INFO - 'USER': 'cltbld', 05:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:46 INFO - 'XPC_FLAGS': '0x0', 05:19:46 INFO - 'XPC_SERVICE_NAME': '0', 05:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-X99KpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-6V2egL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-czCRMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-dzlwe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-HRPsrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-x4ryJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-SOYYZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-mPxncX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-rTeR6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-YzihU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-YQb61_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-sl7wla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-z0piT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-p0eUhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-dQmVPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-87ckFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-0pmvJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:48 INFO - Running setup.py install for manifestparser 05:19:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:48 INFO - Running setup.py install for mozcrash 05:19:49 INFO - Running setup.py install for mozdebug 05:19:49 INFO - Running setup.py install for mozdevice 05:19:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:49 INFO - Running setup.py install for mozfile 05:19:49 INFO - Running setup.py install for mozhttpd 05:19:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:49 INFO - Running setup.py install for mozinfo 05:19:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:49 INFO - Running setup.py install for mozInstall 05:19:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:50 INFO - Running setup.py install for mozleak 05:19:50 INFO - Running setup.py install for mozlog 05:19:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:50 INFO - Running setup.py install for moznetwork 05:19:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:50 INFO - Running setup.py install for mozprocess 05:19:50 INFO - Running setup.py install for mozprofile 05:19:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:50 INFO - Running setup.py install for mozrunner 05:19:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:50 INFO - Running setup.py install for mozscreenshot 05:19:51 INFO - Running setup.py install for moztest 05:19:51 INFO - Running setup.py install for mozversion 05:19:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:51 INFO - Cleaning up... 05:19:51 INFO - Return code: 0 05:19:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:51 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 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:51 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 05:19:51 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 05:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:51 INFO - 'HOME': '/Users/cltbld', 05:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:51 INFO - 'LOGNAME': 'cltbld', 05:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:51 INFO - 'MOZ_NO_REMOTE': '1', 05:19:51 INFO - 'NO_EM_RESTART': '1', 05:19:51 INFO - 'PAGER': '/bin/cat', 05:19:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:51 INFO - 'PWD': '/builds/slave/test', 05:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:51 INFO - 'SHELL': '/bin/bash', 05:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:51 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:51 INFO - 'USER': 'cltbld', 05:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:51 INFO - 'XPC_FLAGS': '0x0', 05:19:51 INFO - 'XPC_SERVICE_NAME': '0', 05:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:51 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Gmvth1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:51 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)) 05:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:51 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-qRCsdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:51 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)) 05:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:51 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Lidv55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-yGhRZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-gBNV82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-CRrlGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-l9He9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-fimGE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Y16BBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-NiOCUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-reVjlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-YC5xWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:52 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)) 05:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:52 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-KR5aDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:53 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)) 05:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:53 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-jSw7q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:53 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)) 05:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:53 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-ex2X_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:53 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)) 05:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:53 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-MOp7eT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:53 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)) 05:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:53 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-HHSPdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:53 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)) 05:19:53 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)) 05:19:53 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)) 05:19:53 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)) 05:19:53 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)) 05:19:53 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)) 05:19:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:53 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 05:19:53 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 05:19:53 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 05:19:53 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 05:19:53 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 05:19:53 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 05:19:55 INFO - Downloading blessings-1.6.tar.gz 05:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:56 INFO - Installing collected packages: blessings 05:19:56 INFO - Running setup.py install for blessings 05:19:56 INFO - Successfully installed blessings 05:19:56 INFO - Cleaning up... 05:19:56 INFO - Return code: 0 05:19:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:56 INFO - Reading from file tmpfile_stdout 05:19:56 INFO - Current package versions: 05:19:56 INFO - blessings == 1.6 05:19:56 INFO - blobuploader == 1.2.4 05:19:56 INFO - docopt == 0.6.1 05:19:56 INFO - functools32 == 3.2.3-2 05:19:56 INFO - jsonschema == 2.5.1 05:19:56 INFO - manifestparser == 1.1 05:19:56 INFO - mozInstall == 1.12 05:19:56 INFO - mozcrash == 0.17 05:19:56 INFO - mozdebug == 0.1 05:19:56 INFO - mozdevice == 0.48 05:19:56 INFO - mozfile == 1.2 05:19:56 INFO - mozhttpd == 0.7 05:19:56 INFO - mozinfo == 0.9 05:19:56 INFO - mozleak == 0.1 05:19:56 INFO - mozlog == 3.2 05:19:56 INFO - moznetwork == 0.27 05:19:56 INFO - mozprocess == 0.23 05:19:56 INFO - mozprofile == 0.28 05:19:56 INFO - mozrunner == 6.12 05:19:56 INFO - mozscreenshot == 0.1 05:19:56 INFO - mozsystemmonitor == 0.3 05:19:56 INFO - moztest == 0.7 05:19:56 INFO - mozversion == 1.4 05:19:56 INFO - psutil == 3.1.1 05:19:56 INFO - requests == 1.2.3 05:19:56 INFO - wsgiref == 0.1.2 05:19:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:56 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 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:56 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 05:19:56 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 05:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:56 INFO - 'HOME': '/Users/cltbld', 05:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:56 INFO - 'LOGNAME': 'cltbld', 05:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:56 INFO - 'MOZ_NO_REMOTE': '1', 05:19:56 INFO - 'NO_EM_RESTART': '1', 05:19:56 INFO - 'PAGER': '/bin/cat', 05:19:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:56 INFO - 'PWD': '/builds/slave/test', 05:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:56 INFO - 'SHELL': '/bin/bash', 05:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:56 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:56 INFO - 'USER': 'cltbld', 05:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:56 INFO - 'XPC_FLAGS': '0x0', 05:19:56 INFO - 'XPC_SERVICE_NAME': '0', 05:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 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)) 05:19:56 INFO - Cleaning up... 05:19:56 INFO - Return code: 0 05:19:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba8c810c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, '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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 05:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:56 INFO - 'HOME': '/Users/cltbld', 05:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:56 INFO - 'LOGNAME': 'cltbld', 05:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:56 INFO - 'MOZ_NO_REMOTE': '1', 05:19:56 INFO - 'NO_EM_RESTART': '1', 05:19:56 INFO - 'PAGER': '/bin/cat', 05:19:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:56 INFO - 'PWD': '/builds/slave/test', 05:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:56 INFO - 'SHELL': '/bin/bash', 05:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:19:56 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:56 INFO - 'USER': 'cltbld', 05:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:56 INFO - 'XPC_FLAGS': '0x0', 05:19:56 INFO - 'XPC_SERVICE_NAME': '0', 05:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:19:57 INFO - Cleaning up... 05:19:57 INFO - Return code: 0 05:19:57 INFO - Running post-action listener: _resource_record_post_action 05:19:57 INFO - Running post-action listener: _start_resource_monitoring 05:19:57 INFO - Starting resource monitoring. 05:19:57 INFO - ##### 05:19:57 INFO - ##### Finished create-virtualenv step (success) 05:19:57 INFO - ##### 05:19:57 INFO - ##### 05:19:57 INFO - ##### Running install step. 05:19:57 INFO - ##### 05:19:57 INFO - Running pre-action listener: _resource_record_pre_action 05:19:57 INFO - Running main action method: install 05:19:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:57 INFO - Reading from file tmpfile_stdout 05:19:57 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:57 INFO - Reading from file tmpfile_stdout 05:19:57 INFO - Output received: 05:19:57 INFO - Usage: mozinstall [options] installer 05:19:57 INFO - Options: 05:19:57 INFO - -h, --help show this help message and exit 05:19:57 INFO - -d DEST, --destination=DEST 05:19:57 INFO - Directory to install application into. [default: 05:19:57 INFO - "/builds/slave/test"] 05:19:57 INFO - --app=APP Application being installed. [default: firefox] 05:19:57 INFO - mkdir: /builds/slave/test/build/application 05:19:57 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'] 05:19:57 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 05:20:31 INFO - Reading from file tmpfile_stdout 05:20:31 INFO - Output received: 05:20:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:20:31 INFO - Running post-action listener: _resource_record_post_action 05:20:31 INFO - ##### 05:20:31 INFO - ##### Finished install step (success) 05:20:31 INFO - ##### 05:20:31 INFO - ##### 05:20:31 INFO - ##### Running run-tests step. 05:20:31 INFO - ##### 05:20:31 INFO - Running pre-action listener: _resource_record_pre_action 05:20:31 INFO - Running main action method: run_tests 05:20:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:31 INFO - 2016-07-08 05:20:31.866 screenresolution[1645:7970] starting screenresolution argv=screenresolution get 05:20:31 INFO - 2016-07-08 05:20:31.869 screenresolution[1645:7970] Display 0: 1600x1200x32@60 05:20:31 INFO - 2016-07-08 05:20:31.874 screenresolution[1646:7972] starting screenresolution argv=screenresolution list 05:20:31 INFO - Available Modes on Display 0 05:20:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:32 INFO - Intel Iris: 05:20:32 INFO - Chipset Model: Intel Iris 05:20:32 INFO - Type: GPU 05:20:32 INFO - Bus: Built-In 05:20:32 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:32 INFO - Vendor: Intel (0x8086) 05:20:32 INFO - Device ID: 0x0a2e 05:20:32 INFO - Revision ID: 0x0009 05:20:32 INFO - Displays: 05:20:32 INFO - Display: 05:20:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:32 INFO - Display Serial Number: 1600x1200 60 05:20:32 INFO - Main Display: Yes 05:20:32 INFO - Mirror: Off 05:20:32 INFO - Online: Yes 05:20:32 INFO - Rotation: Supported 05:20:32 INFO - Return code: 0 05:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:32 INFO - Python 2.7.3 05:20:32 INFO - Return code: 0 05:20:32 INFO - grabbing minidump binary from tooltool 05:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:20:32 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 0x10ec19d30>, '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 0x10e97edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec11b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:32 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 05:20:32 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 05:20:32 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 05:20:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:32 INFO - Return code: 0 05:20:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:20:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:20:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:32 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/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 05:20:32 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/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0145 --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 05:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jgXNYhPzh/Render', 05:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:32 INFO - 'HOME': '/Users/cltbld', 05:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:32 INFO - 'LOGNAME': 'cltbld', 05:20:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:32 INFO - 'MOZ_NO_REMOTE': '1', 05:20:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:32 INFO - 'NO_EM_RESTART': '1', 05:20:32 INFO - 'PAGER': '/bin/cat', 05:20:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:32 INFO - 'PWD': '/builds/slave/test', 05:20:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:32 INFO - 'SHELL': '/bin/bash', 05:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nK5xqMqTAM/Listeners', 05:20:32 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 05:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:32 INFO - 'USER': 'cltbld', 05:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:32 INFO - 'XPC_FLAGS': '0x0', 05:20:32 INFO - 'XPC_SERVICE_NAME': '0', 05:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:32 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/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 05:20:32 INFO - mozversion application_buildid: 20160708025926 05:20:32 INFO - mozversion application_changeset: 45682df2d2d45e5a8385fd842579e661a4b60bc5 05:20:32 INFO - mozversion application_display_name: Nightly 05:20:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:32 INFO - mozversion application_name: Firefox 05:20:32 INFO - mozversion application_remotingname: firefox 05:20:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:20:32 INFO - mozversion application_vendor: Mozilla 05:20:32 INFO - mozversion application_version: 50.0a1 05:20:32 INFO - mozversion platform_buildid: 20160708025926 05:20:32 INFO - mozversion platform_changeset: 45682df2d2d45e5a8385fd842579e661a4b60bc5 05:20:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:20:32 INFO - mozversion platform_version: 50.0a1 05:20:32 INFO - using testdate: 1467980432 05:20:32 INFO - actual date: 1467980432 05:20:32 INFO - starting webserver on 'localhost:49279' 05:20:32 INFO - SUITE-START | Running 2 tests 05:20:32 INFO - TEST-START | dromaeo_css 05:20:32 INFO - operating with platform_type : mac_ 05:20:32 INFO - Initialising browser for dromaeo_css test... 05:20:32 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpOMk2Uw/profile http://localhost:49279/getInfo.html) 05:20:34 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 05:20:34 INFO - PROCESS | 1656 | colorDepth:24 05:20:34 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 05:20:34 INFO - PROCESS | 1656 | __metrics 05:20:35 INFO - PROCESS | 1656 | 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:20:35 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:20:35 INFO - TEST-INFO | 1656: exit 0 05:20:35 INFO - Browser initialized. 05:20:35 INFO - Running cycle 1/1 for dromaeo_css test... 05:20:35 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpOMk2Uw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:47 INFO - PROCESS | 1659 | RSS: Main: 243687424 05:20:47 INFO - PROCESS | 1659 | 05:21:38 INFO - PROCESS | 1659 | 1467980498722 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] 05:23:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 05:23:51 INFO - PROCESS | 1659 | RSS: Main: 393105408 05:23:51 INFO - PROCESS | 1659 | 05:26:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 05:26:55 INFO - PROCESS | 1659 | RSS: Main: 380502016 05:26:55 INFO - PROCESS | 1659 | 05:29:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 05:29:58 INFO - PROCESS | 1659 | RSS: Main: 374996992 05:29:58 INFO - PROCESS | 1659 | 05:33:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 05:33:01 INFO - PROCESS | 1659 | RSS: Main: 375533568 05:33:01 INFO - PROCESS | 1659 | 05:36:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 05:36:05 INFO - PROCESS | 1659 | RSS: Main: 349446144 05:36:05 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 05:39:08 INFO - PROCESS | 1659 | RSS: Main: 387760128 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | __start_tp_report 05:39:08 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 05:39:08 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 05:39:08 INFO - PROCESS | 1659 | |i|pagename|runs| 05:39:08 INFO - PROCESS | 1659 | |0;dojo.html;3182.6180858297007;3324.335132973405;3326.268220991381;3348.096014076201;3379.543761592184;5519.199716041172;5534.612577119598;5563.13771365438;5630.943690563098;5657.745401456928;5544.306961629796;5607.859803504904;5614.27014488117;5614.382417934024;5626.971865140678;5096.4648711885275;5138.152204886189;5154.94695778518;5175.197844334134;5229.294045303893;5250.422453530127;5252.185911183754;5266.605004624649;5271.920921186185;5275.57795376369;5151.150060240056;5153.561947234471;5190.532852043315;5196.0647083524955;5271.4464981177;5249.370075590915;5259.31628888244;5261.184516399964;5271.683698978035;5292.179712144585;5086.79652813887;5092.439831618519;5099.974500127512;5183.45573714762;5186.5332120109;4619.330197121432;5394.973025134849;5522.834314970517;5532.502074813246;5621.943780562183;6113.633182009094;6116.296625887991;6127.234095738033;6139.109829074804;6145.4161854623735;5168.483151684802;5970.074638431007;6111.43980788641;6164.691765411756;6208.068789681584;5738.852229554106;5741.368449470555;5749.022666146765;5766.308043034863;5770.221020162225;5907.970460147758;6314.242290925026;6335.11308416822;6341;6345.555811093272;5146.665466744649;5920.763169473259;5963.105534169909;6001.549883758648;6046.3651316612;6168.814935551941;6222.875542489215;6223.62658240498;6225.62646241227;6229.657368844666;6142.416470435391;6146.293176284785;6149.692515374303;6151.753929842756;6180.907286390618;6047.0929360596265;6105.694715264219;6107;6107.694615269219;6111.083337499322;4860.6111511079025;4897.216445368724;4912.729799861042;4917.409910810654;4952.5542701157065;4342.522322544518;4396.890077748082;4432.889177770581;4462.286034234507;4475.5748203920575;5586;5608.719564021864;5614.578906582023;5645.435456454404;5669.2346533218315;6168.599041062316;6174.104754810527;6191.380861913773;6193.566450348522;6218.782342617987;5467.808626698126;5514.172874068841;5591.720413979366;5602;5613.242212301287;5303.151495760737;5313.495217954288;5317.654352467154;5318.414974352818;5328.813491527778;5291.417944026154;6216;6275.090111933835;6307.274663413767;6340.841478963062;6213.720382582705;6217.347178546188;6228.439440450381;6230.906536402048;6236.968815155895;6161.4146656067605;6207.006878899354;6291.307956124731;6342.23893132827;6348.650824204713;4809.831655892027;5956.672383018976;5962.761489540369;5979.551533634908;6031.306399763907;6074.1799857020505;6102.176206212104;6105.145279660762;6106.053561697944;6121.112438696453;5832.358440571449;5867.090600956859;5902.321233058082;5938.406159384027;5953.7023148843255;5947.137665038632;5973.581849270509;6020.096985452217;6030.7889223876955;6073.180120683828;5685.971570142123;5870;5875.853103672443;5894.410558944071;5957.3446920837805;5861.794837180678;5962.284525856925;5982.252218472691;6042.395760423922;6075.848103797263;5847.8245652630485;5862.296524417097;5876.6180198288685;5882.029465138376;5924.763009479572 05:39:08 INFO - PROCESS | 1659 | |1;ext.html;468.04285236691004;528.2815371095311;534.914413693809;540.6512799244487;551.732409781256;24475.877620611893;30184.39631207377;31470;31478.370432591335;31528.211794705145;32111.51832722511;32767.1808204795;35031.82484087582;42838.57161428385;43171.568284317145;9586.712398628053;9713.271504637181;9713.95143024284;9716.611335546604;9719.514024298793;9624.422534647909;9729.854052189223;9730.902690973106;9734.415935043884;9756.756081097994;8655;8820.735377938672;8821.073787252342;8920.10798920106;8941.731748047569;8792.34057445691;11611.941940290286;11770.352630605314;11787.469563869647;12088.214266072677;11689.649310520697;11764.470598823074;11769.117316201318;11770.41147942608;11801.881981180164;41253.79373103145;41486;41554.584454155374;41627.37558936619;41674.1665166698;3543.8050907200095;3731.8320675569666;3747.4566187902874;3756.981215093907;4386.649068074546;27810.582841257397;32078.358432831214;32598.696052157884;33139.3372132556;33334.83332583346;3227.2415982244156;3385.356782211372;3402.5406570112964;3423.1099914022284;3431;527216;529559;530455.3477232627;531953;532595;61256.77486450335;61313;61623.38376616266;61688.69155654193;61977;62613.060804087974;62634.68682656557;62651.37348626547;62676;62681;16756;17020.06389648581;17045.829541704425;17046.14769261532;17053.829461705223;13092.083554151308;13192.802107968388;13196.010299227404;13203.933980330037;13207.471701131846;15229.238538073274;16229.918850405673;16434.83565164357;16463.83536164647;16580.83419165817;5029.06962211992;6204.534659900435;6224.657643829632;6239.844003899939;6306;912.6349460215966;922.9616681233612;1025.205465764024;1168.9415529223504;1173.8239264110452;27958.161255162377;28553.08091317281;30268.394632107236;31316.686833131833;31733;1150.3730466895395;1161.6863446869468;1176.5529098942347;1216.5681183179843;1232.5190847257766;15878.603034923988;15885.920570397306;15994.840051599567;16412.917935410245;16464.847460677876;650431;655684;656182;656608;659055;60230.397696023356;60270.39729602735;60385.69807150936;60392.69803650954;60516.39483605196;1277.316635599968;1365.3514580574347;1391.8468968413265;1392.1647011793045;1436.4469679173594;31744.04767856968;31879.840600796848;31956.8402157997;32792.01623951285;32883.83558082194;50246;51213.48786512087;56390;56390.43609564016;62582;607253;614887;620019;621164;622276;23686.407839803454;26232.868835656463;26734.86632566825;26800.32999175036;26949.191524254304;48439;49922.75038624783;50337.74831125821;50599.73500662513;50649;38007.28967196538;40576.59423405846;40614.59385406227;41014.38478422767;41599.7920010398;39896.40155397613;40469.797651011555;40563.18873622452;41435.96410089772;41492;38534.80732596319;38748.80625596854;39072;39275.607243927196;39541.20917581575;30312.939047133244;32216.720936111335;34002.659973400936;35396;35963.820180898925;26155.869220653774;26206.475870482864;26329.86835065889;26577.803998819712;26971.325716856452 05:39:08 INFO - PROCESS | 1659 | |2;jquery.html;354.09881850689993;377.486618199249;384.6153846153844;384.97497662651915;405.0905716666085;11909.404529773508;13059.73880522389;20365.1853925843;20381.898090509527;20804.063817128226;19974.002599740055;20170.89914550429;20762.27332043379;21191.894040529776;21227.6815847762;5649.096144616862;5883.205767221413;6088.847778805543;6118.785842495514;6123.908141377883;5941.85145371367;5961.552883533731;5962.8807423851495;5976.043832986723;5989;5737.397573254811;5820.621659592134;5828.795992140276;5888.793892213774;5900.409959004108;5697.857553561153;5833.562482813784;5846.619969701977;5870.853228669274;5871.178035075093;5028.949710502885;5093.898122037575;5095.082885080684;5103.336566246401;5116.2069879168785;23430.882845585835;24150.87924560366;24554.263372098867;24807.379815504577;24825.751742482524;2260.536589999046;2273.1362082408746;2456.4841383309526;2483.5777917754026;2927.3706153176954;15930.283137258852;16225.51323460298;16346.673066538722;16388.75416868748;16600.83399166005;1888.9811101888943;1918.2039453626728;1969.379645411705;2026.1085122546033;2026.8934150682194;123492;128022;128034;128586;128586.71413285934;9227.677031303872;10642.680719578426;10963.013328800287;11157.832632510519;11277.774444511064;13563.186208827501;13646.931765341109;13650.590482285548;13673.179609223478;13678.521251756141;8584.39909206362;8780.297576193889;8787;8888.73333799987;8929.955350223207;8972.327075469366;8976.237019853255;8978.506182160045;9097.999220085785;9128.634854605874;5547.889042219133;6617.536772425884;6736.2253340865445;6745.831354216184;6756.831079223059;1837.1824538080425;1862.6926557118195;1867.915943782533;1868.9252429902924;1871.691170956777;721.4753634224625;836.2348451167236;861.7156338408436;900.9954950225207;950.3369104067881;21482.34219203118;21969.568301479776;21999.670004949938;22033.118675252812;22035.66946495804;949.9097585729345;968.2109081099022;984.3650845204803;1022.7136401807505;1035.7100011996654;11201.0479109275;11600.593979210687;11632.941835290769;11641.010514106227;11655.88344116548;577992;622081;624764;631749;632600;131605;140799;141430;142494.28752856376;143306;876.8775966762554;978.6721448314752;998.1964518562481;1026.58423338548;1026.8767747870304;17402.78180527351;18505.90747046256;19157.8084219156;19207.903960480107;19312.4206273812;51156;54199.45800541944;57208.71395642995;57842.7107864458;58239.417605825096;75580.62209688916;78767;83048;83531;84243.31513369575;6943.722251109899;7163.8567228656175;7503.137139229058;8241.217084376975;8548.829023419372;11095.5561777528;12601.43293551774;13308.268045257604;13325.66685832862;13420.127691700009;5488;11026.944865275622;11250.774984500427;11495;11723.41382930833;11384.260023098803;11484.712882178012;11585;11753.353565553976;11834.940825295818;11780.823287650863;11866.881331186578;11877.287362758263;11888.881111188777;11894.226875253082;8237.464564803508;8342.249197572248;8346.332293416634;8361.83276334482;8476.576171191538;11447.370394628375;11530.654080377602;11609.245399049034;11635.418229088342;11881.465334059814 05:39:08 INFO - PROCESS | 1659 | |3;mootools.html;1552.5342397280813;1601.3114360824845;1824.4253060286017;1903.9904800475995;1960.1571324330532;8566.057733649293;8783.560821958892;8948.657701344802;8963.59663815129;8964;7810.843783124335;8046.994125734283;8457.91542084579;8693.69572064978;8710.520921349324;2314.7916687498123;2342.0397636968846;2348.1664009276715;2348.8003519700846;2350.0247397330086;2303.57383883982;2304.7925686688195;2354.8822558872075;2355.387599224197;2357.3045951444365;2057.5781964697276;2112.6302896993043;2117.4282943605217;2142.689310050042;2144.0566150893633;1673.481220821543;2330.3591512334074;2330.7086614173227;2334.054707843326;2338.812894968407;1886.9245230190777;2261.525079733258;2276.8747718875456;2292.988535057314;2300.332903457995;7859.607019649052;8584.613692383859;8803.471791692518;8904.376693631448;8975;5160.664556803796;5323.574114070865;5342.198670199501;5344.171653393731;5354.14333706609;3539.557555305587;3562.839672214731;3631.5823680276576;3635.781853088823;3669.6330366963357;1072.4584085037084;1073.9194560408;1077.3535878472933;1078.0459293525207;1094.518411898762;11452;11638.359890206035;11638.767224655545;11645.825312620318;11648.301101933912;3705.40713485841;4519.909601807945;4749.26386410107;4753.405824271966;4756.7859446325;10030.64892728751;10033.749156271006;10050.195984321237;10056.044675755791;10056.597736090474;5282.920756188661;5301.575873930076;5306.442823503554;5312.282841816383;5312.362516498045;4771.880702982453;4836.492168322346;4875.049365373789;4878.048780487842;4878.341423907798;3727.254549090193;3769.321522125989;3809.980950095232;3850.4994350734223;3850.6534411903062;1191.874853140425;1202.4949521201117;1208.7703336366237;1219.5426714981882;1224.4857159992825;800.3957012455559;862.9946112778542;876.5179151466668;918.912703293186;921.4793641592479;4959.22283521222;5672.631278966936;5753.712314384347;5899.439553242435;5901.734421951022;439.34098851722416;441.18160811693843;445.6548650656103;457.4853290048695;458.51168505541494;3656.4149736042104;3660.8834305537066;3711.0536813112485;3959.604039596075;4238.724482908601;10515.26393152472;10545.156387488982;10643.25497215203;10756.731081723019;10764.515596798163;10683.465826708634;10700.892991070144;10703.946480267548;10711.46442677863;10781.02970732638;7968.844517544923;8042.155573664798;8280;8339.374546909006;8372.916270837335;5994.460498555151;6053;6136.907946380718;6146.508279337553;6205.162303089025;8077.676892924217;9966.551505182138;9989.650362237287;9999.650012249536;10011.699649010541;9697.22422206236;10348.896511034793;10355.275130740776;10360.481975901024;10366.429846358522;7485.5508669480005;7640.579768112585;7655.157932627518;7657.349125324187;7662.080550333995;8016.8797468036855;8285.171482851667;8301.626426810688;8361.122082181526;8386.412951093365;7509.812254693457;7568.5458872467825;7605.011348524657;7682.078150621961;7707.997960265129;7341.302576255247;7417.369523590555;7481.551106933602;7510.812229694301;7569.243075692387;6776.864462710817;6875.278095800069;6962;7040.119985001875;7048.541844780073;4910.607151427948;4957;5002.399712034579;5004.349434573628;5087.440381557952;4760.571548560646;4847.418309802846;5037.271849228676;5134.409542902614;5177.611679124156 05:39:08 INFO - PROCESS | 1659 | |4;prototype.html;302.3921916946937;310.0249714647434;311.1924555777757;316.2851954582641;319.28320919535645;5029.47190544993;5197.896042079161;5555;5566.27638407007;5621.100623900177;4232.407462955189;4792.736399498026;5024.5729113025345;5350.598705097113;5523.585731070176;3863.729538932276;3977.389157391254;4000.3399439092514;4156.937645935313;4292.120115376356;3839.676995417774;3989.321815291393;4128.772917489537;4175.812088456011;4205.558416366283;3431.5882094148737;3922.941155882664;4169.62473377396;4174.123434078847;4176;3782.2813665403487;3945.5265368156006;3955.7230993830444;4176.373543968414;4187.748735075906;3130.89041883533;3566.5720113586276;3602.883106237075;3617.4212126059706;3620.2984597239256;4332.267093162729;4365.934510982338;5242.501962313574;5374.978754036721;5393.2449457076045;4384.333953097835;4419.116176764628;4738.147133515966;4804.063207674505;4810.542998415145;4965.553100220997;5681.7727290908315;5701.287339082614;5754.510866576346;5784.479396854304;3076.6308043034755;3326.95392333713;3963.5243770747406;4004.8798536043673;4086.2849001424634;5312.415634280226;5807.274090738658;5817.88364232713;6106.847328816815;6117.011971109363;3387.7628566000144;3571.8571257149943;3792.2036372361563;3848.5381754189116;4076.408920706481;4087.959120408758;4114.300568903293;4115.382692596135;4116.567760385176;4165.687573432005;4424.646028317727;4768.47546769855;4778.259369797687;4785;4803.567678908845;4972.801087956441;5830.591858569859;5835.416458354215;5861.648301101947;5934.643921364732;1131.4569006876745;1380.8400943207553;1382.4884792626592;1383.3567391163158;1383.6540864783803;692.9688164032631;714.3999040805749;827;833.9499630022218;850.6172222499899;4977.303177555145;5239.973800130975;5295.099833028395;5400.891982160413;5401.730593310569;4808.66779611888;5963.314218864886;5989.191459153046;5994.400559943971;6062.878742425127;4786.7502273931195;4818.734969576637;5208.192730126836;5421.620486565903;5544.972275138599;2609.386794103384;3091.8405597900787;3134.8275844828754;3138.7959782614057;3148.4572559446165;5219.6868187908085;6148.938510614926;6152.446279834837;6206.386339551749;6240.188775459162;5847.590668653153;6089.177960975212;6097.664628445389;6120.867639486709;6162.630242185483;4645;5165.845024649267;6140.539459540552;6157.907631385533;6163.19878415812;5151.407588127413;5410.458954104558;5648.63283886546;5936.970315148396;6001.519878409804;5151.793928242978;5414.025475414305;5508.366537848038;5960.5827592068135;6014.8195554133445;5459.836204913859;6220.377962203744;6283.2460104787715;6292.1505596743855;6297;3698.3342998260573;3907.27715372657;3923.411488276667;3925.704517509272;3938.6061393861532;5139.588832893434;5808.6224395414165;5886.499647530008;5941.673207973603;5998.490128338965;5324.787008519771;5447.891042179213;5652;5854.268216472941;5864.266966629171;4973.850784476472;5072.391313042459;5771.624844385102;5772.163036359789;5804.564657650778;5100.464451232716;5174.27560141588;5460.808871689631;5600.683839297843;5746.534633668555;4977.724682047807;5333.306670132858;5432.643806746038;5747.48272655463;5824.242848429677;4669.696469729457;5064.518870707277;5356.196570514454;5646.2942132233475;5684.687342196219 05:39:08 INFO - PROCESS | 1659 | |5;yui.html;632.4813652804701;707.2255879811604;807.3783186946056;812.1675282835097;848.2238751542337;12730.872691273085;16181.919090404532;16207.756883646725;16221.675566488679;16231.675366492678;12502.749945001116;14779.704405911876;14993.17537535435;15012.399504019853;15035.624109397271;699.077218072145;725.6843273176186;882.4925667741043;882.9381943263974;922.3543519536317;898.8112496375769;902.62992173209;902.7517432705993;905.4341036851968;906.6373450619739;937.8265020971108;959.0313783079071;963.8216929867997;968.2496065549185;971.7279161834698;750.8535835512077;807.8384323135367;956.8182045411419;958.9136977672008;959.7744530035505;566.0659911146599;567.7955935863087;572.879695263995;678.2471456683078;706.3042902740797;14593.9784215105;14600.977931544796;14625;14647.926760366237;14659;13711.794323085162;14075.085119467201;14101.64745881351;14104.717905641932;14196.148231106166;11193.888061119365;11403.828942565868;11885.465154068088;11910.404479775976;12834.679133021657;4039.8788036358956;4632.884177895546;4639.280911458729;4808.519148085164;4811.879703007417;103841;104249.95750042555;105143;106330.4050439244;107390;10918.563257469612;10952.726181845359;11028;11124.443777811077;11165.83251251232;21076.156953721988;21087.367378978655;21102.472438188863;21109;21109.050092745863;7771.378289736807;7836.686532538749;7894.684212631545;7927.286544210933;7984.4810087345295;3796.677282431024;4326.697131200785;4416.6025057744955;4431.534688857688;4521.163584736769;3515.964840351615;3617.5839778425293;3618.4029635110437;3618.8552457901387;3622.166901612644;675.537256978971;687.6355531568277;696.5124412910983;741.255038686117;752.2101793117205;647.3429469088878;688.6109348218241;702.4332167571972;705.5942832871017;706.1420374245316;9569.61721531145;9628.47043412619;9672.419654820733;9763.511824408752;9832.459214743258;555.7665780372254;622.5766478794372;651.8402991267075;661.7386132477644;672.8082496488576;3910;4735.12400205963;4836.032793441326;4839.540243676845;4845.53967373099;105924;120903;150221;157105.2144739269;164025.17987410226;139268;140189;141339.29330353282;145245;147761.2611936955;116602;117735;118612;123005;123828;9211.677591284273;9300.11648893354;9312.301577381588;9338.859917101114;9345.77580678032;58457.415425845196;59417.92037278674;64339.56604339529;67464.3253567458;71003.64498177476;52886.677833054484;102434.48782756038;107847;108374;109840;12004.279743215435;12143.939280303543;12146.514139434325;12202.938985305016;12432.937835310766;12486.43811028524;13820.861791381958;13960.58118256454;14326.713465730836;14349.35427905726;12322;12451;13208.867911320764;13220.603381898558;13239.86760132425;11186.216964812385;12132.393380331125;12580.685482862635;12599.370031498573;12953.935230323788;12142.210756300812;12205.267683938991;12254.326012069421;12279.9386003073;12870.420831062438;10536.736581585215;10706.036456718934;10895.56417743313;10927.56289748433;11142.665720028412;9043.00526942049;10675.679729608124;10727.356358618508;10936.343819370863;10963.177761668067 05:39:08 INFO - PROCESS | 1659 | __end_tp_report 05:39:08 INFO - PROCESS | 1659 | __start_cc_report 05:39:08 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1475 05:39:08 INFO - PROCESS | 1659 | __end_cc_report 05:39:08 INFO - PROCESS | 1659 | __startTimestamp1467981548593__endTimestamp 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | ------- Summary: start ------- 05:39:08 INFO - PROCESS | 1659 | Number of tests: 6 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5644.15 Median:5861.79 stddev:627.13 (10.7%) stddev-sans-first:598.59 05:39:08 INFO - PROCESS | 1659 | Values: 3182.6 3324.3 3326.3 3348.1 3379.5 5519.2 5534.6 5563.1 5630.9 5657.7 5544.3 5607.9 5614.3 5614.4 5627.0 5096.5 5138.2 5154.9 5175.2 5229.3 5250.4 5252.2 5266.6 5271.9 5275.6 5151.2 5153.6 5190.5 5196.1 5271.4 5249.4 5259.3 5261.2 5271.7 5292.2 5086.8 5092.4 5100.0 5183.5 5186.5 4619.3 5395.0 5522.8 5532.5 5621.9 6113.6 6116.3 6127.2 6139.1 6145.4 5168.5 5970.1 6111.4 6164.7 6208.1 5738.9 5741.4 5749.0 5766.3 5770.2 5908.0 6314.2 6335.1 6341.0 6345.6 5146.7 5920.8 5963.1 6001.5 6046.4 6168.8 6222.9 6223.6 6225.6 6229.7 6142.4 6146.3 6149.7 6151.8 6180.9 6047.1 6105.7 6107.0 6107.7 6111.1 4860.6 4897.2 4912.7 4917.4 4952.6 4342.5 4396.9 4432.9 4462.3 4475.6 5586.0 5608.7 5614.6 5645.4 5669.2 6168.6 6174.1 6191.4 6193.6 6218.8 5467.8 5514.2 5591.7 5602.0 5613.2 5303.2 5313.5 5317.7 5318.4 5328.8 5291.4 6216.0 6275.1 6307.3 6340.8 6213.7 6217.3 6228.4 6230.9 6237.0 6161.4 6207.0 6291.3 6342.2 6348.7 4809.8 5956.7 5962.8 5979.6 6031.3 6074.2 6102.2 6105.1 6106.1 6121.1 5832.4 5867.1 5902.3 5938.4 5953.7 5947.1 5973.6 6020.1 6030.8 6073.2 5686.0 5870.0 5875.9 5894.4 5957.3 5861.8 5962.3 5982.3 6042.4 6075.8 5847.8 5862.3 5876.6 5882.0 5924.8 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:73134.38 Median:38641.81 stddev:161370.40 (417.6%) stddev-sans-first:161730.86 05:39:08 INFO - PROCESS | 1659 | Values: 468.0 528.3 534.9 540.7 551.7 24475.9 30184.4 31470.0 31478.4 31528.2 32111.5 32767.2 35031.8 42838.6 43171.6 9586.7 9713.3 9714.0 9716.6 9719.5 9624.4 9729.9 9730.9 9734.4 9756.8 8655.0 8820.7 8821.1 8920.1 8941.7 8792.3 11611.9 11770.4 11787.5 12088.2 11689.6 11764.5 11769.1 11770.4 11801.9 41253.8 41486.0 41554.6 41627.4 41674.2 3543.8 3731.8 3747.5 3757.0 4386.6 27810.6 32078.4 32598.7 33139.3 33334.8 3227.2 3385.4 3402.5 3423.1 3431.0 527216.0 529559.0 530455.3 531953.0 532595.0 61256.8 61313.0 61623.4 61688.7 61977.0 62613.1 62634.7 62651.4 62676.0 62681.0 16756.0 17020.1 17045.8 17046.1 17053.8 13092.1 13192.8 13196.0 13203.9 13207.5 15229.2 16229.9 16434.8 16463.8 16580.8 5029.1 6204.5 6224.7 6239.8 6306.0 912.6 923.0 1025.2 1168.9 1173.8 27958.2 28553.1 30268.4 31316.7 31733.0 1150.4 1161.7 1176.6 1216.6 1232.5 15878.6 15885.9 15994.8 16412.9 16464.8 650431.0 655684.0 656182.0 656608.0 659055.0 60230.4 60270.4 60385.7 60392.7 60516.4 1277.3 1365.4 1391.8 1392.2 1436.4 31744.0 31879.8 31956.8 32792.0 32883.8 50246.0 51213.5 56390.0 56390.4 62582.0 607253.0 614887.0 620019.0 621164.0 622276.0 23686.4 26232.9 26734.9 26800.3 26949.2 48439.0 49922.8 50337.7 50599.7 50649.0 38007.3 40576.6 40614.6 41014.4 41599.8 39896.4 40469.8 40563.2 41436.0 41492.0 38534.8 38748.8 39072.0 39275.6 39541.2 30312.9 32216.7 34002.7 35396.0 35963.8 26155.9 26206.5 26329.9 26577.8 26971.3 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:36487.12 Median:24352.57 stddev:103537.72 (425.2%) stddev-sans-first:103792.62 05:39:08 INFO - PROCESS | 1659 | Values: 354.1 377.5 384.6 385.0 405.1 11909.4 13059.7 20365.2 20381.9 20804.1 19974.0 20170.9 20762.3 21191.9 21227.7 5649.1 5883.2 6088.8 6118.8 6123.9 5941.9 5961.6 5962.9 5976.0 5989.0 5737.4 5820.6 5828.8 5888.8 5900.4 5697.9 5833.6 5846.6 5870.9 5871.2 5028.9 5093.9 5095.1 5103.3 5116.2 23430.9 24150.9 24554.3 24807.4 24825.8 2260.5 2273.1 2456.5 2483.6 2927.4 15930.3 16225.5 16346.7 16388.8 16600.8 1889.0 1918.2 1969.4 2026.1 2026.9 123492.0 128022.0 128034.0 128586.0 128586.7 9227.7 10642.7 10963.0 11157.8 11277.8 13563.2 13646.9 13650.6 13673.2 13678.5 8584.4 8780.3 8787.0 8888.7 8930.0 8972.3 8976.2 8978.5 9098.0 9128.6 5547.9 6617.5 6736.2 6745.8 6756.8 1837.2 1862.7 1867.9 1868.9 1871.7 721.5 836.2 861.7 901.0 950.3 21482.3 21969.6 21999.7 22033.1 22035.7 949.9 968.2 984.4 1022.7 1035.7 11201.0 11600.6 11632.9 11641.0 11655.9 577992.0 622081.0 624764.0 631749.0 632600.0 131605.0 140799.0 141430.0 142494.3 143306.0 876.9 978.7 998.2 1026.6 1026.9 17402.8 18505.9 19157.8 19207.9 19312.4 51156.0 54199.5 57208.7 57842.7 58239.4 75580.6 78767.0 83048.0 83531.0 84243.3 6943.7 7163.9 7503.1 8241.2 8548.8 11095.6 12601.4 13308.3 13325.7 13420.1 5488.0 11026.9 11250.8 11495.0 11723.4 11384.3 11484.7 11585.0 11753.4 11834.9 11780.8 11866.9 11877.3 11888.9 11894.2 8237.5 8342.2 8346.3 8361.8 8476.6 11447.4 11530.7 11609.2 11635.4 11881.5 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5674.58 Median:4766.23 stddev:3231.32 (67.8%) stddev-sans-first:3225.54 05:39:08 INFO - PROCESS | 1659 | Values: 1552.5 1601.3 1824.4 1904.0 1960.2 8566.1 8783.6 8948.7 8963.6 8964.0 7810.8 8047.0 8457.9 8693.7 8710.5 2314.8 2342.0 2348.2 2348.8 2350.0 2303.6 2304.8 2354.9 2355.4 2357.3 2057.6 2112.6 2117.4 2142.7 2144.1 1673.5 2330.4 2330.7 2334.1 2338.8 1886.9 2261.5 2276.9 2293.0 2300.3 7859.6 8584.6 8803.5 8904.4 8975.0 5160.7 5323.6 5342.2 5344.2 5354.1 3539.6 3562.8 3631.6 3635.8 3669.6 1072.5 1073.9 1077.4 1078.0 1094.5 11452.0 11638.4 11638.8 11645.8 11648.3 3705.4 4519.9 4749.3 4753.4 4756.8 10030.6 10033.7 10050.2 10056.0 10056.6 5282.9 5301.6 5306.4 5312.3 5312.4 4771.9 4836.5 4875.0 4878.0 4878.3 3727.3 3769.3 3810.0 3850.5 3850.7 1191.9 1202.5 1208.8 1219.5 1224.5 800.4 863.0 876.5 918.9 921.5 4959.2 5672.6 5753.7 5899.4 5901.7 439.3 441.2 445.7 457.5 458.5 3656.4 3660.9 3711.1 3959.6 4238.7 10515.3 10545.2 10643.3 10756.7 10764.5 10683.5 10700.9 10703.9 10711.5 10781.0 7968.8 8042.2 8280.0 8339.4 8372.9 5994.5 6053.0 6136.9 6146.5 6205.2 8077.7 9966.6 9989.7 9999.7 10011.7 9697.2 10348.9 10355.3 10360.5 10366.4 7485.6 7640.6 7655.2 7657.3 7662.1 8016.9 8285.2 8301.6 8361.1 8386.4 7509.8 7568.5 7605.0 7682.1 7708.0 7341.3 7417.4 7481.6 7510.8 7569.2 6776.9 6875.3 6962.0 7040.1 7048.5 4910.6 4957.0 5002.4 5004.3 5087.4 4760.6 4847.4 5037.3 5134.4 5177.6 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4625.77 Median:5151.60 stddev:1450.70 (28.2%) stddev-sans-first:1418.01 05:39:08 INFO - PROCESS | 1659 | Values: 302.4 310.0 311.2 316.3 319.3 5029.5 5197.9 5555.0 5566.3 5621.1 4232.4 4792.7 5024.6 5350.6 5523.6 3863.7 3977.4 4000.3 4156.9 4292.1 3839.7 3989.3 4128.8 4175.8 4205.6 3431.6 3922.9 4169.6 4174.1 4176.0 3782.3 3945.5 3955.7 4176.4 4187.7 3130.9 3566.6 3602.9 3617.4 3620.3 4332.3 4365.9 5242.5 5375.0 5393.2 4384.3 4419.1 4738.1 4804.1 4810.5 4965.6 5681.8 5701.3 5754.5 5784.5 3076.6 3327.0 3963.5 4004.9 4086.3 5312.4 5807.3 5817.9 6106.8 6117.0 3387.8 3571.9 3792.2 3848.5 4076.4 4088.0 4114.3 4115.4 4116.6 4165.7 4424.6 4768.5 4778.3 4785.0 4803.6 4972.8 5830.6 5835.4 5861.6 5934.6 1131.5 1380.8 1382.5 1383.4 1383.7 693.0 714.4 827.0 833.9 850.6 4977.3 5240.0 5295.1 5400.9 5401.7 4808.7 5963.3 5989.2 5994.4 6062.9 4786.8 4818.7 5208.2 5421.6 5545.0 2609.4 3091.8 3134.8 3138.8 3148.5 5219.7 6148.9 6152.4 6206.4 6240.2 5847.6 6089.2 6097.7 6120.9 6162.6 4645.0 5165.8 6140.5 6157.9 6163.2 5151.4 5410.5 5648.6 5937.0 6001.5 5151.8 5414.0 5508.4 5960.6 6014.8 5459.8 6220.4 6283.2 6292.2 6297.0 3698.3 3907.3 3923.4 3925.7 3938.6 5139.6 5808.6 5886.5 5941.7 5998.5 5324.8 5447.9 5652.0 5854.3 5864.3 4973.9 5072.4 5771.6 5772.2 5804.6 5100.5 5174.3 5460.8 5600.7 5746.5 4977.7 5333.3 5432.6 5747.5 5824.2 4669.7 5064.5 5356.2 5646.3 5684.7 05:39:08 INFO - PROCESS | 1659 | 05:39:08 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:25182.66 Median:21105.74 stddev:40897.44 (193.8%) stddev-sans-first:40970.62 05:39:08 INFO - PROCESS | 1659 | Values: 632.5 707.2 807.4 812.2 848.2 12730.9 16181.9 16207.8 16221.7 16231.7 12502.7 14779.7 14993.2 15012.4 15035.6 699.1 725.7 882.5 882.9 922.4 898.8 902.6 902.8 905.4 906.6 937.8 959.0 963.8 968.2 971.7 750.9 807.8 956.8 958.9 959.8 566.1 567.8 572.9 678.2 706.3 14594.0 14601.0 14625.0 14647.9 14659.0 13711.8 14075.1 14101.6 14104.7 14196.1 11193.9 11403.8 11885.5 11910.4 12834.7 4039.9 4632.9 4639.3 4808.5 4811.9 103841.0 104250.0 105143.0 106330.4 107390.0 10918.6 10952.7 11028.0 11124.4 11165.8 21076.2 21087.4 21102.5 21109.0 21109.1 7771.4 7836.7 7894.7 7927.3 7984.5 3796.7 4326.7 4416.6 4431.5 4521.2 3516.0 3617.6 3618.4 3618.9 3622.2 675.5 687.6 696.5 741.3 752.2 647.3 688.6 702.4 705.6 706.1 9569.6 9628.5 9672.4 9763.5 9832.5 555.8 622.6 651.8 661.7 672.8 3910.0 4735.1 4836.0 4839.5 4845.5 105924.0 120903.0 150221.0 157105.2 164025.2 139268.0 140189.0 141339.3 145245.0 147761.3 116602.0 117735.0 118612.0 123005.0 123828.0 9211.7 9300.1 9312.3 9338.9 9345.8 58457.4 59417.9 64339.6 67464.3 71003.6 52886.7 102434.5 107847.0 108374.0 109840.0 12004.3 12143.9 12146.5 12202.9 12432.9 12486.4 13820.9 13960.6 14326.7 14349.4 12322.0 12451.0 13208.9 13220.6 13239.9 11186.2 12132.4 12580.7 12599.4 12953.9 12142.2 12205.3 12254.3 12279.9 12870.4 10536.7 10706.0 10895.6 10927.6 11142.7 9043.0 10675.7 10727.4 10936.3 10963.2 05:39:08 INFO - PROCESS | 1659 | -------- Summary: end -------- 05:39:08 INFO - PROCESS | 1659 | 05:39:09 INFO - TEST-INFO | 1659: exit 0 05:39:09 INFO - TEST-OK | dromaeo_css | took 1116826ms 05:39:09 INFO - TEST-START | kraken 05:39:09 INFO - operating with platform_type : mac_ 05:39:09 INFO - Initialising browser for kraken test... 05:39:09 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpAERcZq/profile http://localhost:49279/getInfo.html) 05:39:11 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 05:39:11 INFO - PROCESS | 1666 | colorDepth:24 05:39:11 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 05:39:11 INFO - PROCESS | 1666 | __metrics 05:39:11 INFO - PROCESS | 1666 | 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:39:11 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:39:12 INFO - TEST-INFO | 1666: exit 0 05:39:12 INFO - Browser initialized. 05:39:12 INFO - Running cycle 1/1 for kraken test... 05:39:12 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpAERcZq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:24 INFO - PROCESS | 1667 | RSS: Main: 243548160 05:39:24 INFO - PROCESS | 1667 | 05:39:27 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:27 INFO - PROCESS | 1667 | RSS: Main: 294289408 05:39:27 INFO - PROCESS | 1667 | 05:39:30 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 05:39:31 INFO - PROCESS | 1667 | RSS: Main: 304328704 05:39:31 INFO - PROCESS | 1667 | 05:39:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 05:39:35 INFO - PROCESS | 1667 | RSS: Main: 387096576 05:39:35 INFO - PROCESS | 1667 | 05:39:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 05:39:38 INFO - PROCESS | 1667 | RSS: Main: 288894976 05:39:38 INFO - PROCESS | 1667 | 05:39:40 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:40 INFO - PROCESS | 1667 | RSS: Main: 292663296 05:39:40 INFO - PROCESS | 1667 | 05:39:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:44 INFO - PROCESS | 1667 | RSS: Main: 328982528 05:39:44 INFO - PROCESS | 1667 | 05:39:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:47 INFO - PROCESS | 1667 | RSS: Main: 333680640 05:39:47 INFO - PROCESS | 1667 | 05:39:50 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 05:39:51 INFO - PROCESS | 1667 | RSS: Main: 333230080 05:39:51 INFO - PROCESS | 1667 | 05:39:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:53 INFO - PROCESS | 1667 | RSS: Main: 313352192 05:39:53 INFO - PROCESS | 1667 | 05:39:55 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:55 INFO - PROCESS | 1667 | RSS: Main: 319991808 05:39:55 INFO - PROCESS | 1667 | 05:39:58 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:58 INFO - PROCESS | 1667 | RSS: Main: 322007040 05:39:58 INFO - PROCESS | 1667 | 05:40:01 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:02 INFO - PROCESS | 1667 | RSS: Main: 326463488 05:40:02 INFO - PROCESS | 1667 | 05:40:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:05 INFO - PROCESS | 1667 | RSS: Main: 327868416 05:40:05 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 05:40:07 INFO - PROCESS | 1667 | RSS: Main: 371400704 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | __start_tp_report 05:40:07 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 05:40:07 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 05:40:07 INFO - PROCESS | 1667 | |i|pagename|runs| 05:40:07 INFO - PROCESS | 1667 | |0;ai-astar;101;112;112;111;112;113;113;112;112;111 05:40:07 INFO - PROCESS | 1667 | |1;audio-beat-detection;144;107;107;157;108;106;125;105;167;138 05:40:07 INFO - PROCESS | 1667 | |2;audio-dft;181;185;186;184;183;191;185;182;177;183 05:40:07 INFO - PROCESS | 1667 | |3;audio-fft;79;73;58;91;58;59;81;87;111;59 05:40:07 INFO - PROCESS | 1667 | |4;audio-oscillator;82;91;91;70;69;67;95;70;67;99 05:40:07 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;88;86;74;76;117;129;88;86;87;84 05:40:07 INFO - PROCESS | 1667 | |6;imaging-darkroom;149;113;94;112;150;97;94;94;94;93 05:40:07 INFO - PROCESS | 1667 | |7;imaging-desaturate;86;94;83;85;86;84;83;83;84;84 05:40:07 INFO - PROCESS | 1667 | |8;json-parse-financial;54;55;56;57;56;53;53;52;54;54 05:40:07 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;66;66;79;77;71;70;72;69;71;72 05:40:07 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;83;83;86;88;87;80;85;82;81;86 05:40:07 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;142;142;152;137;133;135;136;132;177;135 05:40:07 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;164;166;163;165;163;163;164;166;164;163 05:40:07 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;67;67;67;66;66;64;66;66;68;61 05:40:07 INFO - PROCESS | 1667 | __end_tp_report 05:40:07 INFO - PROCESS | 1667 | __start_cc_report 05:40:07 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,2013 05:40:07 INFO - PROCESS | 1667 | __end_cc_report 05:40:07 INFO - PROCESS | 1667 | __startTimestamp1467981607625__endTimestamp 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | ------- Summary: start ------- 05:40:07 INFO - PROCESS | 1667 | Number of tests: 14 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.54 (3.2%) stddev-sans-first:0.71 05:40:07 INFO - PROCESS | 1667 | Values: 101.0 112.0 112.0 111.0 112.0 113.0 113.0 112.0 112.0 111.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:131.50 stddev:23.56 (17.9%) stddev-sans-first:24.11 05:40:07 INFO - PROCESS | 1667 | Values: 144.0 107.0 107.0 157.0 108.0 106.0 125.0 105.0 167.0 138.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:3.62 (2.0%) stddev-sans-first:3.71 05:40:07 INFO - PROCESS | 1667 | Values: 181.0 185.0 186.0 184.0 183.0 191.0 185.0 182.0 177.0 183.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:75.60 Median:76.00 stddev:17.76 (23.4%) stddev-sans-first:18.79 05:40:07 INFO - PROCESS | 1667 | Values: 79.0 73.0 58.0 91.0 58.0 59.0 81.0 87.0 111.0 59.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:86.50 stddev:12.87 (14.9%) stddev-sans-first:13.63 05:40:07 INFO - PROCESS | 1667 | Values: 82.0 91.0 91.0 70.0 69.0 67.0 95.0 70.0 67.0 99.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:86.00 stddev:17.53 (20.4%) stddev-sans-first:18.54 05:40:07 INFO - PROCESS | 1667 | Values: 88.0 86.0 74.0 76.0 117.0 129.0 88.0 86.0 87.0 84.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:94.00 stddev:22.62 (24.1%) stddev-sans-first:18.80 05:40:07 INFO - PROCESS | 1667 | Values: 149.0 113.0 94.0 112.0 150.0 97.0 94.0 94.0 94.0 93.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:84.50 stddev:3.29 (3.9%) stddev-sans-first:3.48 05:40:07 INFO - PROCESS | 1667 | Values: 86.0 94.0 83.0 85.0 86.0 84.0 83.0 83.0 84.0 84.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.58 (2.9%) stddev-sans-first:1.67 05:40:07 INFO - PROCESS | 1667 | Values: 54.0 55.0 56.0 57.0 56.0 53.0 53.0 52.0 54.0 54.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:4.16 (5.8%) stddev-sans-first:3.95 05:40:07 INFO - PROCESS | 1667 | Values: 66.0 66.0 79.0 77.0 71.0 70.0 72.0 69.0 71.0 72.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.50 stddev:2.69 (3.1%) stddev-sans-first:2.82 05:40:07 INFO - PROCESS | 1667 | Values: 83.0 83.0 86.0 88.0 87.0 80.0 85.0 82.0 81.0 86.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:139.50 stddev:13.60 (9.7%) stddev-sans-first:14.43 05:40:07 INFO - PROCESS | 1667 | Values: 142.0 142.0 152.0 137.0 133.0 135.0 136.0 132.0 177.0 135.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:164.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 05:40:07 INFO - PROCESS | 1667 | Values: 164.0 166.0 163.0 165.0 163.0 163.0 164.0 166.0 164.0 163.0 05:40:07 INFO - PROCESS | 1667 | 05:40:07 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.50 stddev:1.99 (3.0%) stddev-sans-first:2.06 05:40:07 INFO - PROCESS | 1667 | Values: 67.0 67.0 67.0 66.0 66.0 64.0 66.0 66.0 68.0 61.0 05:40:07 INFO - PROCESS | 1667 | -------- Summary: end -------- 05:40:07 INFO - PROCESS | 1667 | 05:40:08 INFO - TEST-INFO | 1667: exit 0 05:40:08 INFO - TEST-OK | kraken | took 59405ms 05:40:08 INFO - SUITE-END | took 1176s 05:40:08 INFO - Completed test suite (00:19:36) 05:40:08 INFO - javascript benchmark 05:40:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3182.6180858297007, 3324.335132973405, 3326.268220991381, 3348.096014076201, 3379.543761592184, 5519.199716041172, 5534.612577119598, 5563.13771365438, 5630.943690563098, 5657.745401456928, 5544.306961629796, 5607.859803504904, 5614.27014488117, 5614.382417934024, 5626.971865140678, 5096.4648711885275, 5138.152204886189, 5154.94695778518, 5175.197844334134, 5229.294045303893, 5250.422453530127, 5252.185911183754, 5266.605004624649, 5271.920921186185, 5275.57795376369, 5151.150060240056, 5153.561947234471, 5190.532852043315, 5196.0647083524955, 5271.4464981177, 5249.370075590915, 5259.31628888244, 5261.184516399964, 5271.683698978035, 5292.179712144585, 5086.79652813887, 5092.439831618519, 5099.974500127512, 5183.45573714762, 5186.5332120109, 4619.330197121432, 5394.973025134849, 5522.834314970517, 5532.502074813246, 5621.943780562183, 6113.633182009094, 6116.296625887991, 6127.234095738033, 6139.109829074804, 6145.4161854623735, 5168.483151684802, 5970.074638431007, 6111.43980788641, 6164.691765411756, 6208.068789681584, 5738.852229554106, 5741.368449470555, 5749.022666146765, 5766.308043034863, 5770.221020162225, 5907.970460147758, 6314.242290925026, 6335.11308416822, 6341.0, 6345.555811093272, 5146.665466744649, 5920.763169473259, 5963.105534169909, 6001.549883758648, 6046.3651316612, 6168.814935551941, 6222.875542489215, 6223.62658240498, 6225.62646241227, 6229.657368844666, 6142.416470435391, 6146.293176284785, 6149.692515374303, 6151.753929842756, 6180.907286390618, 6047.0929360596265, 6105.694715264219, 6107.0, 6107.694615269219, 6111.083337499322, 4860.6111511079025, 4897.216445368724, 4912.729799861042, 4917.409910810654, 4952.5542701157065, 4342.522322544518, 4396.890077748082, 4432.889177770581, 4462.286034234507, 4475.5748203920575, 5586.0, 5608.719564021864, 5614.578906582023, 5645.435456454404, 5669.2346533218315, 6168.599041062316, 6174.104754810527, 6191.380861913773, 6193.566450348522, 6218.782342617987, 5467.808626698126, 5514.172874068841, 5591.720413979366, 5602.0, 5613.242212301287, 5303.151495760737, 5313.495217954288, 5317.654352467154, 5318.414974352818, 5328.813491527778, 5291.417944026154, 6216.0, 6275.090111933835, 6307.274663413767, 6340.841478963062, 6213.720382582705, 6217.347178546188, 6228.439440450381, 6230.906536402048, 6236.968815155895, 6161.4146656067605, 6207.006878899354, 6291.307956124731, 6342.23893132827, 6348.650824204713, 4809.831655892027, 5956.672383018976, 5962.761489540369, 5979.551533634908, 6031.306399763907, 6074.1799857020505, 6102.176206212104, 6105.145279660762, 6106.053561697944, 6121.112438696453, 5832.358440571449, 5867.090600956859, 5902.321233058082, 5938.406159384027, 5953.7023148843255, 5947.137665038632, 5973.581849270509, 6020.096985452217, 6030.7889223876955, 6073.180120683828, 5685.971570142123, 5870.0, 5875.853103672443, 5894.410558944071, 5957.3446920837805, 5861.794837180678, 5962.284525856925, 5982.252218472691, 6042.395760423922, 6075.848103797263, 5847.8245652630485, 5862.296524417097, 5876.6180198288685, 5882.029465138376, 5924.763009479572], "value": 5605.547953260939, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.04285236691004, 528.2815371095311, 534.914413693809, 540.6512799244487, 551.732409781256, 24475.877620611893, 30184.39631207377, 31470.0, 31478.370432591335, 31528.211794705145, 32111.51832722511, 32767.1808204795, 35031.82484087582, 42838.57161428385, 43171.568284317145, 9586.712398628053, 9713.271504637181, 9713.95143024284, 9716.611335546604, 9719.514024298793, 9624.422534647909, 9729.854052189223, 9730.902690973106, 9734.415935043884, 9756.756081097994, 8655.0, 8820.735377938672, 8821.073787252342, 8920.10798920106, 8941.731748047569, 8792.34057445691, 11611.941940290286, 11770.352630605314, 11787.469563869647, 12088.214266072677, 11689.649310520697, 11764.470598823074, 11769.117316201318, 11770.41147942608, 11801.881981180164, 41253.79373103145, 41486.0, 41554.584454155374, 41627.37558936619, 41674.1665166698, 3543.8050907200095, 3731.8320675569666, 3747.4566187902874, 3756.981215093907, 4386.649068074546, 27810.582841257397, 32078.358432831214, 32598.696052157884, 33139.3372132556, 33334.83332583346, 3227.2415982244156, 3385.356782211372, 3402.5406570112964, 3423.1099914022284, 3431.0, 527216.0, 529559.0, 530455.3477232627, 531953.0, 532595.0, 61256.77486450335, 61313.0, 61623.38376616266, 61688.69155654193, 61977.0, 62613.060804087974, 62634.68682656557, 62651.37348626547, 62676.0, 62681.0, 16756.0, 17020.06389648581, 17045.829541704425, 17046.14769261532, 17053.829461705223, 13092.083554151308, 13192.802107968388, 13196.010299227404, 13203.933980330037, 13207.471701131846, 15229.238538073274, 16229.918850405673, 16434.83565164357, 16463.83536164647, 16580.83419165817, 5029.06962211992, 6204.534659900435, 6224.657643829632, 6239.844003899939, 6306.0, 912.6349460215966, 922.9616681233612, 1025.205465764024, 1168.9415529223504, 1173.8239264110452, 27958.161255162377, 28553.08091317281, 30268.394632107236, 31316.686833131833, 31733.0, 1150.3730466895395, 1161.6863446869468, 1176.5529098942347, 1216.5681183179843, 1232.5190847257766, 15878.603034923988, 15885.920570397306, 15994.840051599567, 16412.917935410245, 16464.847460677876, 650431.0, 655684.0, 656182.0, 656608.0, 659055.0, 60230.397696023356, 60270.39729602735, 60385.69807150936, 60392.69803650954, 60516.39483605196, 1277.316635599968, 1365.3514580574347, 1391.8468968413265, 1392.1647011793045, 1436.4469679173594, 31744.04767856968, 31879.840600796848, 31956.8402157997, 32792.01623951285, 32883.83558082194, 50246.0, 51213.48786512087, 56390.0, 56390.43609564016, 62582.0, 607253.0, 614887.0, 620019.0, 621164.0, 622276.0, 23686.407839803454, 26232.868835656463, 26734.86632566825, 26800.32999175036, 26949.191524254304, 48439.0, 49922.75038624783, 50337.74831125821, 50599.73500662513, 50649.0, 38007.28967196538, 40576.59423405846, 40614.59385406227, 41014.38478422767, 41599.7920010398, 39896.40155397613, 40469.797651011555, 40563.18873622452, 41435.96410089772, 41492.0, 38534.80732596319, 38748.80625596854, 39072.0, 39275.607243927196, 39541.20917581575, 30312.939047133244, 32216.720936111335, 34002.659973400936, 35396.0, 35963.820180898925, 26155.869220653774, 26206.475870482864, 26329.86835065889, 26577.803998819712, 26971.325716856452], "value": 20354.608719795655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.09881850689993, 377.486618199249, 384.6153846153844, 384.97497662651915, 405.0905716666085, 11909.404529773508, 13059.73880522389, 20365.1853925843, 20381.898090509527, 20804.063817128226, 19974.002599740055, 20170.89914550429, 20762.27332043379, 21191.894040529776, 21227.6815847762, 5649.096144616862, 5883.205767221413, 6088.847778805543, 6118.785842495514, 6123.908141377883, 5941.85145371367, 5961.552883533731, 5962.8807423851495, 5976.043832986723, 5989.0, 5737.397573254811, 5820.621659592134, 5828.795992140276, 5888.793892213774, 5900.409959004108, 5697.857553561153, 5833.562482813784, 5846.619969701977, 5870.853228669274, 5871.178035075093, 5028.949710502885, 5093.898122037575, 5095.082885080684, 5103.336566246401, 5116.2069879168785, 23430.882845585835, 24150.87924560366, 24554.263372098867, 24807.379815504577, 24825.751742482524, 2260.536589999046, 2273.1362082408746, 2456.4841383309526, 2483.5777917754026, 2927.3706153176954, 15930.283137258852, 16225.51323460298, 16346.673066538722, 16388.75416868748, 16600.83399166005, 1888.9811101888943, 1918.2039453626728, 1969.379645411705, 2026.1085122546033, 2026.8934150682194, 123492.0, 128022.0, 128034.0, 128586.0, 128586.71413285934, 9227.677031303872, 10642.680719578426, 10963.013328800287, 11157.832632510519, 11277.774444511064, 13563.186208827501, 13646.931765341109, 13650.590482285548, 13673.179609223478, 13678.521251756141, 8584.39909206362, 8780.297576193889, 8787.0, 8888.73333799987, 8929.955350223207, 8972.327075469366, 8976.237019853255, 8978.506182160045, 9097.999220085785, 9128.634854605874, 5547.889042219133, 6617.536772425884, 6736.2253340865445, 6745.831354216184, 6756.831079223059, 1837.1824538080425, 1862.6926557118195, 1867.915943782533, 1868.9252429902924, 1871.691170956777, 721.4753634224625, 836.2348451167236, 861.7156338408436, 900.9954950225207, 950.3369104067881, 21482.34219203118, 21969.568301479776, 21999.670004949938, 22033.118675252812, 22035.66946495804, 949.9097585729345, 968.2109081099022, 984.3650845204803, 1022.7136401807505, 1035.7100011996654, 11201.0479109275, 11600.593979210687, 11632.941835290769, 11641.010514106227, 11655.88344116548, 577992.0, 622081.0, 624764.0, 631749.0, 632600.0, 131605.0, 140799.0, 141430.0, 142494.28752856376, 143306.0, 876.8775966762554, 978.6721448314752, 998.1964518562481, 1026.58423338548, 1026.8767747870304, 17402.78180527351, 18505.90747046256, 19157.8084219156, 19207.903960480107, 19312.4206273812, 51156.0, 54199.45800541944, 57208.71395642995, 57842.7107864458, 58239.417605825096, 75580.62209688916, 78767.0, 83048.0, 83531.0, 84243.31513369575, 6943.722251109899, 7163.8567228656175, 7503.137139229058, 8241.217084376975, 8548.829023419372, 11095.5561777528, 12601.43293551774, 13308.268045257604, 13325.66685832862, 13420.127691700009, 5488.0, 11026.944865275622, 11250.774984500427, 11495.0, 11723.41382930833, 11384.260023098803, 11484.712882178012, 11585.0, 11753.353565553976, 11834.940825295818, 11780.823287650863, 11866.881331186578, 11877.287362758263, 11888.881111188777, 11894.226875253082, 8237.464564803508, 8342.249197572248, 8346.332293416634, 8361.83276334482, 8476.576171191538, 11447.370394628375, 11530.654080377602, 11609.245399049034, 11635.418229088342, 11881.465334059814], "value": 9856.871740718354, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1552.5342397280813, 1601.3114360824845, 1824.4253060286017, 1903.9904800475995, 1960.1571324330532, 8566.057733649293, 8783.560821958892, 8948.657701344802, 8963.59663815129, 8964.0, 7810.843783124335, 8046.994125734283, 8457.91542084579, 8693.69572064978, 8710.520921349324, 2314.7916687498123, 2342.0397636968846, 2348.1664009276715, 2348.8003519700846, 2350.0247397330086, 2303.57383883982, 2304.7925686688195, 2354.8822558872075, 2355.387599224197, 2357.3045951444365, 2057.5781964697276, 2112.6302896993043, 2117.4282943605217, 2142.689310050042, 2144.0566150893633, 1673.481220821543, 2330.3591512334074, 2330.7086614173227, 2334.054707843326, 2338.812894968407, 1886.9245230190777, 2261.525079733258, 2276.8747718875456, 2292.988535057314, 2300.332903457995, 7859.607019649052, 8584.613692383859, 8803.471791692518, 8904.376693631448, 8975.0, 5160.664556803796, 5323.574114070865, 5342.198670199501, 5344.171653393731, 5354.14333706609, 3539.557555305587, 3562.839672214731, 3631.5823680276576, 3635.781853088823, 3669.6330366963357, 1072.4584085037084, 1073.9194560408, 1077.3535878472933, 1078.0459293525207, 1094.518411898762, 11452.0, 11638.359890206035, 11638.767224655545, 11645.825312620318, 11648.301101933912, 3705.40713485841, 4519.909601807945, 4749.26386410107, 4753.405824271966, 4756.7859446325, 10030.64892728751, 10033.749156271006, 10050.195984321237, 10056.044675755791, 10056.597736090474, 5282.920756188661, 5301.575873930076, 5306.442823503554, 5312.282841816383, 5312.362516498045, 4771.880702982453, 4836.492168322346, 4875.049365373789, 4878.048780487842, 4878.341423907798, 3727.254549090193, 3769.321522125989, 3809.980950095232, 3850.4994350734223, 3850.6534411903062, 1191.874853140425, 1202.4949521201117, 1208.7703336366237, 1219.5426714981882, 1224.4857159992825, 800.3957012455559, 862.9946112778542, 876.5179151466668, 918.912703293186, 921.4793641592479, 4959.22283521222, 5672.631278966936, 5753.712314384347, 5899.439553242435, 5901.734421951022, 439.34098851722416, 441.18160811693843, 445.6548650656103, 457.4853290048695, 458.51168505541494, 3656.4149736042104, 3660.8834305537066, 3711.0536813112485, 3959.604039596075, 4238.724482908601, 10515.26393152472, 10545.156387488982, 10643.25497215203, 10756.731081723019, 10764.515596798163, 10683.465826708634, 10700.892991070144, 10703.946480267548, 10711.46442677863, 10781.02970732638, 7968.844517544923, 8042.155573664798, 8280.0, 8339.374546909006, 8372.916270837335, 5994.460498555151, 6053.0, 6136.907946380718, 6146.508279337553, 6205.162303089025, 8077.676892924217, 9966.551505182138, 9989.650362237287, 9999.650012249536, 10011.699649010541, 9697.22422206236, 10348.896511034793, 10355.275130740776, 10360.481975901024, 10366.429846358522, 7485.5508669480005, 7640.579768112585, 7655.157932627518, 7657.349125324187, 7662.080550333995, 8016.8797468036855, 8285.171482851667, 8301.626426810688, 8361.122082181526, 8386.412951093365, 7509.812254693457, 7568.5458872467825, 7605.011348524657, 7682.078150621961, 7707.997960265129, 7341.302576255247, 7417.369523590555, 7481.551106933602, 7510.812229694301, 7569.243075692387, 6776.864462710817, 6875.278095800069, 6962.0, 7040.119985001875, 7048.541844780073, 4910.607151427948, 4957.0, 5002.399712034579, 5004.349434573628, 5087.440381557952, 4760.571548560646, 4847.418309802846, 5037.271849228676, 5134.409542902614, 5177.611679124156], "value": 4461.334986806446, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.3921916946937, 310.0249714647434, 311.1924555777757, 316.2851954582641, 319.28320919535645, 5029.47190544993, 5197.896042079161, 5555.0, 5566.27638407007, 5621.100623900177, 4232.407462955189, 4792.736399498026, 5024.5729113025345, 5350.598705097113, 5523.585731070176, 3863.729538932276, 3977.389157391254, 4000.3399439092514, 4156.937645935313, 4292.120115376356, 3839.676995417774, 3989.321815291393, 4128.772917489537, 4175.812088456011, 4205.558416366283, 3431.5882094148737, 3922.941155882664, 4169.62473377396, 4174.123434078847, 4176.0, 3782.2813665403487, 3945.5265368156006, 3955.7230993830444, 4176.373543968414, 4187.748735075906, 3130.89041883533, 3566.5720113586276, 3602.883106237075, 3617.4212126059706, 3620.2984597239256, 4332.267093162729, 4365.934510982338, 5242.501962313574, 5374.978754036721, 5393.2449457076045, 4384.333953097835, 4419.116176764628, 4738.147133515966, 4804.063207674505, 4810.542998415145, 4965.553100220997, 5681.7727290908315, 5701.287339082614, 5754.510866576346, 5784.479396854304, 3076.6308043034755, 3326.95392333713, 3963.5243770747406, 4004.8798536043673, 4086.2849001424634, 5312.415634280226, 5807.274090738658, 5817.88364232713, 6106.847328816815, 6117.011971109363, 3387.7628566000144, 3571.8571257149943, 3792.2036372361563, 3848.5381754189116, 4076.408920706481, 4087.959120408758, 4114.300568903293, 4115.382692596135, 4116.567760385176, 4165.687573432005, 4424.646028317727, 4768.47546769855, 4778.259369797687, 4785.0, 4803.567678908845, 4972.801087956441, 5830.591858569859, 5835.416458354215, 5861.648301101947, 5934.643921364732, 1131.4569006876745, 1380.8400943207553, 1382.4884792626592, 1383.3567391163158, 1383.6540864783803, 692.9688164032631, 714.3999040805749, 827.0, 833.9499630022218, 850.6172222499899, 4977.303177555145, 5239.973800130975, 5295.099833028395, 5400.891982160413, 5401.730593310569, 4808.66779611888, 5963.314218864886, 5989.191459153046, 5994.400559943971, 6062.878742425127, 4786.7502273931195, 4818.734969576637, 5208.192730126836, 5421.620486565903, 5544.972275138599, 2609.386794103384, 3091.8405597900787, 3134.8275844828754, 3138.7959782614057, 3148.4572559446165, 5219.6868187908085, 6148.938510614926, 6152.446279834837, 6206.386339551749, 6240.188775459162, 5847.590668653153, 6089.177960975212, 6097.664628445389, 6120.867639486709, 6162.630242185483, 4645.0, 5165.845024649267, 6140.539459540552, 6157.907631385533, 6163.19878415812, 5151.407588127413, 5410.458954104558, 5648.63283886546, 5936.970315148396, 6001.519878409804, 5151.793928242978, 5414.025475414305, 5508.366537848038, 5960.5827592068135, 6014.8195554133445, 5459.836204913859, 6220.377962203744, 6283.2460104787715, 6292.1505596743855, 6297.0, 3698.3342998260573, 3907.27715372657, 3923.411488276667, 3925.704517509272, 3938.6061393861532, 5139.588832893434, 5808.6224395414165, 5886.499647530008, 5941.673207973603, 5998.490128338965, 5324.787008519771, 5447.891042179213, 5652.0, 5854.268216472941, 5864.266966629171, 4973.850784476472, 5072.391313042459, 5771.624844385102, 5772.163036359789, 5804.564657650778, 5100.464451232716, 5174.27560141588, 5460.808871689631, 5600.683839297843, 5746.534633668555, 4977.724682047807, 5333.306670132858, 5432.643806746038, 5747.48272655463, 5824.242848429677, 4669.696469729457, 5064.518870707277, 5356.196570514454, 5646.2942132233475, 5684.687342196219], "value": 4154.993219409582, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.4813652804701, 707.2255879811604, 807.3783186946056, 812.1675282835097, 848.2238751542337, 12730.872691273085, 16181.919090404532, 16207.756883646725, 16221.675566488679, 16231.675366492678, 12502.749945001116, 14779.704405911876, 14993.17537535435, 15012.399504019853, 15035.624109397271, 699.077218072145, 725.6843273176186, 882.4925667741043, 882.9381943263974, 922.3543519536317, 898.8112496375769, 902.62992173209, 902.7517432705993, 905.4341036851968, 906.6373450619739, 937.8265020971108, 959.0313783079071, 963.8216929867997, 968.2496065549185, 971.7279161834698, 750.8535835512077, 807.8384323135367, 956.8182045411419, 958.9136977672008, 959.7744530035505, 566.0659911146599, 567.7955935863087, 572.879695263995, 678.2471456683078, 706.3042902740797, 14593.9784215105, 14600.977931544796, 14625.0, 14647.926760366237, 14659.0, 13711.794323085162, 14075.085119467201, 14101.64745881351, 14104.717905641932, 14196.148231106166, 11193.888061119365, 11403.828942565868, 11885.465154068088, 11910.404479775976, 12834.679133021657, 4039.8788036358956, 4632.884177895546, 4639.280911458729, 4808.519148085164, 4811.879703007417, 103841.0, 104249.95750042555, 105143.0, 106330.4050439244, 107390.0, 10918.563257469612, 10952.726181845359, 11028.0, 11124.443777811077, 11165.83251251232, 21076.156953721988, 21087.367378978655, 21102.472438188863, 21109.0, 21109.050092745863, 7771.378289736807, 7836.686532538749, 7894.684212631545, 7927.286544210933, 7984.4810087345295, 3796.677282431024, 4326.697131200785, 4416.6025057744955, 4431.534688857688, 4521.163584736769, 3515.964840351615, 3617.5839778425293, 3618.4029635110437, 3618.8552457901387, 3622.166901612644, 675.537256978971, 687.6355531568277, 696.5124412910983, 741.255038686117, 752.2101793117205, 647.3429469088878, 688.6109348218241, 702.4332167571972, 705.5942832871017, 706.1420374245316, 9569.61721531145, 9628.47043412619, 9672.419654820733, 9763.511824408752, 9832.459214743258, 555.7665780372254, 622.5766478794372, 651.8402991267075, 661.7386132477644, 672.8082496488576, 3910.0, 4735.12400205963, 4836.032793441326, 4839.540243676845, 4845.53967373099, 105924.0, 120903.0, 150221.0, 157105.2144739269, 164025.17987410226, 139268.0, 140189.0, 141339.29330353282, 145245.0, 147761.2611936955, 116602.0, 117735.0, 118612.0, 123005.0, 123828.0, 9211.677591284273, 9300.11648893354, 9312.301577381588, 9338.859917101114, 9345.77580678032, 58457.415425845196, 59417.92037278674, 64339.56604339529, 67464.3253567458, 71003.64498177476, 52886.677833054484, 102434.48782756038, 107847.0, 108374.0, 109840.0, 12004.279743215435, 12143.939280303543, 12146.514139434325, 12202.938985305016, 12432.937835310766, 12486.43811028524, 13820.861791381958, 13960.58118256454, 14326.713465730836, 14349.35427905726, 12322.0, 12451.0, 13208.867911320764, 13220.603381898558, 13239.86760132425, 11186.216964812385, 12132.393380331125, 12580.685482862635, 12599.370031498573, 12953.935230323788, 12142.210756300812, 12205.267683938991, 12254.326012069421, 12279.9386003073, 12870.420831062438, 10536.736581585215, 10706.036456718934, 10895.56417743313, 10927.56289748433, 11142.665720028412, 9043.00526942049, 10675.679729608124, 10727.356358618508, 10936.343819370863, 10963.177761668067], "value": 7845.639682234001, "unit": "score"}], "name": "dromaeo_css", "value": 7395.28978055038, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 111.0, 112.0, 113.0, 113.0, 112.0, 112.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 107.0, 157.0, 108.0, 106.0, 125.0, 105.0, 167.0, 138.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 186.0, 184.0, 183.0, 191.0, 185.0, 182.0, 177.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 58.0, 91.0, 58.0, 59.0, 81.0, 87.0, 111.0, 59.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 91.0, 91.0, 70.0, 69.0, 67.0, 95.0, 70.0, 67.0, 99.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 74.0, 76.0, 117.0, 129.0, 88.0, 86.0, 87.0, 84.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 94.0, 112.0, 150.0, 97.0, 94.0, 94.0, 94.0, 93.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 94.0, 83.0, 85.0, 86.0, 84.0, 83.0, 83.0, 84.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 57.0, 56.0, 53.0, 53.0, 52.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 79.0, 77.0, 71.0, 70.0, 72.0, 69.0, 71.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 86.0, 88.0, 87.0, 80.0, 85.0, 82.0, 81.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 142.0, 152.0, 137.0, 133.0, 135.0, 136.0, 132.0, 177.0, 135.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 163.0, 165.0, 163.0, 163.0, 164.0, 166.0, 164.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 66.0, 64.0, 66.0, 66.0, 68.0, 61.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1444.1999999999998, "alertThreshold": 2.0}]} 05:40:08 INFO - Return code: 0 05:40:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:08 INFO - # TBPL SUCCESS # 05:40:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:40:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:40:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:40:08 INFO - 2016-07-08 05:40:08.965 screenresolution[1671:10225] starting screenresolution argv=screenresolution get 05:40:08 INFO - 2016-07-08 05:40:08.968 screenresolution[1671:10225] Display 0: 1600x1200x32@60 05:40:08 INFO - 2016-07-08 05:40:08.974 screenresolution[1672:10229] starting screenresolution argv=screenresolution list 05:40:08 INFO - Available Modes on Display 0 05:40:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:40:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:40:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:40:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:40:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:40:09 INFO - Intel Iris: 05:40:09 INFO - Chipset Model: Intel Iris 05:40:09 INFO - Type: GPU 05:40:09 INFO - Bus: Built-In 05:40:09 INFO - VRAM (Dynamic, Max): 1536 MB 05:40:09 INFO - Vendor: Intel (0x8086) 05:40:09 INFO - Device ID: 0x0a2e 05:40:09 INFO - Revision ID: 0x0009 05:40:09 INFO - Displays: 05:40:09 INFO - Display: 05:40:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:40:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:40:09 INFO - Display Serial Number: 1600x1200 60 05:40:09 INFO - Main Display: Yes 05:40:09 INFO - Mirror: Off 05:40:09 INFO - Online: Yes 05:40:09 INFO - Rotation: Supported 05:40:09 INFO - Return code: 0 05:40:09 INFO - Running post-action listener: _resource_record_post_action 05:40:09 INFO - ##### 05:40:09 INFO - ##### Finished run-tests step (success) 05:40:09 INFO - ##### 05:40:09 INFO - Running post-run listener: _resource_record_post_run 05:40:09 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32615936; Write bytes: 705302528; Read time: 316; Write time: 1873 05:40:09 INFO - TinderboxPrint: CPU usage
53.3% 05:40:09 INFO - TinderboxPrint: I/O read bytes / time
32,615,936 / 316 05:40:09 INFO - TinderboxPrint: I/O write bytes / time
705,302,528 / 1,873 05:40:09 INFO - TinderboxPrint: CPU idle
2,261.0 (46.7%) 05:40:09 INFO - TinderboxPrint: CPU system
964.8 (19.9%) 05:40:09 INFO - TinderboxPrint: CPU user
1,619.8 (33.4%) 05:40:09 INFO - TinderboxPrint: Swap in / out
435,556,352 / 0 05:40:09 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396653056; Write bytes: 390555136; Read time: 29242; Write time: 740 05:40:09 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30739968; Write bytes: 295074304; Read time: 296; Write time: 1091 05:40:09 INFO - Running post-run listener: _upload_blobber_files 05:40:09 INFO - Blob upload gear active. 05:40:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:09 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:40:09 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:40:09 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:40:10 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:40:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:40:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:11 INFO - (blobuploader) - INFO - Done attempting. 05:40:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:40:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:12 INFO - (blobuploader) - INFO - Done attempting. 05:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:14 INFO - (blobuploader) - INFO - Done attempting. 05:40:14 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:14 INFO - Return code: 0 05:40:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6ff935fa46c7a360c884301da9f02af196abbbdc2c218225cceee361ad5b1144c119622be1b282deb14f62532272c54b5d29e9615420c7a3d23925867a959c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a71ba479eb5efa219fee17f4fa720ce3d731c62ff5a2a772a597d80c0eaead26bc22ffb25605e9cda78a30e73825c35137f63fa0678dfb877e8997ba891eb206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2504888593a3c7624b1beec3ac98c2f891a53aff9832f3d234aa5f244e428b0d074a1e06752455ca6072842d6fdf0e2e9257eb2e8520d35b29724345940ea9f5"} 05:40:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:14 INFO - Contents: 05:40:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6ff935fa46c7a360c884301da9f02af196abbbdc2c218225cceee361ad5b1144c119622be1b282deb14f62532272c54b5d29e9615420c7a3d23925867a959c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a71ba479eb5efa219fee17f4fa720ce3d731c62ff5a2a772a597d80c0eaead26bc22ffb25605e9cda78a30e73825c35137f63fa0678dfb877e8997ba891eb206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2504888593a3c7624b1beec3ac98c2f891a53aff9832f3d234aa5f244e428b0d074a1e06752455ca6072842d6fdf0e2e9257eb2e8520d35b29724345940ea9f5"} 05:40:14 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:14 INFO - Copying logs to upload dir... 05:40:14 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.440052 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-08 05:40:14.689968) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 05:40:14.693093) ========= 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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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/d6ff935fa46c7a360c884301da9f02af196abbbdc2c218225cceee361ad5b1144c119622be1b282deb14f62532272c54b5d29e9615420c7a3d23925867a959c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a71ba479eb5efa219fee17f4fa720ce3d731c62ff5a2a772a597d80c0eaead26bc22ffb25605e9cda78a30e73825c35137f63fa0678dfb877e8997ba891eb206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2504888593a3c7624b1beec3ac98c2f891a53aff9832f3d234aa5f244e428b0d074a1e06752455ca6072842d6fdf0e2e9257eb2e8520d35b29724345940ea9f5"} build_url:https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/Q3qwy40-TIOfmz7j8k1ODA/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/d6ff935fa46c7a360c884301da9f02af196abbbdc2c218225cceee361ad5b1144c119622be1b282deb14f62532272c54b5d29e9615420c7a3d23925867a959c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a71ba479eb5efa219fee17f4fa720ce3d731c62ff5a2a772a597d80c0eaead26bc22ffb25605e9cda78a30e73825c35137f63fa0678dfb877e8997ba891eb206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2504888593a3c7624b1beec3ac98c2f891a53aff9832f3d234aa5f244e428b0d074a1e06752455ca6072842d6fdf0e2e9257eb2e8520d35b29724345940ea9f5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 05:40:14.729663) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:40:14.730072) ========= 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.6jgXNYhPzh/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.nK5xqMqTAM/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.005195 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:40:14.779841) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 05:40:14.780182) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 05:40:14.780520) ========= ========= Total master_lag: 0.32 =========