builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0202 starttime: 1467894749.61 results: success (0) buildid: 20160707030220 builduid: 5f13b9721540481194abec6eabaaa511 revision: 4764b9f8e6d4ef9823237f01ca3901759ce8daeb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:29.612612) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:29.613057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:29.613331) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:29.645688) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:29.646029) ========= 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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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-07 05:32:29-- 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% 16.7M=0.001s 2016-07-07 05:32:30 (16.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.454101 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:30.284133) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:30.284406) ========= 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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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.025812 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:30.336072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 05:32:30.336376) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4764b9f8e6d4ef9823237f01ca3901759ce8daeb --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 4764b9f8e6d4ef9823237f01ca3901759ce8daeb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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-07 05:32:30,415 truncating revision to first 12 chars 2016-07-07 05:32:30,415 Setting DEBUG logging. 2016-07-07 05:32:30,415 attempt 1/10 2016-07-07 05:32:30,415 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4764b9f8e6d4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 05:32:31,449 unpacking tar archive at: mozilla-central-4764b9f8e6d4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.318947 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 05:32:31.696486) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:31.696829) ========= script_repo_revision: 4764b9f8e6d4ef9823237f01ca3901759ce8daeb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:31.697213) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:31.697480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 05:32:31.718861) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-07-07 05:32:31.719186) ========= /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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:32:31 INFO - MultiFileLogger online at 20160707 05:32:31 in /builds/slave/test 05:32:31 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:32:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:32:31 INFO - {'append_to_log': False, 05:32:31 INFO - 'base_work_dir': '/builds/slave/test', 05:32:31 INFO - 'blob_upload_branch': 'Firefox', 05:32:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:32:31 INFO - 'branch': 'Firefox', 05:32:31 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:31 INFO - 'config_files': ('talos/mac_config.py',), 05:32:31 INFO - 'default_actions': ('clobber', 05:32:31 INFO - 'read-buildbot-config', 05:32:31 INFO - 'download-and-extract', 05:32:31 INFO - 'populate-webroot', 05:32:31 INFO - 'create-virtualenv', 05:32:31 INFO - 'install', 05:32:31 INFO - 'run-tests'), 05:32:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:31 INFO - 'download_minidump_stackwalk': True, 05:32:31 INFO - 'download_symbols': 'ondemand', 05:32:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:32:31 INFO - 'tooltool.py': '/tools/tooltool.py', 05:32:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:32:31 INFO - '/tools/misc-python/virtualenv.py')}, 05:32:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:31 INFO - 'installer_path': 'installer.exe', 05:32:31 INFO - 'log_level': 'info', 05:32:31 INFO - 'log_name': 'talos', 05:32:31 INFO - 'log_to_console': True, 05:32:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:32:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:32:31 INFO - 'opt_config_files': (), 05:32:31 INFO - 'pip_index': False, 05:32:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:32:31 INFO - 'cmd': ('bash', 05:32:31 INFO - '-c', 05:32:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:32:31 INFO - 'enabled': True, 05:32:31 INFO - 'halt_on_failure': False, 05:32:31 INFO - 'name': 'check_screen_resolution'},), 05:32:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:32:31 INFO - 'cmd': ('bash', 05:32:31 INFO - '-c', 05:32:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:32:31 INFO - 'enabled': True, 05:32:31 INFO - 'halt_on_failure': False, 05:32:31 INFO - 'name': 'check_screen_resolution'},), 05:32:31 INFO - 'run_cmd_checks_enabled': True, 05:32:31 INFO - 'sps_profile': False, 05:32:31 INFO - 'sps_profile_interval': 0, 05:32:31 INFO - 'suite': 'dromaeojs', 05:32:31 INFO - 'system_bits': '32', 05:32:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:31 INFO - 'title': 't-yosemite-r7-0202', 05:32:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:31 INFO - 'use_talos_json': True, 05:32:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:32:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:31 INFO - 'work_dir': 'build'} 05:32:31 INFO - ##### 05:32:31 INFO - ##### Running clobber step. 05:32:31 INFO - ##### 05:32:31 INFO - Running pre-action listener: _resource_record_pre_action 05:32:31 INFO - Running main action method: clobber 05:32:31 INFO - rmtree: /builds/slave/test/build 05:32:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:32:33 INFO - Running post-action listener: _resource_record_post_action 05:32:33 INFO - ##### 05:32:33 INFO - ##### Finished clobber step (success) 05:32:33 INFO - ##### 05:32:33 INFO - ##### 05:32:33 INFO - ##### Running read-buildbot-config step. 05:32:33 INFO - ##### 05:32:33 INFO - Running pre-action listener: _resource_record_pre_action 05:32:33 INFO - Running main action method: read_buildbot_config 05:32:33 INFO - Using buildbot properties: 05:32:33 INFO - { 05:32:33 INFO - "project": "", 05:32:33 INFO - "product": "firefox", 05:32:33 INFO - "script_repo_revision": "production", 05:32:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:32:33 INFO - "repository": "", 05:32:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:32:33 INFO - "buildid": "20160707030220", 05:32:33 INFO - "stage_platform": "macosx64", 05:32:33 INFO - "basedir": "/builds/slave/test", 05:32:33 INFO - "nightly_build": "True", 05:32:33 INFO - "buildnumber": 6, 05:32:33 INFO - "slavename": "t-yosemite-r7-0202", 05:32:33 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:32:33 INFO - "platform": "yosemite_r7", 05:32:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:32:33 INFO - "branch": "mozilla-central", 05:32:33 INFO - "repo_path": "mozilla-central", 05:32:33 INFO - "revision": "4764b9f8e6d4ef9823237f01ca3901759ce8daeb", 05:32:33 INFO - "pgo_build": "False", 05:32:33 INFO - "builduid": "5f13b9721540481194abec6eabaaa511", 05:32:33 INFO - "slavebuilddir": "test" 05:32:33 INFO - } 05:32:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:32:33 INFO - Running post-action listener: _resource_record_post_action 05:32:33 INFO - ##### 05:32:33 INFO - ##### Finished read-buildbot-config step (success) 05:32:33 INFO - ##### 05:32:33 INFO - ##### 05:32:33 INFO - ##### Running download-and-extract step. 05:32:33 INFO - ##### 05:32:33 INFO - Running pre-action listener: _resource_record_pre_action 05:32:33 INFO - Running main action method: download_and_extract 05:32:33 INFO - mkdir: /builds/slave/test/build/tests 05:32:33 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:32:33 INFO - https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:32:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:32:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:32:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:32:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:03 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json: timed out 05:33:03 INFO - retry: attempt #1 caught exception: timed out 05:33:03 INFO - retry: Failed, sleeping 30 seconds before retrying 05:33:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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 #2 05:33:34 INFO - Downloaded 1199 bytes. 05:33:34 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:33:34 INFO - Using the following test package requirements: 05:33:34 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:33:34 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:33:34 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:33:34 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'jsshell-mac.zip'], 05:33:34 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:33:34 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:33:34 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:33:34 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:33:34 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:33:34 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:33:34 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:33:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:33:34 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:33:34 INFO - https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:33:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:33:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:33:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:35 INFO - Downloaded 18806348 bytes. 05:33:35 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:33:35 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:33:38 INFO - Return code: 0 05:33:38 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:33: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:33:38 INFO - https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:33:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:33:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:33:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:39 INFO - Downloaded 18806348 bytes. 05:33:39 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:33:39 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:33:43 INFO - Return code: 0 05:33:43 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:33:43 INFO - https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:33:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:33:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:33:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:44 INFO - Downloaded 14403333 bytes. 05:33:44 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:33:44 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:33:44 INFO - Return code: 0 05:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:44 INFO - https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:33:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:33:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:33:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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:33:47 INFO - Downloaded 112557371 bytes. 05:33:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:33:47 INFO - mkdir: /builds/slave/test/properties 05:33:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:33:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:33:47 INFO - Contents: 05:33:47 INFO - build_url:https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:33:48 INFO - Running post-action listener: _resource_record_post_action 05:33:48 INFO - Running post-action listener: set_extra_try_arguments 05:33:48 INFO - ##### 05:33:48 INFO - ##### Finished download-and-extract step (success) 05:33:48 INFO - ##### 05:33:48 INFO - ##### 05:33:48 INFO - ##### Running populate-webroot step. 05:33:48 INFO - ##### 05:33:48 INFO - Running pre-action listener: _resource_record_pre_action 05:33:48 INFO - Running main action method: populate_webroot 05:33:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:33:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:33:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:33:48 INFO - '--tppagecycles', 05:33:48 INFO - '7'], 05:33:48 INFO - 'tests': ['tsvgm']}}, 05:33:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:33:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:33:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:33:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:33:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['damp', 'tps']}, 05:33:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'tests': ['damp', 'tps']}, 05:33:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['dromaeo_dom']}, 05:33:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:33:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['basic_compositor_video']}, 05:33:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:33:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['a11yr', 05:33:48 INFO - 'ts_paint', 05:33:48 INFO - 'tpaint', 05:33:48 INFO - 'sessionrestore', 05:33:48 INFO - 'sessionrestore_no_auto_restore', 05:33:48 INFO - 'tabpaint']}, 05:33:48 INFO - 'other-e10s': {'tests': ['a11yr', 05:33:48 INFO - 'ts_paint', 05:33:48 INFO - 'tpaint', 05:33:48 INFO - 'sessionrestore', 05:33:48 INFO - 'sessionrestore_no_auto_restore', 05:33:48 INFO - 'tabpaint']}, 05:33:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:33:48 INFO - 'ts_paint', 05:33:48 INFO - 'tpaint', 05:33:48 INFO - 'sessionrestore', 05:33:48 INFO - 'sessionrestore_no_auto_restore', 05:33:48 INFO - 'tabpaint']}, 05:33:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:33:48 INFO - 'ts_paint', 05:33:48 INFO - 'tpaint', 05:33:48 INFO - 'sessionrestore', 05:33:48 INFO - 'sessionrestore_no_auto_restore', 05:33:48 INFO - 'tabpaint']}, 05:33:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['a11yr', 05:33:48 INFO - 'ts_paint', 05:33:48 INFO - 'tpaint', 05:33:48 INFO - 'sessionrestore', 05:33:48 INFO - 'sessionrestore_no_auto_restore', 05:33:48 INFO - 'tabpaint']}, 05:33:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['a11yr', 05:33:48 INFO - 'ts_paint', 05:33:48 INFO - 'tpaint', 05:33:48 INFO - 'sessionrestore', 05:33:48 INFO - 'sessionrestore_no_auto_restore', 05:33:48 INFO - 'tabpaint']}, 05:33:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['tsvgx', 05:33:48 INFO - 'tsvgr_opacity', 05:33:48 INFO - 'tart', 05:33:48 INFO - 'tscrollx', 05:33:48 INFO - 'cart']}, 05:33:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:33:48 INFO - 'tsvgr_opacity', 05:33:48 INFO - 'tart', 05:33:48 INFO - 'tscrollx', 05:33:48 INFO - 'cart']}, 05:33:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'talos_options': ['--disable-e10s'], 05:33:48 INFO - 'tests': ['tp5o']}, 05:33:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'tests': ['tp5o']}, 05:33:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'talos_options': ['--disable-e10s', 05:33:48 INFO - '--xperf_path', 05:33:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:48 INFO - 'tests': ['tp5n']}, 05:33:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:48 INFO - 'talos_options': ['--xperf_path', 05:33:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:48 INFO - 'tests': ['tp5n']}}, 05:33:48 INFO - 'talos.zip': {'path': '', 05:33:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:33:48 INFO - Running post-action listener: _resource_record_post_action 05:33:48 INFO - ##### 05:33:48 INFO - ##### Finished populate-webroot step (success) 05:33:48 INFO - ##### 05:33:48 INFO - ##### 05:33:48 INFO - ##### Running create-virtualenv step. 05:33:48 INFO - ##### 05:33:48 INFO - Running pre-action listener: _resource_record_pre_action 05:33:48 INFO - Running main action method: create_virtualenv 05:33:48 INFO - Creating virtualenv /builds/slave/test/build/venv 05:33:48 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:33:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:33:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:33:48 INFO - Using real prefix '/tools/python27' 05:33:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:33:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:33:52 INFO - Installing pip.................done. 05:33:52 INFO - Return code: 0 05:33:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:33:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:52 INFO - 'HOME': '/Users/cltbld', 05:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:52 INFO - 'LOGNAME': 'cltbld', 05:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:52 INFO - 'MOZ_NO_REMOTE': '1', 05:33:52 INFO - 'NO_EM_RESTART': '1', 05:33:52 INFO - 'PAGER': '/bin/cat', 05:33:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:52 INFO - 'PWD': '/builds/slave/test', 05:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:52 INFO - 'SHELL': '/bin/bash', 05:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:33:52 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:52 INFO - 'USER': 'cltbld', 05:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:52 INFO - 'XPC_FLAGS': '0x0', 05:33:52 INFO - 'XPC_SERVICE_NAME': '0', 05:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:52 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:33:52 INFO - Cleaning up... 05:33:52 INFO - Return code: 0 05:33:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:33:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:52 INFO - 'HOME': '/Users/cltbld', 05:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:52 INFO - 'LOGNAME': 'cltbld', 05:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:52 INFO - 'MOZ_NO_REMOTE': '1', 05:33:52 INFO - 'NO_EM_RESTART': '1', 05:33:52 INFO - 'PAGER': '/bin/cat', 05:33:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:52 INFO - 'PWD': '/builds/slave/test', 05:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:52 INFO - 'SHELL': '/bin/bash', 05:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:33:52 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:52 INFO - 'USER': 'cltbld', 05:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:52 INFO - 'XPC_FLAGS': '0x0', 05:33:52 INFO - 'XPC_SERVICE_NAME': '0', 05:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:52 INFO - Downloading/unpacking psutil>=3.1.1 05:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:33:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:56 INFO - Installing collected packages: psutil 05:33:56 INFO - Running setup.py install for psutil 05:33:56 INFO - building 'psutil._psutil_osx' extension 05:33:56 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:33:57 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:33:57 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:33:57 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:33:57 INFO - building 'psutil._psutil_posix' extension 05:33:57 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:33:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:33:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:33:57 INFO - ^ 05:33:57 INFO - 1 warning generated. 05:33:57 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:33:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:57 INFO - Successfully installed psutil 05:33:57 INFO - Cleaning up... 05:33:57 INFO - Return code: 0 05:33:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:33:57 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:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:57 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:33:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:57 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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:33:57 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:33:57 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:33:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:33:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:57 INFO - 'HOME': '/Users/cltbld', 05:33:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:57 INFO - 'LOGNAME': 'cltbld', 05:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:57 INFO - 'MOZ_NO_REMOTE': '1', 05:33:57 INFO - 'NO_EM_RESTART': '1', 05:33:57 INFO - 'PAGER': '/bin/cat', 05:33:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:57 INFO - 'PWD': '/builds/slave/test', 05:33:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:57 INFO - 'SHELL': '/bin/bash', 05:33:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:33:57 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:33:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:57 INFO - 'USER': 'cltbld', 05:33:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:57 INFO - 'XPC_FLAGS': '0x0', 05:33:57 INFO - 'XPC_SERVICE_NAME': '0', 05:33:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:34:00 INFO - Installing collected packages: mozsystemmonitor 05:34:00 INFO - Running setup.py install for mozsystemmonitor 05:34:00 INFO - Successfully installed mozsystemmonitor 05:34:00 INFO - Cleaning up... 05:34:00 INFO - Return code: 0 05:34:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:00 INFO - 'HOME': '/Users/cltbld', 05:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:00 INFO - 'LOGNAME': 'cltbld', 05:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:00 INFO - 'MOZ_NO_REMOTE': '1', 05:34:00 INFO - 'NO_EM_RESTART': '1', 05:34:00 INFO - 'PAGER': '/bin/cat', 05:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:00 INFO - 'PWD': '/builds/slave/test', 05:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:00 INFO - 'SHELL': '/bin/bash', 05:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:34:00 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:00 INFO - 'USER': 'cltbld', 05:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:00 INFO - 'XPC_FLAGS': '0x0', 05:34:00 INFO - 'XPC_SERVICE_NAME': '0', 05:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:34:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:34:03 INFO - warning: no files found matching '*.txt' 05:34:03 INFO - no previously-included directories found matching 'build' 05:34:03 INFO - no previously-included directories found matching 'dist' 05:34:03 INFO - no previously-included directories found matching '.git*' 05:34:03 INFO - Installing collected packages: jsonschema, functools32 05:34:03 INFO - Running setup.py install for functools32 05:34:03 INFO - warning: no files found matching '*.txt' 05:34:03 INFO - no previously-included directories found matching 'build' 05:34:03 INFO - no previously-included directories found matching 'dist' 05:34:03 INFO - no previously-included directories found matching '.git*' 05:34:03 INFO - Successfully installed jsonschema functools32 05:34:03 INFO - Cleaning up... 05:34:04 INFO - Return code: 0 05:34:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:34:04 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:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:04 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:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:04 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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:34:04 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:34:04 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:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:04 INFO - 'HOME': '/Users/cltbld', 05:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:04 INFO - 'LOGNAME': 'cltbld', 05:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:04 INFO - 'MOZ_NO_REMOTE': '1', 05:34:04 INFO - 'NO_EM_RESTART': '1', 05:34:04 INFO - 'PAGER': '/bin/cat', 05:34:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:04 INFO - 'PWD': '/builds/slave/test', 05:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:04 INFO - 'SHELL': '/bin/bash', 05:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:34:04 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:04 INFO - 'USER': 'cltbld', 05:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:04 INFO - 'XPC_FLAGS': '0x0', 05:34:04 INFO - 'XPC_SERVICE_NAME': '0', 05:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:34:04 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:34:04 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:34:04 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:34:04 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:34:04 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:34:04 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:34:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:34:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:34:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:34:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 INFO - Downloading docopt-0.6.1.tar.gz 05:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:34:08 INFO - Installing collected packages: blobuploader, requests, docopt 05:34:08 INFO - Running setup.py install for blobuploader 05:34:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:34:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:34:08 INFO - Running setup.py install for requests 05:34:08 INFO - Running setup.py install for docopt 05:34:08 INFO - Successfully installed blobuploader requests docopt 05:34:08 INFO - Cleaning up... 05:34:08 INFO - Return code: 0 05:34:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:08 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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:34:08 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:34:08 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:34:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:34:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:08 INFO - 'HOME': '/Users/cltbld', 05:34:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:08 INFO - 'LOGNAME': 'cltbld', 05:34:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:08 INFO - 'MOZ_NO_REMOTE': '1', 05:34:08 INFO - 'NO_EM_RESTART': '1', 05:34:08 INFO - 'PAGER': '/bin/cat', 05:34:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:08 INFO - 'PWD': '/builds/slave/test', 05:34:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:08 INFO - 'SHELL': '/bin/bash', 05:34:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:34:08 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:34:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:08 INFO - 'USER': 'cltbld', 05:34:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:08 INFO - 'XPC_FLAGS': '0x0', 05:34:08 INFO - 'XPC_SERVICE_NAME': '0', 05:34:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-kWwQwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-umWonx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-xmMoDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-NNqx4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-4JCLm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-laNLUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-BA9HMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:34:09 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-jDee7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-9QqvKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-T11tco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-9jVPEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-LL5d9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-XsZiQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-3bO_v5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-ZfCGn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-UHMCei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:34:10 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-Xz0c4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:34:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:34:10 INFO - Running setup.py install for manifestparser 05:34:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Running setup.py install for mozcrash 05:34:11 INFO - Running setup.py install for mozdebug 05:34:11 INFO - Running setup.py install for mozdevice 05:34:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Running setup.py install for mozfile 05:34:11 INFO - Running setup.py install for mozhttpd 05:34:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Running setup.py install for mozinfo 05:34:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Running setup.py install for mozInstall 05:34:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Running setup.py install for mozleak 05:34:12 INFO - Running setup.py install for mozlog 05:34:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Running setup.py install for moznetwork 05:34:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Running setup.py install for mozprocess 05:34:12 INFO - Running setup.py install for mozprofile 05:34:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Running setup.py install for mozrunner 05:34:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:34:13 INFO - Running setup.py install for mozscreenshot 05:34:13 INFO - Running setup.py install for moztest 05:34:13 INFO - Running setup.py install for mozversion 05:34:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:34:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:34:13 INFO - Cleaning up... 05:34:13 INFO - Return code: 0 05:34:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:34:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:13 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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:34:13 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:34:13 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:34:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:34:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:13 INFO - 'HOME': '/Users/cltbld', 05:34:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:13 INFO - 'LOGNAME': 'cltbld', 05:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:13 INFO - 'MOZ_NO_REMOTE': '1', 05:34:13 INFO - 'NO_EM_RESTART': '1', 05:34:13 INFO - 'PAGER': '/bin/cat', 05:34:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:13 INFO - 'PWD': '/builds/slave/test', 05:34:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:13 INFO - 'SHELL': '/bin/bash', 05:34:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:34:13 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:34:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:13 INFO - 'USER': 'cltbld', 05:34:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:13 INFO - 'XPC_FLAGS': '0x0', 05:34:13 INFO - 'XPC_SERVICE_NAME': '0', 05:34:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:34:13 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-YVgoLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:34:13 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-3XWqPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-YTmqdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-nX6fiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-l38eih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-z0e4Gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-yA5WuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-J_9m60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-h8IN7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-fFyUEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:34:14 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-8rolWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:34:15 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:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:34:15 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-A5N_tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:34:15 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:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:34:15 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-lhfzw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:34:15 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:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:34:15 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-SeKnOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:34:15 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:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:15 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-Zqa6NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:15 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:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:34:15 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-sPeW1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:34:15 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:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:34:15 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-keiKqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:34:15 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:34:15 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:34:15 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:34:15 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:34:15 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:34:15 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:34:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:18 INFO - Downloading blessings-1.6.tar.gz 05:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:34:18 INFO - Installing collected packages: blessings 05:34:18 INFO - Running setup.py install for blessings 05:34:18 INFO - Successfully installed blessings 05:34:18 INFO - Cleaning up... 05:34:18 INFO - Return code: 0 05:34:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:34:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:34:18 INFO - Reading from file tmpfile_stdout 05:34:18 INFO - Current package versions: 05:34:18 INFO - blessings == 1.6 05:34:18 INFO - blobuploader == 1.2.4 05:34:18 INFO - docopt == 0.6.1 05:34:18 INFO - functools32 == 3.2.3-2 05:34:18 INFO - jsonschema == 2.5.1 05:34:18 INFO - manifestparser == 1.1 05:34:18 INFO - mozInstall == 1.12 05:34:18 INFO - mozcrash == 0.17 05:34:18 INFO - mozdebug == 0.1 05:34:18 INFO - mozdevice == 0.48 05:34:18 INFO - mozfile == 1.2 05:34:18 INFO - mozhttpd == 0.7 05:34:18 INFO - mozinfo == 0.9 05:34:18 INFO - mozleak == 0.1 05:34:18 INFO - mozlog == 3.2 05:34:18 INFO - moznetwork == 0.27 05:34:18 INFO - mozprocess == 0.23 05:34:18 INFO - mozprofile == 0.28 05:34:18 INFO - mozrunner == 6.12 05:34:18 INFO - mozscreenshot == 0.1 05:34:18 INFO - mozsystemmonitor == 0.3 05:34:18 INFO - moztest == 0.7 05:34:18 INFO - mozversion == 1.4 05:34:18 INFO - psutil == 3.1.1 05:34:18 INFO - requests == 1.2.3 05:34:18 INFO - wsgiref == 0.1.2 05:34:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:34:18 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:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:18 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:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:18 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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:34:18 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:34:18 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:34:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:34:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:18 INFO - 'HOME': '/Users/cltbld', 05:34:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:18 INFO - 'LOGNAME': 'cltbld', 05:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:18 INFO - 'MOZ_NO_REMOTE': '1', 05:34:18 INFO - 'NO_EM_RESTART': '1', 05:34:18 INFO - 'PAGER': '/bin/cat', 05:34:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:18 INFO - 'PWD': '/builds/slave/test', 05:34:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:18 INFO - 'SHELL': '/bin/bash', 05:34:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:34:18 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:34:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:18 INFO - 'USER': 'cltbld', 05:34:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:18 INFO - 'XPC_FLAGS': '0x0', 05:34:18 INFO - 'XPC_SERVICE_NAME': '0', 05:34:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 INFO - Cleaning up... 05:34:18 INFO - Return code: 0 05:34:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:34:18 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:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:18 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:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:18 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 0x10d482a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d517618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04ad6a350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, '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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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:34:18 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:34:18 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:34:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:34:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:18 INFO - 'HOME': '/Users/cltbld', 05:34:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:18 INFO - 'LOGNAME': 'cltbld', 05:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:18 INFO - 'MOZ_NO_REMOTE': '1', 05:34:18 INFO - 'NO_EM_RESTART': '1', 05:34:18 INFO - 'PAGER': '/bin/cat', 05:34:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:18 INFO - 'PWD': '/builds/slave/test', 05:34:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:18 INFO - 'SHELL': '/bin/bash', 05:34:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:34:18 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:34:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:18 INFO - 'USER': 'cltbld', 05:34:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:18 INFO - 'XPC_FLAGS': '0x0', 05:34:18 INFO - 'XPC_SERVICE_NAME': '0', 05:34:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:34:19 INFO - Cleaning up... 05:34:19 INFO - Return code: 0 05:34:19 INFO - Running post-action listener: _resource_record_post_action 05:34:19 INFO - Running post-action listener: _start_resource_monitoring 05:34:19 INFO - Starting resource monitoring. 05:34:19 INFO - ##### 05:34:19 INFO - ##### Finished create-virtualenv step (success) 05:34:19 INFO - ##### 05:34:19 INFO - ##### 05:34:19 INFO - ##### Running install step. 05:34:19 INFO - ##### 05:34:19 INFO - Running pre-action listener: _resource_record_pre_action 05:34:19 INFO - Running main action method: install 05:34:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:34:19 INFO - Reading from file tmpfile_stdout 05:34:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:34:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:34:19 INFO - Reading from file tmpfile_stdout 05:34:19 INFO - Output received: 05:34:19 INFO - Usage: mozinstall [options] installer 05:34:19 INFO - Options: 05:34:19 INFO - -h, --help show this help message and exit 05:34:19 INFO - -d DEST, --destination=DEST 05:34:19 INFO - Directory to install application into. [default: 05:34:19 INFO - "/builds/slave/test"] 05:34:19 INFO - --app=APP Application being installed. [default: firefox] 05:34:19 INFO - mkdir: /builds/slave/test/build/application 05:34:19 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:34:19 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:34:54 INFO - Reading from file tmpfile_stdout 05:34:54 INFO - Output received: 05:34:54 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:34:54 INFO - Running post-action listener: _resource_record_post_action 05:34:54 INFO - ##### 05:34:54 INFO - ##### Finished install step (success) 05:34:54 INFO - ##### 05:34:54 INFO - ##### 05:34:54 INFO - ##### Running run-tests step. 05:34:54 INFO - ##### 05:34:54 INFO - Running pre-action listener: _resource_record_pre_action 05:34:54 INFO - Running main action method: run_tests 05:34:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:54 INFO - 2016-07-07 05:34:54.124 screenresolution[1641:7956] starting screenresolution argv=screenresolution get 05:34:54 INFO - 2016-07-07 05:34:54.127 screenresolution[1641:7956] Display 0: 1600x1200x32@60 05:34:54 INFO - 2016-07-07 05:34:54.132 screenresolution[1642:7958] starting screenresolution argv=screenresolution list 05:34:54 INFO - Available Modes on Display 0 05:34:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:34:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:34:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:34:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:34:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:34:54 INFO - Intel Iris: 05:34:54 INFO - Chipset Model: Intel Iris 05:34:54 INFO - Type: GPU 05:34:54 INFO - Bus: Built-In 05:34:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:34:54 INFO - Vendor: Intel (0x8086) 05:34:54 INFO - Device ID: 0x0a2e 05:34:54 INFO - Revision ID: 0x0009 05:34:54 INFO - Displays: 05:34:54 INFO - Display: 05:34:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:34:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:34:54 INFO - Display Serial Number: 1600x1200 60 05:34:54 INFO - Main Display: Yes 05:34:54 INFO - Mirror: Off 05:34:54 INFO - Online: Yes 05:34:54 INFO - Rotation: Supported 05:34:54 INFO - Return code: 0 05:34:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:34:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:34:54 INFO - Python 2.7.3 05:34:54 INFO - Return code: 0 05:34:54 INFO - grabbing minidump binary from tooltool 05:34:54 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:34:54 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 0x10d3e6d30>, '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 0x10d14bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3deb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:34:54 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:34:54 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:34:54 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:34:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:34:54 INFO - Return code: 0 05:34:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:34:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:34:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:34:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:34:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:34:54 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0202', '--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:34:54 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0202 --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:34:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6IuLcuDRkL/Render', 05:34:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:54 INFO - 'HOME': '/Users/cltbld', 05:34:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:54 INFO - 'LOGNAME': 'cltbld', 05:34:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:34:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:34:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:54 INFO - 'MOZ_NO_REMOTE': '1', 05:34:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:34:54 INFO - 'NO_EM_RESTART': '1', 05:34:54 INFO - 'PAGER': '/bin/cat', 05:34:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:54 INFO - 'PWD': '/builds/slave/test', 05:34:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:34:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:54 INFO - 'SHELL': '/bin/bash', 05:34:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zUUN4XQ2Rr/Listeners', 05:34:54 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 05:34:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:54 INFO - 'USER': 'cltbld', 05:34:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:54 INFO - 'XPC_FLAGS': '0x0', 05:34:54 INFO - 'XPC_SERVICE_NAME': '0', 05:34:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:54 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0202', '--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:34:54 INFO - mozversion application_buildid: 20160707030220 05:34:54 INFO - mozversion application_changeset: 4764b9f8e6d4ef9823237f01ca3901759ce8daeb 05:34:54 INFO - mozversion application_display_name: Nightly 05:34:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:34:54 INFO - mozversion application_name: Firefox 05:34:54 INFO - mozversion application_remotingname: firefox 05:34:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:34:54 INFO - mozversion application_vendor: Mozilla 05:34:54 INFO - mozversion application_version: 50.0a1 05:34:54 INFO - mozversion platform_buildid: 20160707030220 05:34:54 INFO - mozversion platform_changeset: 4764b9f8e6d4ef9823237f01ca3901759ce8daeb 05:34:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:34:54 INFO - mozversion platform_version: 50.0a1 05:34:54 INFO - using testdate: 1467894894 05:34:54 INFO - actual date: 1467894894 05:34:54 INFO - starting webserver on 'localhost:49272' 05:34:54 INFO - SUITE-START | Running 2 tests 05:34:54 INFO - TEST-START | dromaeo_css 05:34:54 INFO - operating with platform_type : mac_ 05:34:54 INFO - Initialising browser for dromaeo_css test... 05:34:54 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmpOnXwyj/profile http://localhost:49272/getInfo.html) 05:34:57 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 05:34:57 INFO - PROCESS | 1652 | colorDepth:24 05:34:57 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 05:34:57 INFO - PROCESS | 1652 | __metrics 05:34:57 INFO - PROCESS | 1652 | 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:34:57 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:34:57 INFO - TEST-INFO | 1652: exit 0 05:34:57 INFO - Browser initialized. 05:34:57 INFO - Running cycle 1/1 for dromaeo_css test... 05:34:57 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmpOnXwyj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:35:09 INFO - PROCESS | 1656 | RSS: Main: 243642368 05:35:09 INFO - PROCESS | 1656 | 05:36:01 INFO - PROCESS | 1656 | 1467894961921 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:38:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 05:38:13 INFO - PROCESS | 1656 | RSS: Main: 458899456 05:38:13 INFO - PROCESS | 1656 | 05:41:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 05:41:17 INFO - PROCESS | 1656 | RSS: Main: 381595648 05:41:17 INFO - PROCESS | 1656 | 05:44:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 05:44:20 INFO - PROCESS | 1656 | RSS: Main: 369369088 05:44:20 INFO - PROCESS | 1656 | 05:47:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 05:47:23 INFO - PROCESS | 1656 | RSS: Main: 372150272 05:47:23 INFO - PROCESS | 1656 | 05:50:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 05:50:27 INFO - PROCESS | 1656 | RSS: Main: 343179264 05:50:27 INFO - PROCESS | 1656 | 05:53:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 05:53:31 INFO - PROCESS | 1656 | RSS: Main: 382451712 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | __start_tp_report 05:53:31 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 05:53:31 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 05:53:31 INFO - PROCESS | 1656 | |i|pagename|runs| 05:53:31 INFO - PROCESS | 1656 | |0;dojo.html;3345.1469875181824;3368.4273673475523;3377.7128944039755;3384.4077286474862;3425.914352141196;5543.279373681416;5583.07879199932;5633.042382794924;5675.29058867642;5724.198612194296;5689.431056894308;5695.943040569593;5803.129530570416;5822.446867547586;5848.853778655525;5104.948950510485;5138.820141295056;5158.690478571298;5170.819021334254;5325.334333208349;5283.07546179419;5329.840104796862;5341;5341.332333458317;5358.839234822962;5117.948820511803;5182.274481572583;5200;5224.6081543884175;5248.396434410015;5106.106431374532;5181.922271165936;5216.087184742655;5229.5293423591675;5248.895022099575;5108.386993560759;5127.410347810012;5133.383993920716;5179.430262671103;5227.424983251839;5720.599558030939;5773.653580785124;5779.624324418942;5783;5793.391693872167;5308.416074231832;5967.015442451991;6137.478314343241;6152.98475751505;6170.167027451282;6237.87524249517;6275.8744825103695;6278.152449419315;6289.213848268966;6298.338674439209;5836.2996440427;5836.5914385993;5838.095095260242;5839.182514447981;5844.532437405082;6127.433875357673;6192.535559833031;6238.781642642576;6240.937590624127;6256.374362563798;6165.84585385369;6166.537509686791;6171.6297022178815;6172.444479996821;6192.380761923771;6274.247090349188;6297.4332310091395;6301.684915754286;6305.527085468517;6316.778912738032;5780;6076.4531192192035;6081.513478921675;6125.662799505599;6138.263408391022;5767.538596912237;6144.262688477412;6187.783427580103;6212.875742485125;6213.130161777355;4844.806207751721;4870.95129048705;4887.902241955141;4906.288588154697;4921.532454416824;4388.385626012361;4411.580899814512;4414.337849322563;4430.269005614037;4494.325851122358;5726.856828579319;5728.198052272685;5732.713364331767;5746;5815.941840581625;6130.724117414727;6273.027680709497;6283.8114856554375;6306.306306306303;6310.905336419957;5649.548036157097;5681.176229446707;5693.146028095736;5694.430556944355;5708.885822283531;4599.425071866017;5342.679439233659;5402.51377376038;5402.837914862561;5403;6276.529260305495;6324.873502530016;6347.650879201688;6369.044643303541;6375.011873159668;6217.782377616762;6264.060390941395;6294.590851594632;6295.779647712308;6316.557840951089;6204.937950620436;6211.09939058843;6354.491640668828;6365.108884756045;6374.968125159345;5182.740862956762;5956.493698035542;6142.775043102407;6188.226471691039;6208.0998255253635;6121.969390153199;6208.192934918432;6226.25284965807;6262;6272.2159730033745;5973.91039134422;6029.849253768691;6043.214382130295;6093.086037094471;6094.481969032682;6063.151158837854;6085.421884920926;6088.939110609014;6092.847678808065;6100.8779824404155;5900.970495147497;5918.2898052234;5919.378465261258;5923.49650279731;5924.851878703067;6040;6050.606710563799;6062.6665533394325;6081.543884208613;6105.328413874381;5865.648061116347;5917.142014407973;5935.643861368331;5959.0763431668165;6013.158157857816 05:53:31 INFO - PROCESS | 1656 | |1;ext.html;478.81086970646595;483.26302388857;523.6543881038514;538.8464287678011;540.0441219042293;39615.40576891345;40593.59406405943;40629.79685101574;40698.38952415712;42972.925676858096;41784;42015;42080;42099.368509472304;42205.366919496155;8667.653293868238;8864.202221800037;9504.76238094048;9646.324757266993;9690.75773105671;11751.353675547842;12047.156699031071;12072.517099316017;12222.449989750483;12240.93879530601;11799.882001179963;11881;11895.167338286323;11908.46411911466;11908.702282442922;8689.82620347596;11681.591144309994;11925.463354148997;11940.940295298511;12006.939965300206;11742.882571174265;11828.290302581787;11833.644990650295;11855;11859.051275897993;39997.60002399997;40317;40332.39501407481;40434.79782601068;40588;3607.3687104756564;3625.148090198801;4127.2570937231285;4136.172765446922;4311.353297005443;30850.38299234025;32028;32141;32164.35671286561;32321.191970200696;3297.950530742017;3312.950305745416;3364.983175084109;3391.8982430526876;3395.151212196951;535393;537558;539683.3015834895;541657.2917135468;542775;59178.11232831511;59585.404145958855;59671.403285967455;59697;59701;62459;62928.37071629225;62930.68534657389;63019.05471417932;63024.054639180446;15776.684466310839;15929.920350398173;16086.678266434606;16097.114658693885;16130.112843793704;11497.597584084517;11672;12524.499020039097;12654.430550625244;12681.619551413472;15648.374065037498;15664.451744188898;15667.529974100795;15676.294566744524;15729.44946926852;6032;6449.774257900951;7299.211693135109;7310.52481588695;7499.887501687479;861;966.9226461883032;969.6654654144309;1024.7088668278022;1075.6450371377427;30078.54882176769;30346.54480182799;30499.39001219963;30859.537106943415;30962;1013.3261381181431;1057.3021805608262;1063.5745701719375;1081.2701426537055;1185.8280549320473;14871.47949821751;14895.77656335156;15066.171360575274;15186.924065379602;15207.239638018054;677854;682486;683401;683432;685683;55424.722876386164;55730.72134639301;55751;55758.539316854556;55872.44127558754;1256.7172386213213;1388.8472268050507;1399.6290982889543;1434;1445.3279225160352;30233.395332092794;30236.395272094876;30383.088507344815;30822.845885770428;31139.754409824258;49298.260526092854;49768.75115624544;50131.24803127883;50365;50369.49630503648;561607;562497;563083;563237.1838140783;563964;18813.717794232824;25216.49567008613;25732.742672573782;25784.61323080193;26555.60166597464;40273.1945361097;40343.596564033985;41104.794476027426;41337.79331103325;41430.792846035576;37003.88988330354;38041.04897377477;38269.61730382772;38763.80618096892;38960;37988;38089.809550952064;38095.85712428632;38486;38948.2210355797;36677.9139879407;36711;37253.441198382585;37557.62442375541;37604.435933460474;31313.8434307827;33917.49123763195;33945.830270848484;34217.65782342145;34757.9572612822;24363.634545481476;24829.751702483467;25057.373565661004;25125.538198415394;25323.74676253214 05:53:31 INFO - PROCESS | 1656 | |2;jquery.html;326.8496491613858;355.1972542054955;378.2095420571006;389.3109196721801;410.6263300396638;15674.294656740445;17502.562435939093;22007.449813754665;22066.448338791517;22117.778822211814;20572.279970201045;20775;21419.464513387175;22781.658275125887;22893.771062289372;5292.470752924715;5911.231539899818;6112.327643959161;6132.110843927628;6167;6028.367021462749;6063.302720187169;6136.785212517563;6140.293866205377;6140.815775526741;5813.651180929137;5984.760609575634;6007.128966299884;6008.1888944992525;6009.609375390585;5775.971120144414;5939.2575928009;6030.758769649209;6033;6036.1851150094835;5255.632105752599;5274.604404669627;5278.762455689503;5286.709230992294;5286.947130528684;26009.699515024364;26535.203943881716;26629.866850665818;26988.325291867663;27396.726032739618;2189.638709612912;2268.104098880949;2384.4396566806786;2415.4806716556027;2770.792190585694;15081.396744130221;15609.297581608855;15732.528024159294;15843.60390990223;16742.330306787713;1824.078840185711;1883.8775479593783;1895.4503194073702;1963.008680616294;2047.2322878920404;139638.97908592608;141536;150336.24831875772;153828.2308588461;158490.20754896267;16932.492025239015;20432.591348172955;20794.48013799667;21725.78274217269;22033.449163771034;30751.846240768653;35515.822420888246;35592;35641.82179089088;35663;10805.40570268627;11139.777204455866;11188.944055279835;11213.831792523119;11298.491567879466;9115.772105697411;9162.358634895494;9167.633294668272;9244.768880778034;9301.488418136933;8179.591020448954;8440.62017209227;9724.270679699051;9729.70810875675;9929.503524823875;2428.7085549734147;3674.042395871296;3839.5584507781964;3870.9612903871166;3875.6124387561017;769.1731388757108;823.9876401853977;896.5293221058996;956.5599824081;966.183574879226;18455.869206778065;21332.253371131937;21384.679229811252;21835.01742421595;21992.732071359587;916.5463095767637;948.7059011706393;958.6165533786541;962.4898803634085;1047.4972013433442;11119.777604448027;11456.427178641035;11490.046692062202;11528.406108625491;11565.826512602318;511601;601571;602838;603759;618752;131571;132780.3360983189;133609;134037;137568;929.1776777551916;932.5663566441638;945.744648944781;948.691675205561;970.6069042037844;17781.822181778534;18874.52813679669;19285.4214373569;19329.4201173965;19356.32252871143;36868.63131368652;51368.74315628398;57406.7129664349;57800;57841.13238301345;80718.59640701758;81818;82352.76472352956;83127;85439;5166.560842328444;7095.680694368662;8117.066537348044;8316;8443.07126216128;12804.23174609489;13388.19670819754;13685.31573421345;13779.862201377859;13822.37799299014;7986.84026319482;11762.764744704888;11813.40932953366;11832.11259155584;11858.584949526725;11727.35499547533;11782.351970641697;11784;11794.351310677996;11995.40022998864;11672.649820505398;11778.705532361417;11937;11969.521219151486;11984.64046078619;6800.285969973082;7468.3998564535805;7477.70089196426;7719.536827790351;8343.082260951169;10033.09702126812;11459.885401146215;11468.885311147116;11739.530418783495;11821.645350639494 05:53:31 INFO - PROCESS | 1656 | |3;mootools.html;1546.141891250356;1565.6868626274745;1723.9655206895861;1839.5585059585703;1853.4439668099576;8381.413301068927;8655.740327790161;8820.073892241302;8832.205101540869;8832.558372081403;7103.85792284154;7764;7975.521468711881;8601.612927418266;8649.69726059588;1917.4343568647282;2348.154664320843;2348.412896775806;2350.5181437805295;2356.2931120663734;1993.3421970749619;2348.2602980061224;2358.3396648938324;2384.403899025244;2393.126508824284;1942.4269840397117;2006.1574138861713;2294.6672732453785;2374.465745207332;2376.809855211579;2341.9765802342013;2342.9179978700663;2361.2680069178523;2374.1334412939327;2374.5725769361507;2345.64815277708;2349.083857295656;2354.3290162303833;2358.3278765551736;2361.374235827507;8719.912800871973;8870.645174193025;8883.911160888372;8886.866696999485;8907.421017633833;5081.822136225214;5277.050130976423;5278.02356564033;5280.947190528084;5281.339832520935;2929.5166297560877;3007;3473.70473509752;3638.163222458823;3658.506101676293;949.7293271417612;1037.5694086953904;1067.5996501312009;1091.0562363555546;1095.5179720922768;11218.943905280421;11247.887521124847;11251.0436612888;11255.718607034807;11347.375894325725;4481.4174157359985;4500.909981800346;4714.22215334466;4727.763611819395;4729.574338309569;9675.129238368581;9678.516074196263;9680.27397945157;9703.466309353053;9722.319437639297;5245.554127899095;5247.580193584504;5248.868778280574;5252.395974462986;5256.9737151314;4712.858614241578;4800.423949126127;4801.591864691471;4810.7113573185725;4819.3012013257885;3890.144168282973;3913.8238779255003;3932.2528719543766;3993.241284156059;4003.6396724294955;1186.4898093819568;1234.3581337704343;1273.3378643105718;1285.119693010291;1298.8116723075102;770.957597332141;811.4522697179381;845.5949540899172;872.5070335260557;956.9425834449955;5661.348944871393;5746.224259724883;5939.792107276225;5950.077737950624;6113.266408031065;949.9050094990446;955.1403736637083;976.5800705696477;981.4798156976814;982.3074732313702;3827.923441531154;3940.4483372327895;3945.151792364598;4061.289274376972;4078.34746440574;9850.80298394028;10516.158707303397;10517.106045986176;10519.21105917044;10531.894681053245;9622.470764107902;9907.157891579152;10319.1744660427;10471;10471.267011309135;7870.606469676493;8042.195780421911;8056.436049476598;8071.717489887826;8073.959630201929;5861.7655293787775;5900.144479050599;5942.910856337072;6053.969730151321;6217.347178546368;9396.577154028222;9880.35777674449;9905.405675659484;9951.253655975976;10009.299349045497;9824.06671366219;10094.242931780293;10102.898971010196;10131.13885319756;10161.745956350855;7092.645367731489;7346.265373462825;7403.703851845866;7431.071116110486;7507.211742767149;7898.68405263783;7966.880496792437;8002.399820013406;8013.4791238569305;8021.15777843318;7281.344678978706;7362.852742945004;7372.852542949004;7468.925310746823;7558.735444259644;7036.472264580279;7222.674979626035;7326.120865496175;7365.263473652806;7481.88777168332;6836.521443499107;6962.825929351807;6970.407515361048;6970.895436568354;6974.163100427778;4675.76621168947;4753.786079626356;4889.828855990023;4894.290327902506;4978.651494395358;4917.852464426073;4941.283513890394;4950.430700469492;4952.628552858622;5052.494750524918 05:53:31 INFO - PROCESS | 1656 | |4;prototype.html;304.6618253738351;310.36994900352295;314.5266374106969;319.5749652961561;321.5546468141626;5255.290535777669;5383.407825139237;5411.485908838654;5412.539934105601;5419.403865574784;4647.814087436498;5135.871603209922;5346.331708536433;5428.348598168215;5441.59188060895;2885.769138468928;3420.734328298533;3960.3069462844032;4049.412835138903;4179.582041795811;3651.434027725707;3686.9123608535547;4123.195976784528;4150.522689890671;4212.347086201628;3381.98771730821;4096.426500289962;4110.177964407116;4152.937705934413;4172.955251926549;3868;3890.513685789276;3991.7405368650943;4061.715679902408;4171.395147703566;3139;3541.8029634882746;3545.2170046513365;3560.626134255918;3606.1886075633033;4347.826086956518;5246.449122842123;5261.579373569115;5295.655782374133;5353.03645343838;4503.144402563503;4569.983810684932;4595.128044971892;4692.319613656035;4707.835225767081;4939.209726443788;5138.2549530318265;5151.253068305112;5645.774169033234;5648.661080335193;3483.9129021774406;3761.4733937248807;3903.882883513499;3960.5247370315747;4011.4785077939973;5434.212039254286;5758.107493338539;5963.940360596339;6004.78983235589;6066.272047354301;3114.813111213289;3424.8972530824117;3982.9402558961638;4002.1795531915886;4067.613576710208;3304.8843290485197;3628.32875917956;4127.525334586501;4129.793510324472;4130.855420060284;3698.9458004468597;4295.871123866289;4383.693141480129;4530.954690453119;4778.092162489185;5043.99120175959;5611.7474713637985;5643.294588176478;5671.602987790898;5731.77072917087;1334.3595074364166;1352.6280272925023;1355.7120735301503;1361.5574938145141;1361.6323592629935;795.9562224077731;821.5070957425497;824.9523105655784;836.9707060252862;839.5676226743232;4284.0146766243315;4451.755153466591;5268.788178718916;5281.577473802086;5286.788528458896;4626.074785043005;5708.514776243983;5758.942410575924;5818.4763371296785;5910.202122713465;4370.257056300373;4490.497412018631;4496.64026877849;5342.305500284938;5359.946400536022;2856;2890.309169136079;2920.4597149527403;3038.9240268993008;3041.7832866853437;5155.484451554815;5311.867203319948;5724.427557244242;6032.125341825411;6050.152978583002;5618.016847051749;5957.374475680078;6036.227357901312;6068.726907289182;6071.605345652607;4568.428946381769;4618.782984167618;6040.822039702216;6048.909266361008;6099.451049405486;5608.383077861514;5857.7698683275285;6002.939970600238;6005.099235114768;6029.427204415573;5285.445028271976;5481.451854814487;5790.0627573250595;5792.771276967753;5815.360310365941;5083.442359356389;5196.558292545176;5249.737513124405;6037.63774173551;6116.724747386289;3465.809380484098;3558.7900113961377;3665.395209790461;3693.8891833245048;3966.5835087315418;4774.4509381421585;5776.162456443876;5833.708314584169;5842.970785146047;5844.590878638454;5350;5413.133898576209;5688.951977288256;5697.401772813795;5765.740541675551;5372.952274306629;5584.5986225921615;5608.38307786135;5749.511291540266;5765.164051212634;4572.536788227735;5064.392272927125;5469.343678758574;5470.370907345706;5514.310711161105;4769.47376839408;4856.479052686774;5169.508896654812;5461.644993075437;5589.55283577321;4617.907641847077;5001.474550262205;5442.700651464207;5518.172274158908;5642.322921249476 05:53:31 INFO - PROCESS | 1656 | |5;yui.html;614.6373639552663;812.3541784281496;827.3174630929481;835.9958200208998;872.3936863879602;12239.877601223985;15741.134237616941;15742.212889355515;15744.897857149976;16262.268197931093;14264.857351426483;14657.853421465783;14856;14874.702505949901;14880.553583392488;865.3466632692324;867.3581549653243;869.7782065573269;870;873.8077622922947;866.5017614871442;879.8636211387213;880.0451510111536;881.0704706534603;881.9779505512381;928.4661319741142;930.4463844012802;931.4504442378995;932.7388331267222;933.5518950903537;783.4280974888363;916.9724908252763;922.9815403691956;923.4182465047045;926.1248120526105;688.8036909480824;689.3726708695064;691.1705952856541;691.512483698991;693.2028167607242;12852.742945141139;13029.739405211843;13292.936565074771;13294.40175192109;13312.134711243834;12114.152009359348;12194.512219511209;12306.384680765927;12353;12476.937615311956;11021.83467247992;11050.447477626087;11081.113510919107;11434.656960291204;11447.942760286145;3761.755485893408;3773.3019391412677;3823.617638236182;3945.4279129526344;3957.564667886559;109146.90853091526;112262;113648.86351136466;115284;117381.41309293399;9406.294527910433;9657.32398732082;9657.565409556586;10560.313579617301;11025.889741102486;18237.27050917975;19343.709844352346;20914.47713807167;21521.89239053795;21846.890765546388;7752.961235193788;7776.416768742367;7796.649150788228;7831.021122359705;7911.683532658629;4296.720713153635;4486.12520558488;4490.865274041784;4492.842750503782;4590.081983603226;3413.8805141819917;3528.788272703646;3532.2405682778326;3542.4686297055646;3542.71658267338;642.9003504456816;643.1767337807614;651.8631087471684;653.8267359149829;658.7694306992514;559.2980809084573;615.1664494609829;645.8837409266378;646.0374042676473;663.2107791727828;8031.558264295403;8754.29965602763;8756.824863502694;8822.073682263399;8935.106489351143;553.7812564037183;590.3004939147039;607.8389226854887;646.0664340028613;679.3478260869621;4443.533428989974;4547.090581883636;4565.132624801344;4670.486246512881;4697.81208751653;146022;146617;151456;152984;153425.23287383714;90404.54797725969;117310.41344793393;117968;124562;129288;74078;107012;107217;109252.4537377308;110347.44826275817;8450.450720703135;8532.360072994426;8535.658573656985;8604.311655067704;9645.517724113906;59120;60164.796704066546;61629.691851540454;62929.68535157478;63933.36899518692;57637.42362576488;73947.63026184835;93416.53291733497;95534;98095;12893.355332233163;13304.534341298395;13882.722345552831;13975.301234938415;14115.929420352833;12742.49030038788;15414;15849.969751966086;15896.205189740698;15931.522054338388;11717.882821172021;13684.93157534206;13949.651258718613;14372.640683982983;14605.634859128182;12110.939445303069;14029.438822447397;14059.297035148405;14347.641308966942;14665.56003319902;13648.317584120952;13946.79079813824;13972;14019.228942408265;14032.859671403156;11367.886321136682;11487.25332853362;11526.423678816192;11883.465244063866;12152.848215567044;11325.730435475158;11495.425228738697;11628.6511404658;11723;11751.647450576496 05:53:31 INFO - PROCESS | 1656 | __end_tp_report 05:53:31 INFO - PROCESS | 1656 | __start_cc_report 05:53:31 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2181 05:53:31 INFO - PROCESS | 1656 | __end_cc_report 05:53:31 INFO - PROCESS | 1656 | __startTimestamp1467896011026__endTimestamp 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | ------- Summary: start ------- 05:53:31 INFO - PROCESS | 1656 | Number of tests: 6 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5723.40 Median:5918.29 stddev:637.66 (10.8%) stddev-sans-first:611.71 05:53:31 INFO - PROCESS | 1656 | Values: 3345.1 3368.4 3377.7 3384.4 3425.9 5543.3 5583.1 5633.0 5675.3 5724.2 5689.4 5695.9 5803.1 5822.4 5848.9 5104.9 5138.8 5158.7 5170.8 5325.3 5283.1 5329.8 5341.0 5341.3 5358.8 5117.9 5182.3 5200.0 5224.6 5248.4 5106.1 5181.9 5216.1 5229.5 5248.9 5108.4 5127.4 5133.4 5179.4 5227.4 5720.6 5773.7 5779.6 5783.0 5793.4 5308.4 5967.0 6137.5 6153.0 6170.2 6237.9 6275.9 6278.2 6289.2 6298.3 5836.3 5836.6 5838.1 5839.2 5844.5 6127.4 6192.5 6238.8 6240.9 6256.4 6165.8 6166.5 6171.6 6172.4 6192.4 6274.2 6297.4 6301.7 6305.5 6316.8 5780.0 6076.5 6081.5 6125.7 6138.3 5767.5 6144.3 6187.8 6212.9 6213.1 4844.8 4871.0 4887.9 4906.3 4921.5 4388.4 4411.6 4414.3 4430.3 4494.3 5726.9 5728.2 5732.7 5746.0 5815.9 6130.7 6273.0 6283.8 6306.3 6310.9 5649.5 5681.2 5693.1 5694.4 5708.9 4599.4 5342.7 5402.5 5402.8 5403.0 6276.5 6324.9 6347.7 6369.0 6375.0 6217.8 6264.1 6294.6 6295.8 6316.6 6204.9 6211.1 6354.5 6365.1 6375.0 5182.7 5956.5 6142.8 6188.2 6208.1 6122.0 6208.2 6226.3 6262.0 6272.2 5973.9 6029.8 6043.2 6093.1 6094.5 6063.2 6085.4 6088.9 6092.8 6100.9 5901.0 5918.3 5919.4 5923.5 5924.9 6040.0 6050.6 6062.7 6081.5 6105.3 5865.6 5917.1 5935.6 5959.1 6013.2 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:72229.90 Median:37128.67 stddev:160036.24 (431.0%) stddev-sans-first:160394.51 05:53:31 INFO - PROCESS | 1656 | Values: 478.8 483.3 523.7 538.8 540.0 39615.4 40593.6 40629.8 40698.4 42972.9 41784.0 42015.0 42080.0 42099.4 42205.4 8667.7 8864.2 9504.8 9646.3 9690.8 11751.4 12047.2 12072.5 12222.4 12240.9 11799.9 11881.0 11895.2 11908.5 11908.7 8689.8 11681.6 11925.5 11940.9 12006.9 11742.9 11828.3 11833.6 11855.0 11859.1 39997.6 40317.0 40332.4 40434.8 40588.0 3607.4 3625.1 4127.3 4136.2 4311.4 30850.4 32028.0 32141.0 32164.4 32321.2 3298.0 3313.0 3365.0 3391.9 3395.2 535393.0 537558.0 539683.3 541657.3 542775.0 59178.1 59585.4 59671.4 59697.0 59701.0 62459.0 62928.4 62930.7 63019.1 63024.1 15776.7 15929.9 16086.7 16097.1 16130.1 11497.6 11672.0 12524.5 12654.4 12681.6 15648.4 15664.5 15667.5 15676.3 15729.4 6032.0 6449.8 7299.2 7310.5 7499.9 861.0 966.9 969.7 1024.7 1075.6 30078.5 30346.5 30499.4 30859.5 30962.0 1013.3 1057.3 1063.6 1081.3 1185.8 14871.5 14895.8 15066.2 15186.9 15207.2 677854.0 682486.0 683401.0 683432.0 685683.0 55424.7 55730.7 55751.0 55758.5 55872.4 1256.7 1388.8 1399.6 1434.0 1445.3 30233.4 30236.4 30383.1 30822.8 31139.8 49298.3 49768.8 50131.2 50365.0 50369.5 561607.0 562497.0 563083.0 563237.2 563964.0 18813.7 25216.5 25732.7 25784.6 26555.6 40273.2 40343.6 41104.8 41337.8 41430.8 37003.9 38041.0 38269.6 38763.8 38960.0 37988.0 38089.8 38095.9 38486.0 38948.2 36677.9 36711.0 37253.4 37557.6 37604.4 31313.8 33917.5 33945.8 34217.7 34758.0 24363.6 24829.8 25057.4 25125.5 25323.7 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:37224.95 Median:16761.32 stddev:99224.01 (592.0%) stddev-sans-first:99463.68 05:53:31 INFO - PROCESS | 1656 | Values: 326.8 355.2 378.2 389.3 410.6 15674.3 17502.6 22007.4 22066.4 22117.8 20572.3 20775.0 21419.5 22781.7 22893.8 5292.5 5911.2 6112.3 6132.1 6167.0 6028.4 6063.3 6136.8 6140.3 6140.8 5813.7 5984.8 6007.1 6008.2 6009.6 5776.0 5939.3 6030.8 6033.0 6036.2 5255.6 5274.6 5278.8 5286.7 5286.9 26009.7 26535.2 26629.9 26988.3 27396.7 2189.6 2268.1 2384.4 2415.5 2770.8 15081.4 15609.3 15732.5 15843.6 16742.3 1824.1 1883.9 1895.5 1963.0 2047.2 139639.0 141536.0 150336.2 153828.2 158490.2 16932.5 20432.6 20794.5 21725.8 22033.4 30751.8 35515.8 35592.0 35641.8 35663.0 10805.4 11139.8 11188.9 11213.8 11298.5 9115.8 9162.4 9167.6 9244.8 9301.5 8179.6 8440.6 9724.3 9729.7 9929.5 2428.7 3674.0 3839.6 3871.0 3875.6 769.2 824.0 896.5 956.6 966.2 18455.9 21332.3 21384.7 21835.0 21992.7 916.5 948.7 958.6 962.5 1047.5 11119.8 11456.4 11490.0 11528.4 11565.8 511601.0 601571.0 602838.0 603759.0 618752.0 131571.0 132780.3 133609.0 134037.0 137568.0 929.2 932.6 945.7 948.7 970.6 17781.8 18874.5 19285.4 19329.4 19356.3 36868.6 51368.7 57406.7 57800.0 57841.1 80718.6 81818.0 82352.8 83127.0 85439.0 5166.6 7095.7 8117.1 8316.0 8443.1 12804.2 13388.2 13685.3 13779.9 13822.4 7986.8 11762.8 11813.4 11832.1 11858.6 11727.4 11782.4 11784.0 11794.4 11995.4 11672.6 11778.7 11937.0 11969.5 11984.6 6800.3 7468.4 7477.7 7719.5 8343.1 10033.1 11459.9 11468.9 11739.5 11821.6 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5614.35 Median:5067.16 stddev:3112.75 (61.4%) stddev-sans-first:3106.46 05:53:31 INFO - PROCESS | 1656 | Values: 1546.1 1565.7 1724.0 1839.6 1853.4 8381.4 8655.7 8820.1 8832.2 8832.6 7103.9 7764.0 7975.5 8601.6 8649.7 1917.4 2348.2 2348.4 2350.5 2356.3 1993.3 2348.3 2358.3 2384.4 2393.1 1942.4 2006.2 2294.7 2374.5 2376.8 2342.0 2342.9 2361.3 2374.1 2374.6 2345.6 2349.1 2354.3 2358.3 2361.4 8719.9 8870.6 8883.9 8886.9 8907.4 5081.8 5277.1 5278.0 5280.9 5281.3 2929.5 3007.0 3473.7 3638.2 3658.5 949.7 1037.6 1067.6 1091.1 1095.5 11218.9 11247.9 11251.0 11255.7 11347.4 4481.4 4500.9 4714.2 4727.8 4729.6 9675.1 9678.5 9680.3 9703.5 9722.3 5245.6 5247.6 5248.9 5252.4 5257.0 4712.9 4800.4 4801.6 4810.7 4819.3 3890.1 3913.8 3932.3 3993.2 4003.6 1186.5 1234.4 1273.3 1285.1 1298.8 771.0 811.5 845.6 872.5 956.9 5661.3 5746.2 5939.8 5950.1 6113.3 949.9 955.1 976.6 981.5 982.3 3827.9 3940.4 3945.2 4061.3 4078.3 9850.8 10516.2 10517.1 10519.2 10531.9 9622.5 9907.2 10319.2 10471.0 10471.3 7870.6 8042.2 8056.4 8071.7 8074.0 5861.8 5900.1 5942.9 6054.0 6217.3 9396.6 9880.4 9905.4 9951.3 10009.3 9824.1 10094.2 10102.9 10131.1 10161.7 7092.6 7346.3 7403.7 7431.1 7507.2 7898.7 7966.9 8002.4 8013.5 8021.2 7281.3 7362.9 7372.9 7468.9 7558.7 7036.5 7222.7 7326.1 7365.3 7481.9 6836.5 6962.8 6970.4 6970.9 6974.2 4675.8 4753.8 4889.8 4894.3 4978.7 4917.9 4941.3 4950.4 4952.6 5052.5 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4523.52 Median:5137.06 stddev:1417.84 (27.6%) stddev-sans-first:1386.00 05:53:31 INFO - PROCESS | 1656 | Values: 304.7 310.4 314.5 319.6 321.6 5255.3 5383.4 5411.5 5412.5 5419.4 4647.8 5135.9 5346.3 5428.3 5441.6 2885.8 3420.7 3960.3 4049.4 4179.6 3651.4 3686.9 4123.2 4150.5 4212.3 3382.0 4096.4 4110.2 4152.9 4173.0 3868.0 3890.5 3991.7 4061.7 4171.4 3139.0 3541.8 3545.2 3560.6 3606.2 4347.8 5246.4 5261.6 5295.7 5353.0 4503.1 4570.0 4595.1 4692.3 4707.8 4939.2 5138.3 5151.3 5645.8 5648.7 3483.9 3761.5 3903.9 3960.5 4011.5 5434.2 5758.1 5963.9 6004.8 6066.3 3114.8 3424.9 3982.9 4002.2 4067.6 3304.9 3628.3 4127.5 4129.8 4130.9 3698.9 4295.9 4383.7 4531.0 4778.1 5044.0 5611.7 5643.3 5671.6 5731.8 1334.4 1352.6 1355.7 1361.6 1361.6 796.0 821.5 825.0 837.0 839.6 4284.0 4451.8 5268.8 5281.6 5286.8 4626.1 5708.5 5758.9 5818.5 5910.2 4370.3 4490.5 4496.6 5342.3 5359.9 2856.0 2890.3 2920.5 3038.9 3041.8 5155.5 5311.9 5724.4 6032.1 6050.2 5618.0 5957.4 6036.2 6068.7 6071.6 4568.4 4618.8 6040.8 6048.9 6099.5 5608.4 5857.8 6002.9 6005.1 6029.4 5285.4 5481.5 5790.1 5792.8 5815.4 5083.4 5196.6 5249.7 6037.6 6116.7 3465.8 3558.8 3665.4 3693.9 3966.6 4774.5 5776.2 5833.7 5843.0 5844.6 5350.0 5413.1 5689.0 5697.4 5765.7 5373.0 5584.6 5608.4 5749.5 5765.2 4572.5 5064.4 5469.3 5470.4 5514.3 4769.5 4856.5 5169.5 5461.6 5589.6 4617.9 5001.5 5442.7 5518.2 5642.3 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24040.36 Median:3767.53 stddev:38393.54 (1019.1%) stddev-sans-first:38460.95 05:53:31 INFO - PROCESS | 1656 | Values: 614.6 812.4 827.3 836.0 872.4 12239.9 15741.1 15742.2 15744.9 16262.3 14264.9 14657.9 14856.0 14874.7 14880.6 865.3 867.4 869.8 870.0 873.8 866.5 879.9 880.0 881.1 882.0 928.5 930.4 931.5 932.7 933.6 783.4 917.0 923.0 923.4 926.1 688.8 689.4 691.2 691.5 693.2 12852.7 13029.7 13292.9 13294.4 13312.1 12114.2 12194.5 12306.4 12353.0 12476.9 11021.8 11050.4 11081.1 11434.7 11447.9 3761.8 3773.3 3823.6 3945.4 3957.6 109146.9 112262.0 113648.9 115284.0 117381.4 9406.3 9657.3 9657.6 10560.3 11025.9 18237.3 19343.7 20914.5 21521.9 21846.9 7753.0 7776.4 7796.6 7831.0 7911.7 4296.7 4486.1 4490.9 4492.8 4590.1 3413.9 3528.8 3532.2 3542.5 3542.7 642.9 643.2 651.9 653.8 658.8 559.3 615.2 645.9 646.0 663.2 8031.6 8754.3 8756.8 8822.1 8935.1 553.8 590.3 607.8 646.1 679.3 4443.5 4547.1 4565.1 4670.5 4697.8 146022.0 146617.0 151456.0 152984.0 153425.2 90404.5 117310.4 117968.0 124562.0 129288.0 74078.0 107012.0 107217.0 109252.5 110347.4 8450.5 8532.4 8535.7 8604.3 9645.5 59120.0 60164.8 61629.7 62929.7 63933.4 57637.4 73947.6 93416.5 95534.0 98095.0 12893.4 13304.5 13882.7 13975.3 14115.9 12742.5 15414.0 15850.0 15896.2 15931.5 11717.9 13684.9 13949.7 14372.6 14605.6 12110.9 14029.4 14059.3 14347.6 14665.6 13648.3 13946.8 13972.0 14019.2 14032.9 11367.9 11487.3 11526.4 11883.5 12152.8 11325.7 11495.4 11628.7 11723.0 11751.6 05:53:31 INFO - PROCESS | 1656 | -------- Summary: end -------- 05:53:31 INFO - PROCESS | 1656 | 05:53:31 INFO - TEST-INFO | 1656: exit 0 05:53:31 INFO - TEST-OK | dromaeo_css | took 1116969ms 05:53:31 INFO - TEST-START | kraken 05:53:31 INFO - operating with platform_type : mac_ 05:53:31 INFO - Initialising browser for kraken test... 05:53:31 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmp61iDEV/profile http://localhost:49272/getInfo.html) 05:53:33 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 05:53:33 INFO - PROCESS | 1661 | colorDepth:24 05:53:33 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 05:53:33 INFO - PROCESS | 1661 | __metrics 05:53:33 INFO - PROCESS | 1661 | 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:53:33 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:53:34 INFO - TEST-INFO | 1661: exit 0 05:53:34 INFO - Browser initialized. 05:53:34 INFO - Running cycle 1/1 for kraken test... 05:53:34 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmp61iDEV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:53:46 INFO - PROCESS | 1663 | RSS: Main: 243806208 05:53:46 INFO - PROCESS | 1663 | 05:53:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 05:53:50 INFO - PROCESS | 1663 | RSS: Main: 294445056 05:53:50 INFO - PROCESS | 1663 | 05:53:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 05:53:53 INFO - PROCESS | 1663 | RSS: Main: 305065984 05:53:53 INFO - PROCESS | 1663 | 05:53:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 05:53:57 INFO - PROCESS | 1663 | RSS: Main: 389459968 05:53:57 INFO - PROCESS | 1663 | 05:53:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 05:54:00 INFO - PROCESS | 1663 | RSS: Main: 292257792 05:54:00 INFO - PROCESS | 1663 | 05:54:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:54:03 INFO - PROCESS | 1663 | RSS: Main: 296230912 05:54:03 INFO - PROCESS | 1663 | 05:54:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 05:54:06 INFO - PROCESS | 1663 | RSS: Main: 334061568 05:54:06 INFO - PROCESS | 1663 | 05:54:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 05:54:10 INFO - PROCESS | 1663 | RSS: Main: 334602240 05:54:10 INFO - PROCESS | 1663 | 05:54:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 05:54:13 INFO - PROCESS | 1663 | RSS: Main: 333664256 05:54:13 INFO - PROCESS | 1663 | 05:54:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:54:15 INFO - PROCESS | 1663 | RSS: Main: 313757696 05:54:15 INFO - PROCESS | 1663 | 05:54:17 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:54:18 INFO - PROCESS | 1663 | RSS: Main: 321286144 05:54:18 INFO - PROCESS | 1663 | 05:54:20 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:54:21 INFO - PROCESS | 1663 | RSS: Main: 320036864 05:54:21 INFO - PROCESS | 1663 | 05:54:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:54:24 INFO - PROCESS | 1663 | RSS: Main: 328048640 05:54:24 INFO - PROCESS | 1663 | 05:54:27 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:54:28 INFO - PROCESS | 1663 | RSS: Main: 330555392 05:54:28 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 05:54:30 INFO - PROCESS | 1663 | RSS: Main: 373858304 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | __start_tp_report 05:54:30 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 05:54:30 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 05:54:30 INFO - PROCESS | 1663 | |i|pagename|runs| 05:54:30 INFO - PROCESS | 1663 | |0;ai-astar;101;113;113;113;112;112;114;113;112;113 05:54:30 INFO - PROCESS | 1663 | |1;audio-beat-detection;143;115;108;155;106;111;114;110;179;138 05:54:30 INFO - PROCESS | 1663 | |2;audio-dft;184;183;184;182;184;183;184;191;228;254 05:54:30 INFO - PROCESS | 1663 | |3;audio-fft;76;76;58;86;58;59;80;54;77;48 05:54:30 INFO - PROCESS | 1663 | |4;audio-oscillator;85;84;94;72;74;70;96;70;68;97 05:54:30 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;87;85;86;86;87;96;88;84;87;87 05:54:30 INFO - PROCESS | 1663 | |6;imaging-darkroom;92;93;93;94;101;135;93;94;93;92 05:54:30 INFO - PROCESS | 1663 | |7;imaging-desaturate;81;87;87;85;84;84;85;86;84;84 05:54:30 INFO - PROCESS | 1663 | |8;json-parse-financial;56;55;56;53;54;54;54;53;54;54 05:54:30 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;66;80;74;72;70;70;73;104;103;88 05:54:30 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;83;82;82;80;85;85;86;85;86;85 05:54:30 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;147;152;154;135;135;158;127;140;205;157 05:54:30 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;221;187;169;169;166;168;225;167;167;165 05:54:30 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;70;68;68;68;67;66;67;68;66;67 05:54:30 INFO - PROCESS | 1663 | __end_tp_report 05:54:30 INFO - PROCESS | 1663 | __start_cc_report 05:54:30 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2020 05:54:30 INFO - PROCESS | 1663 | __end_cc_report 05:54:30 INFO - PROCESS | 1663 | __startTimestamp1467896070388__endTimestamp 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | ------- Summary: start ------- 05:54:30 INFO - PROCESS | 1663 | Number of tests: 14 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.78 (3.3%) stddev-sans-first:0.67 05:54:30 INFO - PROCESS | 1663 | Values: 101.0 113.0 113.0 113.0 112.0 112.0 114.0 113.0 112.0 113.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:126.50 stddev:24.76 (19.6%) stddev-sans-first:25.65 05:54:30 INFO - PROCESS | 1663 | Values: 143.0 115.0 108.0 155.0 106.0 111.0 114.0 110.0 179.0 138.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:195.70 Median:184.00 stddev:24.77 (13.5%) stddev-sans-first:25.91 05:54:30 INFO - PROCESS | 1663 | Values: 184.0 183.0 184.0 182.0 184.0 183.0 184.0 191.0 228.0 254.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:67.20 Median:76.00 stddev:13.11 (17.3%) stddev-sans-first:13.52 05:54:30 INFO - PROCESS | 1663 | Values: 76.0 76.0 58.0 86.0 58.0 59.0 80.0 54.0 77.0 48.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:84.50 stddev:11.62 (13.8%) stddev-sans-first:12.24 05:54:30 INFO - PROCESS | 1663 | Values: 85.0 84.0 94.0 72.0 74.0 70.0 96.0 70.0 68.0 97.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:87.00 stddev:3.27 (3.8%) stddev-sans-first:3.46 05:54:30 INFO - PROCESS | 1663 | Values: 87.0 85.0 86.0 86.0 87.0 96.0 88.0 84.0 87.0 87.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:93.00 stddev:13.26 (14.3%) stddev-sans-first:13.88 05:54:30 INFO - PROCESS | 1663 | Values: 92.0 93.0 93.0 94.0 101.0 135.0 93.0 94.0 93.0 92.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.77 (2.1%) stddev-sans-first:1.27 05:54:30 INFO - PROCESS | 1663 | Values: 81.0 87.0 87.0 85.0 84.0 84.0 85.0 86.0 84.0 84.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:1.06 (2.0%) stddev-sans-first:0.93 05:54:30 INFO - PROCESS | 1663 | Values: 56.0 55.0 56.0 53.0 54.0 54.0 54.0 53.0 54.0 54.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:80.00 Median:72.50 stddev:13.80 (19.0%) stddev-sans-first:13.68 05:54:30 INFO - PROCESS | 1663 | Values: 66.0 80.0 74.0 72.0 70.0 70.0 73.0 104.0 103.0 88.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:85.00 stddev:2.02 (2.4%) stddev-sans-first:2.12 05:54:30 INFO - PROCESS | 1663 | Values: 83.0 82.0 82.0 80.0 85.0 85.0 86.0 85.0 86.0 85.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:153.00 stddev:21.69 (14.2%) stddev-sans-first:22.96 05:54:30 INFO - PROCESS | 1663 | Values: 147.0 152.0 154.0 135.0 135.0 158.0 127.0 140.0 205.0 157.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:169.00 stddev:23.33 (13.8%) stddev-sans-first:19.58 05:54:30 INFO - PROCESS | 1663 | Values: 221.0 187.0 169.0 169.0 166.0 168.0 225.0 167.0 167.0 165.0 05:54:30 INFO - PROCESS | 1663 | 05:54:30 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.18 (1.7%) stddev-sans-first:0.83 05:54:30 INFO - PROCESS | 1663 | Values: 70.0 68.0 68.0 68.0 67.0 66.0 67.0 68.0 66.0 67.0 05:54:30 INFO - PROCESS | 1663 | -------- Summary: end -------- 05:54:30 INFO - PROCESS | 1663 | 05:54:31 INFO - TEST-INFO | 1663: exit 0 05:54:31 INFO - TEST-OK | kraken | took 59487ms 05:54:31 INFO - SUITE-END | took 1176s 05:54:31 INFO - Completed test suite (00:19:36) 05:54:31 INFO - javascript benchmark 05:54:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.1469875181824, 3368.4273673475523, 3377.7128944039755, 3384.4077286474862, 3425.914352141196, 5543.279373681416, 5583.07879199932, 5633.042382794924, 5675.29058867642, 5724.198612194296, 5689.431056894308, 5695.943040569593, 5803.129530570416, 5822.446867547586, 5848.853778655525, 5104.948950510485, 5138.820141295056, 5158.690478571298, 5170.819021334254, 5325.334333208349, 5283.07546179419, 5329.840104796862, 5341.0, 5341.332333458317, 5358.839234822962, 5117.948820511803, 5182.274481572583, 5200.0, 5224.6081543884175, 5248.396434410015, 5106.106431374532, 5181.922271165936, 5216.087184742655, 5229.5293423591675, 5248.895022099575, 5108.386993560759, 5127.410347810012, 5133.383993920716, 5179.430262671103, 5227.424983251839, 5720.599558030939, 5773.653580785124, 5779.624324418942, 5783.0, 5793.391693872167, 5308.416074231832, 5967.015442451991, 6137.478314343241, 6152.98475751505, 6170.167027451282, 6237.87524249517, 6275.8744825103695, 6278.152449419315, 6289.213848268966, 6298.338674439209, 5836.2996440427, 5836.5914385993, 5838.095095260242, 5839.182514447981, 5844.532437405082, 6127.433875357673, 6192.535559833031, 6238.781642642576, 6240.937590624127, 6256.374362563798, 6165.84585385369, 6166.537509686791, 6171.6297022178815, 6172.444479996821, 6192.380761923771, 6274.247090349188, 6297.4332310091395, 6301.684915754286, 6305.527085468517, 6316.778912738032, 5780.0, 6076.4531192192035, 6081.513478921675, 6125.662799505599, 6138.263408391022, 5767.538596912237, 6144.262688477412, 6187.783427580103, 6212.875742485125, 6213.130161777355, 4844.806207751721, 4870.95129048705, 4887.902241955141, 4906.288588154697, 4921.532454416824, 4388.385626012361, 4411.580899814512, 4414.337849322563, 4430.269005614037, 4494.325851122358, 5726.856828579319, 5728.198052272685, 5732.713364331767, 5746.0, 5815.941840581625, 6130.724117414727, 6273.027680709497, 6283.8114856554375, 6306.306306306303, 6310.905336419957, 5649.548036157097, 5681.176229446707, 5693.146028095736, 5694.430556944355, 5708.885822283531, 4599.425071866017, 5342.679439233659, 5402.51377376038, 5402.837914862561, 5403.0, 6276.529260305495, 6324.873502530016, 6347.650879201688, 6369.044643303541, 6375.011873159668, 6217.782377616762, 6264.060390941395, 6294.590851594632, 6295.779647712308, 6316.557840951089, 6204.937950620436, 6211.09939058843, 6354.491640668828, 6365.108884756045, 6374.968125159345, 5182.740862956762, 5956.493698035542, 6142.775043102407, 6188.226471691039, 6208.0998255253635, 6121.969390153199, 6208.192934918432, 6226.25284965807, 6262.0, 6272.2159730033745, 5973.91039134422, 6029.849253768691, 6043.214382130295, 6093.086037094471, 6094.481969032682, 6063.151158837854, 6085.421884920926, 6088.939110609014, 6092.847678808065, 6100.8779824404155, 5900.970495147497, 5918.2898052234, 5919.378465261258, 5923.49650279731, 5924.851878703067, 6040.0, 6050.606710563799, 6062.6665533394325, 6081.543884208613, 6105.328413874381, 5865.648061116347, 5917.142014407973, 5935.643861368331, 5959.0763431668165, 6013.158157857816], "value": 5682.856482237415, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.81086970646595, 483.26302388857, 523.6543881038514, 538.8464287678011, 540.0441219042293, 39615.40576891345, 40593.59406405943, 40629.79685101574, 40698.38952415712, 42972.925676858096, 41784.0, 42015.0, 42080.0, 42099.368509472304, 42205.366919496155, 8667.653293868238, 8864.202221800037, 9504.76238094048, 9646.324757266993, 9690.75773105671, 11751.353675547842, 12047.156699031071, 12072.517099316017, 12222.449989750483, 12240.93879530601, 11799.882001179963, 11881.0, 11895.167338286323, 11908.46411911466, 11908.702282442922, 8689.82620347596, 11681.591144309994, 11925.463354148997, 11940.940295298511, 12006.939965300206, 11742.882571174265, 11828.290302581787, 11833.644990650295, 11855.0, 11859.051275897993, 39997.60002399997, 40317.0, 40332.39501407481, 40434.79782601068, 40588.0, 3607.3687104756564, 3625.148090198801, 4127.2570937231285, 4136.172765446922, 4311.353297005443, 30850.38299234025, 32028.0, 32141.0, 32164.35671286561, 32321.191970200696, 3297.950530742017, 3312.950305745416, 3364.983175084109, 3391.8982430526876, 3395.151212196951, 535393.0, 537558.0, 539683.3015834895, 541657.2917135468, 542775.0, 59178.11232831511, 59585.404145958855, 59671.403285967455, 59697.0, 59701.0, 62459.0, 62928.37071629225, 62930.68534657389, 63019.05471417932, 63024.054639180446, 15776.684466310839, 15929.920350398173, 16086.678266434606, 16097.114658693885, 16130.112843793704, 11497.597584084517, 11672.0, 12524.499020039097, 12654.430550625244, 12681.619551413472, 15648.374065037498, 15664.451744188898, 15667.529974100795, 15676.294566744524, 15729.44946926852, 6032.0, 6449.774257900951, 7299.211693135109, 7310.52481588695, 7499.887501687479, 861.0, 966.9226461883032, 969.6654654144309, 1024.7088668278022, 1075.6450371377427, 30078.54882176769, 30346.54480182799, 30499.39001219963, 30859.537106943415, 30962.0, 1013.3261381181431, 1057.3021805608262, 1063.5745701719375, 1081.2701426537055, 1185.8280549320473, 14871.47949821751, 14895.77656335156, 15066.171360575274, 15186.924065379602, 15207.239638018054, 677854.0, 682486.0, 683401.0, 683432.0, 685683.0, 55424.722876386164, 55730.72134639301, 55751.0, 55758.539316854556, 55872.44127558754, 1256.7172386213213, 1388.8472268050507, 1399.6290982889543, 1434.0, 1445.3279225160352, 30233.395332092794, 30236.395272094876, 30383.088507344815, 30822.845885770428, 31139.754409824258, 49298.260526092854, 49768.75115624544, 50131.24803127883, 50365.0, 50369.49630503648, 561607.0, 562497.0, 563083.0, 563237.1838140783, 563964.0, 18813.717794232824, 25216.49567008613, 25732.742672573782, 25784.61323080193, 26555.60166597464, 40273.1945361097, 40343.596564033985, 41104.794476027426, 41337.79331103325, 41430.792846035576, 37003.88988330354, 38041.04897377477, 38269.61730382772, 38763.80618096892, 38960.0, 37988.0, 38089.809550952064, 38095.85712428632, 38486.0, 38948.2210355797, 36677.9139879407, 36711.0, 37253.441198382585, 37557.62442375541, 37604.435933460474, 31313.8434307827, 33917.49123763195, 33945.830270848484, 34217.65782342145, 34757.9572612822, 24363.634545481476, 24829.751702483467, 25057.373565661004, 25125.538198415394, 25323.74676253214], "value": 20288.333121424737, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.8496491613858, 355.1972542054955, 378.2095420571006, 389.3109196721801, 410.6263300396638, 15674.294656740445, 17502.562435939093, 22007.449813754665, 22066.448338791517, 22117.778822211814, 20572.279970201045, 20775.0, 21419.464513387175, 22781.658275125887, 22893.771062289372, 5292.470752924715, 5911.231539899818, 6112.327643959161, 6132.110843927628, 6167.0, 6028.367021462749, 6063.302720187169, 6136.785212517563, 6140.293866205377, 6140.815775526741, 5813.651180929137, 5984.760609575634, 6007.128966299884, 6008.1888944992525, 6009.609375390585, 5775.971120144414, 5939.2575928009, 6030.758769649209, 6033.0, 6036.1851150094835, 5255.632105752599, 5274.604404669627, 5278.762455689503, 5286.709230992294, 5286.947130528684, 26009.699515024364, 26535.203943881716, 26629.866850665818, 26988.325291867663, 27396.726032739618, 2189.638709612912, 2268.104098880949, 2384.4396566806786, 2415.4806716556027, 2770.792190585694, 15081.396744130221, 15609.297581608855, 15732.528024159294, 15843.60390990223, 16742.330306787713, 1824.078840185711, 1883.8775479593783, 1895.4503194073702, 1963.008680616294, 2047.2322878920404, 139638.97908592608, 141536.0, 150336.24831875772, 153828.2308588461, 158490.20754896267, 16932.492025239015, 20432.591348172955, 20794.48013799667, 21725.78274217269, 22033.449163771034, 30751.846240768653, 35515.822420888246, 35592.0, 35641.82179089088, 35663.0, 10805.40570268627, 11139.777204455866, 11188.944055279835, 11213.831792523119, 11298.491567879466, 9115.772105697411, 9162.358634895494, 9167.633294668272, 9244.768880778034, 9301.488418136933, 8179.591020448954, 8440.62017209227, 9724.270679699051, 9729.70810875675, 9929.503524823875, 2428.7085549734147, 3674.042395871296, 3839.5584507781964, 3870.9612903871166, 3875.6124387561017, 769.1731388757108, 823.9876401853977, 896.5293221058996, 956.5599824081, 966.183574879226, 18455.869206778065, 21332.253371131937, 21384.679229811252, 21835.01742421595, 21992.732071359587, 916.5463095767637, 948.7059011706393, 958.6165533786541, 962.4898803634085, 1047.4972013433442, 11119.777604448027, 11456.427178641035, 11490.046692062202, 11528.406108625491, 11565.826512602318, 511601.0, 601571.0, 602838.0, 603759.0, 618752.0, 131571.0, 132780.3360983189, 133609.0, 134037.0, 137568.0, 929.1776777551916, 932.5663566441638, 945.744648944781, 948.691675205561, 970.6069042037844, 17781.822181778534, 18874.52813679669, 19285.4214373569, 19329.4201173965, 19356.32252871143, 36868.63131368652, 51368.74315628398, 57406.7129664349, 57800.0, 57841.13238301345, 80718.59640701758, 81818.0, 82352.76472352956, 83127.0, 85439.0, 5166.560842328444, 7095.680694368662, 8117.066537348044, 8316.0, 8443.07126216128, 12804.23174609489, 13388.19670819754, 13685.31573421345, 13779.862201377859, 13822.37799299014, 7986.84026319482, 11762.764744704888, 11813.40932953366, 11832.11259155584, 11858.584949526725, 11727.35499547533, 11782.351970641697, 11784.0, 11794.351310677996, 11995.40022998864, 11672.649820505398, 11778.705532361417, 11937.0, 11969.521219151486, 11984.64046078619, 6800.285969973082, 7468.3998564535805, 7477.70089196426, 7719.536827790351, 8343.082260951169, 10033.09702126812, 11459.885401146215, 11468.885311147116, 11739.530418783495, 11821.645350639494], "value": 10723.819351151686, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1546.141891250356, 1565.6868626274745, 1723.9655206895861, 1839.5585059585703, 1853.4439668099576, 8381.413301068927, 8655.740327790161, 8820.073892241302, 8832.205101540869, 8832.558372081403, 7103.85792284154, 7764.0, 7975.521468711881, 8601.612927418266, 8649.69726059588, 1917.4343568647282, 2348.154664320843, 2348.412896775806, 2350.5181437805295, 2356.2931120663734, 1993.3421970749619, 2348.2602980061224, 2358.3396648938324, 2384.403899025244, 2393.126508824284, 1942.4269840397117, 2006.1574138861713, 2294.6672732453785, 2374.465745207332, 2376.809855211579, 2341.9765802342013, 2342.9179978700663, 2361.2680069178523, 2374.1334412939327, 2374.5725769361507, 2345.64815277708, 2349.083857295656, 2354.3290162303833, 2358.3278765551736, 2361.374235827507, 8719.912800871973, 8870.645174193025, 8883.911160888372, 8886.866696999485, 8907.421017633833, 5081.822136225214, 5277.050130976423, 5278.02356564033, 5280.947190528084, 5281.339832520935, 2929.5166297560877, 3007.0, 3473.70473509752, 3638.163222458823, 3658.506101676293, 949.7293271417612, 1037.5694086953904, 1067.5996501312009, 1091.0562363555546, 1095.5179720922768, 11218.943905280421, 11247.887521124847, 11251.0436612888, 11255.718607034807, 11347.375894325725, 4481.4174157359985, 4500.909981800346, 4714.22215334466, 4727.763611819395, 4729.574338309569, 9675.129238368581, 9678.516074196263, 9680.27397945157, 9703.466309353053, 9722.319437639297, 5245.554127899095, 5247.580193584504, 5248.868778280574, 5252.395974462986, 5256.9737151314, 4712.858614241578, 4800.423949126127, 4801.591864691471, 4810.7113573185725, 4819.3012013257885, 3890.144168282973, 3913.8238779255003, 3932.2528719543766, 3993.241284156059, 4003.6396724294955, 1186.4898093819568, 1234.3581337704343, 1273.3378643105718, 1285.119693010291, 1298.8116723075102, 770.957597332141, 811.4522697179381, 845.5949540899172, 872.5070335260557, 956.9425834449955, 5661.348944871393, 5746.224259724883, 5939.792107276225, 5950.077737950624, 6113.266408031065, 949.9050094990446, 955.1403736637083, 976.5800705696477, 981.4798156976814, 982.3074732313702, 3827.923441531154, 3940.4483372327895, 3945.151792364598, 4061.289274376972, 4078.34746440574, 9850.80298394028, 10516.158707303397, 10517.106045986176, 10519.21105917044, 10531.894681053245, 9622.470764107902, 9907.157891579152, 10319.1744660427, 10471.0, 10471.267011309135, 7870.606469676493, 8042.195780421911, 8056.436049476598, 8071.717489887826, 8073.959630201929, 5861.7655293787775, 5900.144479050599, 5942.910856337072, 6053.969730151321, 6217.347178546368, 9396.577154028222, 9880.35777674449, 9905.405675659484, 9951.253655975976, 10009.299349045497, 9824.06671366219, 10094.242931780293, 10102.898971010196, 10131.13885319756, 10161.745956350855, 7092.645367731489, 7346.265373462825, 7403.703851845866, 7431.071116110486, 7507.211742767149, 7898.68405263783, 7966.880496792437, 8002.399820013406, 8013.4791238569305, 8021.15777843318, 7281.344678978706, 7362.852742945004, 7372.852542949004, 7468.925310746823, 7558.735444259644, 7036.472264580279, 7222.674979626035, 7326.120865496175, 7365.263473652806, 7481.88777168332, 6836.521443499107, 6962.825929351807, 6970.407515361048, 6970.895436568354, 6974.163100427778, 4675.76621168947, 4753.786079626356, 4889.828855990023, 4894.290327902506, 4978.651494395358, 4917.852464426073, 4941.283513890394, 4950.430700469492, 4952.628552858622, 5052.494750524918], "value": 4523.812274179594, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.6618253738351, 310.36994900352295, 314.5266374106969, 319.5749652961561, 321.5546468141626, 5255.290535777669, 5383.407825139237, 5411.485908838654, 5412.539934105601, 5419.403865574784, 4647.814087436498, 5135.871603209922, 5346.331708536433, 5428.348598168215, 5441.59188060895, 2885.769138468928, 3420.734328298533, 3960.3069462844032, 4049.412835138903, 4179.582041795811, 3651.434027725707, 3686.9123608535547, 4123.195976784528, 4150.522689890671, 4212.347086201628, 3381.98771730821, 4096.426500289962, 4110.177964407116, 4152.937705934413, 4172.955251926549, 3868.0, 3890.513685789276, 3991.7405368650943, 4061.715679902408, 4171.395147703566, 3139.0, 3541.8029634882746, 3545.2170046513365, 3560.626134255918, 3606.1886075633033, 4347.826086956518, 5246.449122842123, 5261.579373569115, 5295.655782374133, 5353.03645343838, 4503.144402563503, 4569.983810684932, 4595.128044971892, 4692.319613656035, 4707.835225767081, 4939.209726443788, 5138.2549530318265, 5151.253068305112, 5645.774169033234, 5648.661080335193, 3483.9129021774406, 3761.4733937248807, 3903.882883513499, 3960.5247370315747, 4011.4785077939973, 5434.212039254286, 5758.107493338539, 5963.940360596339, 6004.78983235589, 6066.272047354301, 3114.813111213289, 3424.8972530824117, 3982.9402558961638, 4002.1795531915886, 4067.613576710208, 3304.8843290485197, 3628.32875917956, 4127.525334586501, 4129.793510324472, 4130.855420060284, 3698.9458004468597, 4295.871123866289, 4383.693141480129, 4530.954690453119, 4778.092162489185, 5043.99120175959, 5611.7474713637985, 5643.294588176478, 5671.602987790898, 5731.77072917087, 1334.3595074364166, 1352.6280272925023, 1355.7120735301503, 1361.5574938145141, 1361.6323592629935, 795.9562224077731, 821.5070957425497, 824.9523105655784, 836.9707060252862, 839.5676226743232, 4284.0146766243315, 4451.755153466591, 5268.788178718916, 5281.577473802086, 5286.788528458896, 4626.074785043005, 5708.514776243983, 5758.942410575924, 5818.4763371296785, 5910.202122713465, 4370.257056300373, 4490.497412018631, 4496.64026877849, 5342.305500284938, 5359.946400536022, 2856.0, 2890.309169136079, 2920.4597149527403, 3038.9240268993008, 3041.7832866853437, 5155.484451554815, 5311.867203319948, 5724.427557244242, 6032.125341825411, 6050.152978583002, 5618.016847051749, 5957.374475680078, 6036.227357901312, 6068.726907289182, 6071.605345652607, 4568.428946381769, 4618.782984167618, 6040.822039702216, 6048.909266361008, 6099.451049405486, 5608.383077861514, 5857.7698683275285, 6002.939970600238, 6005.099235114768, 6029.427204415573, 5285.445028271976, 5481.451854814487, 5790.0627573250595, 5792.771276967753, 5815.360310365941, 5083.442359356389, 5196.558292545176, 5249.737513124405, 6037.63774173551, 6116.724747386289, 3465.809380484098, 3558.7900113961377, 3665.395209790461, 3693.8891833245048, 3966.5835087315418, 4774.4509381421585, 5776.162456443876, 5833.708314584169, 5842.970785146047, 5844.590878638454, 5350.0, 5413.133898576209, 5688.951977288256, 5697.401772813795, 5765.740541675551, 5372.952274306629, 5584.5986225921615, 5608.38307786135, 5749.511291540266, 5765.164051212634, 4572.536788227735, 5064.392272927125, 5469.343678758574, 5470.370907345706, 5514.310711161105, 4769.47376839408, 4856.479052686774, 5169.508896654812, 5461.644993075437, 5589.55283577321, 4617.907641847077, 5001.474550262205, 5442.700651464207, 5518.172274158908, 5642.322921249476], "value": 4075.367046978744, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.6373639552663, 812.3541784281496, 827.3174630929481, 835.9958200208998, 872.3936863879602, 12239.877601223985, 15741.134237616941, 15742.212889355515, 15744.897857149976, 16262.268197931093, 14264.857351426483, 14657.853421465783, 14856.0, 14874.702505949901, 14880.553583392488, 865.3466632692324, 867.3581549653243, 869.7782065573269, 870.0, 873.8077622922947, 866.5017614871442, 879.8636211387213, 880.0451510111536, 881.0704706534603, 881.9779505512381, 928.4661319741142, 930.4463844012802, 931.4504442378995, 932.7388331267222, 933.5518950903537, 783.4280974888363, 916.9724908252763, 922.9815403691956, 923.4182465047045, 926.1248120526105, 688.8036909480824, 689.3726708695064, 691.1705952856541, 691.512483698991, 693.2028167607242, 12852.742945141139, 13029.739405211843, 13292.936565074771, 13294.40175192109, 13312.134711243834, 12114.152009359348, 12194.512219511209, 12306.384680765927, 12353.0, 12476.937615311956, 11021.83467247992, 11050.447477626087, 11081.113510919107, 11434.656960291204, 11447.942760286145, 3761.755485893408, 3773.3019391412677, 3823.617638236182, 3945.4279129526344, 3957.564667886559, 109146.90853091526, 112262.0, 113648.86351136466, 115284.0, 117381.41309293399, 9406.294527910433, 9657.32398732082, 9657.565409556586, 10560.313579617301, 11025.889741102486, 18237.27050917975, 19343.709844352346, 20914.47713807167, 21521.89239053795, 21846.890765546388, 7752.961235193788, 7776.416768742367, 7796.649150788228, 7831.021122359705, 7911.683532658629, 4296.720713153635, 4486.12520558488, 4490.865274041784, 4492.842750503782, 4590.081983603226, 3413.8805141819917, 3528.788272703646, 3532.2405682778326, 3542.4686297055646, 3542.71658267338, 642.9003504456816, 643.1767337807614, 651.8631087471684, 653.8267359149829, 658.7694306992514, 559.2980809084573, 615.1664494609829, 645.8837409266378, 646.0374042676473, 663.2107791727828, 8031.558264295403, 8754.29965602763, 8756.824863502694, 8822.073682263399, 8935.106489351143, 553.7812564037183, 590.3004939147039, 607.8389226854887, 646.0664340028613, 679.3478260869621, 4443.533428989974, 4547.090581883636, 4565.132624801344, 4670.486246512881, 4697.81208751653, 146022.0, 146617.0, 151456.0, 152984.0, 153425.23287383714, 90404.54797725969, 117310.41344793393, 117968.0, 124562.0, 129288.0, 74078.0, 107012.0, 107217.0, 109252.4537377308, 110347.44826275817, 8450.450720703135, 8532.360072994426, 8535.658573656985, 8604.311655067704, 9645.517724113906, 59120.0, 60164.796704066546, 61629.691851540454, 62929.68535157478, 63933.36899518692, 57637.42362576488, 73947.63026184835, 93416.53291733497, 95534.0, 98095.0, 12893.355332233163, 13304.534341298395, 13882.722345552831, 13975.301234938415, 14115.929420352833, 12742.49030038788, 15414.0, 15849.969751966086, 15896.205189740698, 15931.522054338388, 11717.882821172021, 13684.93157534206, 13949.651258718613, 14372.640683982983, 14605.634859128182, 12110.939445303069, 14029.438822447397, 14059.297035148405, 14347.641308966942, 14665.56003319902, 13648.317584120952, 13946.79079813824, 13972.0, 14019.228942408265, 14032.859671403156, 11367.886321136682, 11487.25332853362, 11526.423678816192, 11883.465244063866, 12152.848215567044, 11325.730435475158, 11495.425228738697, 11628.6511404658, 11723.0, 11751.647450576496], "value": 7722.923624148023, "unit": "score"}], "name": "dromaeo_css", "value": 7486.4760589345815, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 113.0, 112.0, 112.0, 114.0, 113.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 108.0, 155.0, 106.0, 111.0, 114.0, 110.0, 179.0, 138.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 182.0, 184.0, 183.0, 184.0, 191.0, 228.0, 254.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 58.0, 86.0, 58.0, 59.0, 80.0, 54.0, 77.0, 48.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 94.0, 72.0, 74.0, 70.0, 96.0, 70.0, 68.0, 97.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 86.0, 87.0, 96.0, 88.0, 84.0, 87.0, 87.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 101.0, 135.0, 93.0, 94.0, 93.0, 92.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 87.0, 87.0, 85.0, 84.0, 84.0, 85.0, 86.0, 84.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 53.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 80.0, 74.0, 72.0, 70.0, 70.0, 73.0, 104.0, 103.0, 88.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 80.0, 85.0, 85.0, 86.0, 85.0, 86.0, 85.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 152.0, 154.0, 135.0, 135.0, 158.0, 127.0, 140.0, 205.0, 157.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 187.0, 169.0, 169.0, 166.0, 168.0, 225.0, 167.0, 167.0, 165.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 68.0, 67.0, 66.0, 67.0, 68.0, 66.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1470.5, "alertThreshold": 2.0}]} 05:54:31 INFO - Return code: 0 05:54:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:54:31 INFO - # TBPL SUCCESS # 05:54:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:54:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:54:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:54:31 INFO - 2016-07-07 05:54:31.721 screenresolution[1667:9931] starting screenresolution argv=screenresolution get 05:54:31 INFO - 2016-07-07 05:54:31.724 screenresolution[1667:9931] Display 0: 1600x1200x32@60 05:54:31 INFO - 2016-07-07 05:54:31.730 screenresolution[1668:9934] starting screenresolution argv=screenresolution list 05:54:31 INFO - Available Modes on Display 0 05:54:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:54:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:54:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:54:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:54:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:54:31 INFO - Intel Iris: 05:54:31 INFO - Chipset Model: Intel Iris 05:54:31 INFO - Type: GPU 05:54:31 INFO - Bus: Built-In 05:54:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:54:31 INFO - Vendor: Intel (0x8086) 05:54:31 INFO - Device ID: 0x0a2e 05:54:31 INFO - Revision ID: 0x0009 05:54:31 INFO - Displays: 05:54:31 INFO - Display: 05:54:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:54:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:54:31 INFO - Display Serial Number: 1600x1200 60 05:54:31 INFO - Main Display: Yes 05:54:31 INFO - Mirror: Off 05:54:31 INFO - Online: Yes 05:54:31 INFO - Rotation: Supported 05:54:31 INFO - Return code: 0 05:54:31 INFO - Running post-action listener: _resource_record_post_action 05:54:31 INFO - ##### 05:54:31 INFO - ##### Finished run-tests step (success) 05:54:31 INFO - ##### 05:54:31 INFO - Running post-run listener: _resource_record_post_run 05:54:32 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 31567360; Write bytes: 705621504; Read time: 194; Write time: 1967 05:54:32 INFO - TinderboxPrint: CPU usage
53.4% 05:54:32 INFO - TinderboxPrint: I/O read bytes / time
31,567,360 / 194 05:54:32 INFO - TinderboxPrint: I/O write bytes / time
705,621,504 / 1,967 05:54:32 INFO - TinderboxPrint: CPU idle
2,261.1 (46.6%) 05:54:32 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 05:54:32 INFO - TinderboxPrint: CPU user
1,623.4 (33.5%) 05:54:32 INFO - TinderboxPrint: Swap in / out
434,491,392 / 0 05:54:32 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390373888; Write bytes: 383825408; Read time: 29544; Write time: 826 05:54:32 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30645760; Write bytes: 296667136; Read time: 184; Write time: 1083 05:54:32 INFO - Running post-run listener: _upload_blobber_files 05:54:32 INFO - Blob upload gear active. 05:54:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:54:32 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:54:32 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:54:32 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:54:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:54:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:54:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:54:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:54:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:54:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:54:33 INFO - (blobuploader) - INFO - Done attempting. 05:54:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:54:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:54:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:54:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:54:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:54:34 INFO - (blobuploader) - INFO - Done attempting. 05:54:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:54:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:54:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:54:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:54:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:54:37 INFO - (blobuploader) - INFO - Done attempting. 05:54:37 INFO - (blobuploader) - INFO - Iteration through files over. 05:54:37 INFO - Return code: 0 05:54:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:54:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:54:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c66f0d4fa736934bef3477f828a415a215cd9e216f21d20025cb10da73ddf3e3fe6a52bc94dad8db280932aa853d81cbe0b73b3b146bd293a0a4b943610f235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed5aa698b3cc35a030a9020ac8783b1f22a144a83300a3ad9982c102b7bbe3d70007201b48e86f0acc082bf15b54c672f9578a057205297039a170d16c06901b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0013258efcdbed95363b3638375794b6213ddf961924fddb245e5dbeaea87e67e6befee26076372f0a2f6e2fbdd884a5c94586293eee655221580e640917d23"} 05:54:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:54:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:54:37 INFO - Contents: 05:54:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c66f0d4fa736934bef3477f828a415a215cd9e216f21d20025cb10da73ddf3e3fe6a52bc94dad8db280932aa853d81cbe0b73b3b146bd293a0a4b943610f235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed5aa698b3cc35a030a9020ac8783b1f22a144a83300a3ad9982c102b7bbe3d70007201b48e86f0acc082bf15b54c672f9578a057205297039a170d16c06901b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0013258efcdbed95363b3638375794b6213ddf961924fddb245e5dbeaea87e67e6befee26076372f0a2f6e2fbdd884a5c94586293eee655221580e640917d23"} 05:54:37 INFO - Running post-run listener: copy_logs_to_upload_dir 05:54:37 INFO - Copying logs to upload dir... 05:54:37 INFO - mkdir: /builds/slave/test/build/upload/logs 05:54:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1325.495771 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-07-07 05:54:37.251623) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 05:54:37.255178) ========= 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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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/1c66f0d4fa736934bef3477f828a415a215cd9e216f21d20025cb10da73ddf3e3fe6a52bc94dad8db280932aa853d81cbe0b73b3b146bd293a0a4b943610f235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed5aa698b3cc35a030a9020ac8783b1f22a144a83300a3ad9982c102b7bbe3d70007201b48e86f0acc082bf15b54c672f9578a057205297039a170d16c06901b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0013258efcdbed95363b3638375794b6213ddf961924fddb245e5dbeaea87e67e6befee26076372f0a2f6e2fbdd884a5c94586293eee655221580e640917d23"} build_url:https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010813 build_url: 'https://queue.taskcluster.net/v1/task/NRXfs9SbTGmIZN9aLmuLVQ/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/1c66f0d4fa736934bef3477f828a415a215cd9e216f21d20025cb10da73ddf3e3fe6a52bc94dad8db280932aa853d81cbe0b73b3b146bd293a0a4b943610f235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed5aa698b3cc35a030a9020ac8783b1f22a144a83300a3ad9982c102b7bbe3d70007201b48e86f0acc082bf15b54c672f9578a057205297039a170d16c06901b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0013258efcdbed95363b3638375794b6213ddf961924fddb245e5dbeaea87e67e6befee26076372f0a2f6e2fbdd884a5c94586293eee655221580e640917d23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 05:54:37.286845) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 05:54:37.287169) ========= 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.6IuLcuDRkL/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.zUUN4XQ2Rr/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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.005097 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 05:54:37.335671) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 05:54:37.336009) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 05:54:37.336419) ========= ========= Total master_lag: 0.35 =========