builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0036 starttime: 1469625785.4 results: success (0) buildid: 20160704150257 builduid: 288e0872bed146679b0a067544a79c15 revision: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.403619) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.404115) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.404415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.514890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.515256) ========= 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.49cX6uzle5/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.BjP53ECy4k/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-27 06:23:05-- 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% 8.07M=0.001s 2016-07-27 06:23:05 (8.07 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149973 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.690650) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.690939) ========= 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.49cX6uzle5/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.BjP53ECy4k/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.020570 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.740824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:05.741176) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 --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 c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.49cX6uzle5/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.BjP53ECy4k/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-27 06:23:05,813 truncating revision to first 12 chars 2016-07-27 06:23:05,813 Setting DEBUG logging. 2016-07-27 06:23:05,813 attempt 1/10 2016-07-27 06:23:05,813 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c9a70b64f2fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 06:23:06,478 unpacking tar archive at: mozilla-central-c9a70b64f2fa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.934459 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.697798) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.698175) ========= script_repo_revision: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.698687) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.698965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 06:23:06.717885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-27 06:23:06.718297) ========= /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.49cX6uzle5/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.BjP53ECy4k/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:06 INFO - MultiFileLogger online at 20160727 06:23:06 in /builds/slave/test 06:23:06 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 06:23:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:23:06 INFO - {'append_to_log': False, 06:23:06 INFO - 'base_work_dir': '/builds/slave/test', 06:23:06 INFO - 'blob_upload_branch': 'Firefox', 06:23:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:23:06 INFO - 'branch': 'Firefox', 06:23:06 INFO - 'buildbot_json_path': 'buildprops.json', 06:23:06 INFO - 'config_files': ('talos/mac_config.py',), 06:23:06 INFO - 'default_actions': ('clobber', 06:23:06 INFO - 'read-buildbot-config', 06:23:06 INFO - 'download-and-extract', 06:23:06 INFO - 'populate-webroot', 06:23:06 INFO - 'create-virtualenv', 06:23:06 INFO - 'install', 06:23:06 INFO - 'run-tests'), 06:23:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:23:06 INFO - 'download_minidump_stackwalk': True, 06:23:06 INFO - 'download_symbols': 'ondemand', 06:23:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:23:06 INFO - 'tooltool.py': '/tools/tooltool.py', 06:23:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:23:06 INFO - '/tools/misc-python/virtualenv.py')}, 06:23:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:23:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:23:06 INFO - 'installer_path': 'installer.exe', 06:23:06 INFO - 'log_level': 'info', 06:23:06 INFO - 'log_name': 'talos', 06:23:06 INFO - 'log_to_console': True, 06:23:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:23:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:23:06 INFO - 'opt_config_files': (), 06:23:06 INFO - 'pip_index': False, 06:23:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:23:06 INFO - 'cmd': ('bash', 06:23:06 INFO - '-c', 06:23:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:23:06 INFO - 'enabled': True, 06:23:06 INFO - 'halt_on_failure': False, 06:23:06 INFO - 'name': 'check_screen_resolution'},), 06:23:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:23:06 INFO - 'cmd': ('bash', 06:23:06 INFO - '-c', 06:23:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:23:06 INFO - 'enabled': True, 06:23:06 INFO - 'halt_on_failure': False, 06:23:06 INFO - 'name': 'check_screen_resolution'},), 06:23:06 INFO - 'run_cmd_checks_enabled': True, 06:23:06 INFO - 'sps_profile': False, 06:23:06 INFO - 'sps_profile_interval': 0, 06:23:06 INFO - 'suite': 'dromaeojs', 06:23:06 INFO - 'system_bits': '32', 06:23:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:23:06 INFO - 'title': 't-yosemite-r7-0036', 06:23:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:23:06 INFO - 'use_talos_json': True, 06:23:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:23:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:23:06 INFO - 'work_dir': 'build'} 06:23:06 INFO - ##### 06:23:06 INFO - ##### Running clobber step. 06:23:06 INFO - ##### 06:23:06 INFO - Running pre-action listener: _resource_record_pre_action 06:23:06 INFO - Running main action method: clobber 06:23:06 INFO - rmtree: /builds/slave/test/build 06:23:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:23:07 INFO - Running post-action listener: _resource_record_post_action 06:23:07 INFO - ##### 06:23:07 INFO - ##### Finished clobber step (success) 06:23:07 INFO - ##### 06:23:07 INFO - ##### 06:23:07 INFO - ##### Running read-buildbot-config step. 06:23:07 INFO - ##### 06:23:07 INFO - Running pre-action listener: _resource_record_pre_action 06:23:07 INFO - Running main action method: read_buildbot_config 06:23:07 INFO - Using buildbot properties: 06:23:07 INFO - { 06:23:07 INFO - "project": "", 06:23:07 INFO - "product": "firefox", 06:23:07 INFO - "script_repo_revision": "production", 06:23:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:23:07 INFO - "repository": "", 06:23:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:23:07 INFO - "buildid": "20160704150257", 06:23:07 INFO - "pgo_build": "False", 06:23:07 INFO - "basedir": "/builds/slave/test", 06:23:07 INFO - "buildnumber": 21, 06:23:07 INFO - "slavename": "t-yosemite-r7-0036", 06:23:07 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:23:07 INFO - "platform": "yosemite_r7", 06:23:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:23:07 INFO - "branch": "mozilla-central", 06:23:07 INFO - "repo_path": "mozilla-central", 06:23:07 INFO - "revision": "c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5", 06:23:07 INFO - "stage_platform": "macosx64", 06:23:07 INFO - "builduid": "288e0872bed146679b0a067544a79c15", 06:23:07 INFO - "slavebuilddir": "test" 06:23:07 INFO - } 06:23:07 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg. 06:23:07 INFO - Running post-action listener: _resource_record_post_action 06:23:07 INFO - ##### 06:23:07 INFO - ##### Finished read-buildbot-config step (success) 06:23:07 INFO - ##### 06:23:07 INFO - ##### 06:23:07 INFO - ##### Running download-and-extract step. 06:23:07 INFO - ##### 06:23:07 INFO - Running pre-action listener: _resource_record_pre_action 06:23:07 INFO - Running main action method: download_and_extract 06:23:07 INFO - mkdir: /builds/slave/test/build/tests 06:23:07 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')]} 06:23:07 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.test_packages.json 06:23:07 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:23:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/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 06:23:08 INFO - Downloaded 1199 bytes. 06:23:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:23:08 INFO - Using the following test package requirements: 06:23:08 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:23:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 06:23:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 06:23:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'jsshell-mac.zip'], 06:23:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 06:23:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:23:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 06:23:08 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 06:23:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 06:23:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:23:08 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 06:23:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:23: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')]} 06:23:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/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 06:23:08 INFO - Downloaded 18800192 bytes. 06:23:08 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'] 06:23:08 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 06:23:11 INFO - Return code: 0 06:23:11 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 06:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:11 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:11 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:23:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/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 06:23:12 INFO - Downloaded 18800192 bytes. 06:23:12 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'] 06:23:12 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 06:23:15 INFO - Return code: 0 06:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:23:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.talos.tests.zip 06:23:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:23:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/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 06:23:18 INFO - Downloaded 14403333 bytes. 06:23:18 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'] 06:23:18 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 06:23:19 INFO - Return code: 0 06:23:19 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')]} 06:23:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg 06:23:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 06:23:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 06:23:25 INFO - Downloaded 112425084 bytes. 06:23:25 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg 06:23:25 INFO - mkdir: /builds/slave/test/properties 06:23:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:23:25 INFO - Writing to file /builds/slave/test/properties/build_url 06:23:25 INFO - Contents: 06:23:25 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg 06:23:25 INFO - Running post-action listener: _resource_record_post_action 06:23:25 INFO - Running post-action listener: set_extra_try_arguments 06:23:25 INFO - ##### 06:23:25 INFO - ##### Finished download-and-extract step (success) 06:23:25 INFO - ##### 06:23:25 INFO - ##### 06:23:25 INFO - ##### Running populate-webroot step. 06:23:25 INFO - ##### 06:23:25 INFO - Running pre-action listener: _resource_record_pre_action 06:23:25 INFO - Running main action method: populate_webroot 06:23:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:23:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:23:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:23:25 INFO - '--tppagecycles', 06:23:25 INFO - '7'], 06:23:25 INFO - 'tests': ['tsvgm']}}, 06:23:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:23:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:23:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:23:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:23:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:23:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:23:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['damp', 'tps']}, 06:23:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'tests': ['damp', 'tps']}, 06:23:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['dromaeo_dom']}, 06:23:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:23:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['basic_compositor_video']}, 06:23:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:23:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other-e10s': {'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['a11yr', 06:23:25 INFO - 'ts_paint', 06:23:25 INFO - 'tpaint', 06:23:25 INFO - 'sessionrestore', 06:23:25 INFO - 'sessionrestore_no_auto_restore', 06:23:25 INFO - 'tabpaint']}, 06:23:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tsvgx', 06:23:25 INFO - 'tsvgr_opacity', 06:23:25 INFO - 'tart', 06:23:25 INFO - 'tscrollx', 06:23:25 INFO - 'cart']}, 06:23:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:23:25 INFO - 'tsvgr_opacity', 06:23:25 INFO - 'tart', 06:23:25 INFO - 'tscrollx', 06:23:25 INFO - 'cart']}, 06:23:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s'], 06:23:25 INFO - 'tests': ['tp5o']}, 06:23:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'tests': ['tp5o']}, 06:23:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--disable-e10s', 06:23:25 INFO - '--xperf_path', 06:23:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:23:25 INFO - 'tests': ['tp5n']}, 06:23:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:23:25 INFO - 'talos_options': ['--xperf_path', 06:23:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:23:25 INFO - 'tests': ['tp5n']}}, 06:23:25 INFO - 'talos.zip': {'path': '', 06:23:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:23:25 INFO - Running post-action listener: _resource_record_post_action 06:23:25 INFO - ##### 06:23:25 INFO - ##### Finished populate-webroot step (success) 06:23:25 INFO - ##### 06:23:25 INFO - ##### 06:23:25 INFO - ##### Running create-virtualenv step. 06:23:25 INFO - ##### 06:23:25 INFO - Running pre-action listener: _resource_record_pre_action 06:23:25 INFO - Running main action method: create_virtualenv 06:23:25 INFO - Creating virtualenv /builds/slave/test/build/venv 06:23:25 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 06:23:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:23:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:23:26 INFO - Using real prefix '/tools/python27' 06:23:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:23:26 INFO - Installing distribute.............................................................................................................................................................................................done. 06:23:29 INFO - Installing pip.................done. 06:23:29 INFO - Return code: 0 06:23:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:23:29 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')]} 06:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 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')]} 06:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 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 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:29 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 06:23:29 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 06:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:29 INFO - 'HOME': '/Users/cltbld', 06:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:29 INFO - 'LOGNAME': 'cltbld', 06:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:29 INFO - 'MOZ_NO_REMOTE': '1', 06:23:29 INFO - 'NO_EM_RESTART': '1', 06:23:29 INFO - 'PAGER': '/bin/cat', 06:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:29 INFO - 'PWD': '/builds/slave/test', 06:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:29 INFO - 'SHELL': '/bin/bash', 06:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:29 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:29 INFO - 'USER': 'cltbld', 06:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:29 INFO - 'XPC_FLAGS': '0x0', 06:23:29 INFO - 'XPC_SERVICE_NAME': '0', 06:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:29 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 06:23:29 INFO - Cleaning up... 06:23:29 INFO - Return code: 0 06:23:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:23:29 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')]} 06:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 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')]} 06:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:29 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 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:29 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 06:23:29 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 06:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:29 INFO - 'HOME': '/Users/cltbld', 06:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:29 INFO - 'LOGNAME': 'cltbld', 06:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:29 INFO - 'MOZ_NO_REMOTE': '1', 06:23:29 INFO - 'NO_EM_RESTART': '1', 06:23:29 INFO - 'PAGER': '/bin/cat', 06:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:29 INFO - 'PWD': '/builds/slave/test', 06:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:29 INFO - 'SHELL': '/bin/bash', 06:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:29 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:29 INFO - 'USER': 'cltbld', 06:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:29 INFO - 'XPC_FLAGS': '0x0', 06:23:29 INFO - 'XPC_SERVICE_NAME': '0', 06:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:30 INFO - Downloading/unpacking psutil>=3.1.1 06:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:23:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:23:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:23:33 INFO - Installing collected packages: psutil 06:23:33 INFO - Running setup.py install for psutil 06:23:34 INFO - building 'psutil._psutil_osx' extension 06:23:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:23:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:23:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:23:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:23:34 INFO - building 'psutil._psutil_posix' extension 06:23:34 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 06:23:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:23:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:23:34 INFO - ^ 06:23:34 INFO - 1 warning generated. 06:23:34 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 06:23:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:23:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:23:34 INFO - Successfully installed psutil 06:23:34 INFO - Cleaning up... 06:23:34 INFO - Return code: 0 06:23:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:23: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')]} 06:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23: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')]} 06:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:34 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 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:34 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 06:23:34 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 06:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:34 INFO - 'HOME': '/Users/cltbld', 06:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:34 INFO - 'LOGNAME': 'cltbld', 06:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:34 INFO - 'MOZ_NO_REMOTE': '1', 06:23:34 INFO - 'NO_EM_RESTART': '1', 06:23:34 INFO - 'PAGER': '/bin/cat', 06:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:34 INFO - 'PWD': '/builds/slave/test', 06:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:34 INFO - 'SHELL': '/bin/bash', 06:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:34 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:34 INFO - 'USER': 'cltbld', 06:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:34 INFO - 'XPC_FLAGS': '0x0', 06:23:34 INFO - 'XPC_SERVICE_NAME': '0', 06:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:23:37 INFO - Installing collected packages: mozsystemmonitor 06:23:37 INFO - Running setup.py install for mozsystemmonitor 06:23:37 INFO - Successfully installed mozsystemmonitor 06:23:37 INFO - Cleaning up... 06:23:37 INFO - Return code: 0 06:23:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:37 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 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:37 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 06:23:37 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 06:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:37 INFO - 'HOME': '/Users/cltbld', 06:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:37 INFO - 'LOGNAME': 'cltbld', 06:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:37 INFO - 'MOZ_NO_REMOTE': '1', 06:23:37 INFO - 'NO_EM_RESTART': '1', 06:23:37 INFO - 'PAGER': '/bin/cat', 06:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:37 INFO - 'PWD': '/builds/slave/test', 06:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:37 INFO - 'SHELL': '/bin/bash', 06:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:37 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:37 INFO - 'USER': 'cltbld', 06:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:37 INFO - 'XPC_FLAGS': '0x0', 06:23:37 INFO - 'XPC_SERVICE_NAME': '0', 06:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:38 INFO - Downloading/unpacking jsonschema==2.5.1 06:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:23:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:23:40 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 06:23:40 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 06:23:40 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 06:23:40 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 06:23:40 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 06:23:40 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 06:23:40 INFO - Downloading functools32-3.2.3-2.tar.gz 06:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:23:41 INFO - warning: no files found matching '*.txt' 06:23:41 INFO - no previously-included directories found matching 'build' 06:23:41 INFO - no previously-included directories found matching 'dist' 06:23:41 INFO - no previously-included directories found matching '.git*' 06:23:41 INFO - Installing collected packages: jsonschema, functools32 06:23:41 INFO - Running setup.py install for functools32 06:23:41 INFO - warning: no files found matching '*.txt' 06:23:41 INFO - no previously-included directories found matching 'build' 06:23:41 INFO - no previously-included directories found matching 'dist' 06:23:41 INFO - no previously-included directories found matching '.git*' 06:23:41 INFO - Successfully installed jsonschema functools32 06:23:41 INFO - Cleaning up... 06:23:41 INFO - Return code: 0 06:23:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:41 INFO - 'HOME': '/Users/cltbld', 06:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:41 INFO - 'LOGNAME': 'cltbld', 06:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:41 INFO - 'MOZ_NO_REMOTE': '1', 06:23:41 INFO - 'NO_EM_RESTART': '1', 06:23:41 INFO - 'PAGER': '/bin/cat', 06:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:41 INFO - 'PWD': '/builds/slave/test', 06:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:41 INFO - 'SHELL': '/bin/bash', 06:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:41 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:41 INFO - 'USER': 'cltbld', 06:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:41 INFO - 'XPC_FLAGS': '0x0', 06:23:41 INFO - 'XPC_SERVICE_NAME': '0', 06:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:41 INFO - Downloading/unpacking blobuploader==1.2.4 06:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:43 INFO - Downloading blobuploader-1.2.4.tar.gz 06:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:23:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:23:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:45 INFO - Downloading docopt-0.6.1.tar.gz 06:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:23:45 INFO - Installing collected packages: blobuploader, requests, docopt 06:23:45 INFO - Running setup.py install for blobuploader 06:23:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:23:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:23:45 INFO - Running setup.py install for requests 06:23:45 INFO - Running setup.py install for docopt 06:23:46 INFO - Successfully installed blobuploader requests docopt 06:23:46 INFO - Cleaning up... 06:23:46 INFO - Return code: 0 06:23:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:23:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:23:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:46 INFO - 'HOME': '/Users/cltbld', 06:23:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:46 INFO - 'LOGNAME': 'cltbld', 06:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:46 INFO - 'MOZ_NO_REMOTE': '1', 06:23:46 INFO - 'NO_EM_RESTART': '1', 06:23:46 INFO - 'PAGER': '/bin/cat', 06:23:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:46 INFO - 'PWD': '/builds/slave/test', 06:23:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:46 INFO - 'SHELL': '/bin/bash', 06:23:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:46 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:46 INFO - 'USER': 'cltbld', 06:23:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:46 INFO - 'XPC_FLAGS': '0x0', 06:23:46 INFO - 'XPC_SERVICE_NAME': '0', 06:23:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:23:46 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-5hoDS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:23:46 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-rh3hq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:23:46 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-AMgW5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:23:46 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-QFaTxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:23:46 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ScDp0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:23:46 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-yS1of_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-GG37RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-vKYJj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-MUDZ2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-pxqIxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-427fcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-gEvL1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-J9rzFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-qz9ZCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-169LkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:23:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-oCf3cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:23:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Y9OgaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:23:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:23:48 INFO - Running setup.py install for manifestparser 06:23:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Running setup.py install for mozcrash 06:23:48 INFO - Running setup.py install for mozdebug 06:23:48 INFO - Running setup.py install for mozdevice 06:23:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:23:48 INFO - Running setup.py install for mozfile 06:23:49 INFO - Running setup.py install for mozhttpd 06:23:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for mozinfo 06:23:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for mozInstall 06:23:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for mozleak 06:23:49 INFO - Running setup.py install for mozlog 06:23:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for moznetwork 06:23:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:23:49 INFO - Running setup.py install for mozprocess 06:23:50 INFO - Running setup.py install for mozprofile 06:23:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:23:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:23:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:23:50 INFO - Running setup.py install for mozrunner 06:23:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:23:50 INFO - Running setup.py install for mozscreenshot 06:23:50 INFO - Running setup.py install for moztest 06:23:50 INFO - Running setup.py install for mozversion 06:23:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:23:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:23:50 INFO - Cleaning up... 06:23:50 INFO - Return code: 0 06:23:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:50 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')]} 06:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:50 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')]} 06:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:50 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 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:50 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 06:23:50 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 06:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:50 INFO - 'HOME': '/Users/cltbld', 06:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:50 INFO - 'LOGNAME': 'cltbld', 06:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:50 INFO - 'MOZ_NO_REMOTE': '1', 06:23:50 INFO - 'NO_EM_RESTART': '1', 06:23:50 INFO - 'PAGER': '/bin/cat', 06:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:50 INFO - 'PWD': '/builds/slave/test', 06:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:50 INFO - 'SHELL': '/bin/bash', 06:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:50 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:50 INFO - 'USER': 'cltbld', 06:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:50 INFO - 'XPC_FLAGS': '0x0', 06:23:50 INFO - 'XPC_SERVICE_NAME': '0', 06:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-A51SDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ZJi9yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-TCb_zK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Pzxqzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-W9GvBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-hOMLfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Cb6eEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Ps4Yzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:23:51 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)) 06:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:23:51 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-v2MUqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:23:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ilNh9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:23:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ukuejo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:23:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-EbTJcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:23:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-zopj9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:23:52 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)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-DaTXnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:23:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Ek_XvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:52 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)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-cfUqOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:23:52 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)) 06:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:23:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-t0K3Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 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)) 06:23:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23: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 06:23:55 INFO - Downloading blessings-1.6.tar.gz 06:23:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:23:55 INFO - Installing collected packages: blessings 06:23:55 INFO - Running setup.py install for blessings 06:23:55 INFO - Successfully installed blessings 06:23:55 INFO - Cleaning up... 06:23:55 INFO - Return code: 0 06:23:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:23:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:23:55 INFO - Reading from file tmpfile_stdout 06:23:55 INFO - Current package versions: 06:23:55 INFO - blessings == 1.6 06:23:55 INFO - blobuploader == 1.2.4 06:23:55 INFO - docopt == 0.6.1 06:23:55 INFO - functools32 == 3.2.3-2 06:23:55 INFO - jsonschema == 2.5.1 06:23:55 INFO - manifestparser == 1.1 06:23:55 INFO - mozInstall == 1.12 06:23:55 INFO - mozcrash == 0.17 06:23:55 INFO - mozdebug == 0.1 06:23:55 INFO - mozdevice == 0.48 06:23:55 INFO - mozfile == 1.2 06:23:55 INFO - mozhttpd == 0.7 06:23:55 INFO - mozinfo == 0.9 06:23:55 INFO - mozleak == 0.1 06:23:55 INFO - mozlog == 3.2 06:23:55 INFO - moznetwork == 0.27 06:23:55 INFO - mozprocess == 0.22 06:23:55 INFO - mozprofile == 0.28 06:23:55 INFO - mozrunner == 6.11 06:23:55 INFO - mozscreenshot == 0.1 06:23:55 INFO - mozsystemmonitor == 0.3 06:23:55 INFO - moztest == 0.7 06:23:55 INFO - mozversion == 1.4 06:23:55 INFO - psutil == 3.1.1 06:23:55 INFO - requests == 1.2.3 06:23:55 INFO - wsgiref == 0.1.2 06:23:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:55 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 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:55 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 06:23:55 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 06:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:55 INFO - 'HOME': '/Users/cltbld', 06:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:55 INFO - 'LOGNAME': 'cltbld', 06:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:55 INFO - 'MOZ_NO_REMOTE': '1', 06:23:55 INFO - 'NO_EM_RESTART': '1', 06:23:55 INFO - 'PAGER': '/bin/cat', 06:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:55 INFO - 'PWD': '/builds/slave/test', 06:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:55 INFO - 'SHELL': '/bin/bash', 06:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:55 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:55 INFO - 'USER': 'cltbld', 06:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:55 INFO - 'XPC_FLAGS': '0x0', 06:23:55 INFO - 'XPC_SERVICE_NAME': '0', 06:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:23:56 INFO - Cleaning up... 06:23:56 INFO - Return code: 0 06:23:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:23:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:23:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee43ca1ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, '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.49cX6uzle5/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.BjP53ECy4k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:23:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:23:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:23:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:56 INFO - 'HOME': '/Users/cltbld', 06:23:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:56 INFO - 'LOGNAME': 'cltbld', 06:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:56 INFO - 'MOZ_NO_REMOTE': '1', 06:23:56 INFO - 'NO_EM_RESTART': '1', 06:23:56 INFO - 'PAGER': '/bin/cat', 06:23:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:56 INFO - 'PWD': '/builds/slave/test', 06:23:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:56 INFO - 'SHELL': '/bin/bash', 06:23:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:23:56 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:23:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:56 INFO - 'USER': 'cltbld', 06:23:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:56 INFO - 'XPC_FLAGS': '0x0', 06:23:56 INFO - 'XPC_SERVICE_NAME': '0', 06:23:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:23:56 INFO - Cleaning up... 06:23:56 INFO - Return code: 0 06:23:56 INFO - Running post-action listener: _resource_record_post_action 06:23:56 INFO - Running post-action listener: _start_resource_monitoring 06:23:56 INFO - Starting resource monitoring. 06:23:56 INFO - ##### 06:23:56 INFO - ##### Finished create-virtualenv step (success) 06:23:56 INFO - ##### 06:23:56 INFO - ##### 06:23:56 INFO - ##### Running install step. 06:23:56 INFO - ##### 06:23:56 INFO - Running pre-action listener: _resource_record_pre_action 06:23:56 INFO - Running main action method: install 06:23:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:23:56 INFO - Reading from file tmpfile_stdout 06:23:56 INFO - Detecting whether we're running mozinstall >=1.0... 06:23:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:23:56 INFO - Reading from file tmpfile_stdout 06:23:56 INFO - Output received: 06:23:56 INFO - Usage: mozinstall [options] installer 06:23:56 INFO - Options: 06:23:56 INFO - -h, --help show this help message and exit 06:23:56 INFO - -d DEST, --destination=DEST 06:23:56 INFO - Directory to install application into. [default: 06:23:56 INFO - "/builds/slave/test"] 06:23:56 INFO - --app=APP Application being installed. [default: firefox] 06:23:56 INFO - mkdir: /builds/slave/test/build/application 06:23:56 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'] 06:23:56 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 06:24:31 INFO - Reading from file tmpfile_stdout 06:24:31 INFO - Output received: 06:24:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:24:31 INFO - Running post-action listener: _resource_record_post_action 06:24:31 INFO - ##### 06:24:31 INFO - ##### Finished install step (success) 06:24:31 INFO - ##### 06:24:31 INFO - ##### 06:24:31 INFO - ##### Running run-tests step. 06:24:31 INFO - ##### 06:24:31 INFO - Running pre-action listener: _resource_record_pre_action 06:24:31 INFO - Running main action method: run_tests 06:24:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:24:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:24:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:24:31 INFO - 2016-07-27 06:24:31.200 screenresolution[1937:13107] starting screenresolution argv=screenresolution get 06:24:31 INFO - 2016-07-27 06:24:31.203 screenresolution[1937:13107] Display 0: 1600x1200x32@60 06:24:31 INFO - 2016-07-27 06:24:31.209 screenresolution[1938:13109] starting screenresolution argv=screenresolution list 06:24:31 INFO - Available Modes on Display 0 06:24:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:24:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:24:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:24:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:24:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:24:31 INFO - Intel Iris: 06:24:31 INFO - Chipset Model: Intel Iris 06:24:31 INFO - Type: GPU 06:24:31 INFO - Bus: Built-In 06:24:31 INFO - VRAM (Dynamic, Max): 1536 MB 06:24:31 INFO - Vendor: Intel (0x8086) 06:24:31 INFO - Device ID: 0x0a2e 06:24:31 INFO - Revision ID: 0x0009 06:24:31 INFO - Displays: 06:24:31 INFO - Display: 06:24:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:24:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:24:31 INFO - Display Serial Number: 1600x1200 60 06:24:31 INFO - Main Display: Yes 06:24:31 INFO - Mirror: Off 06:24:31 INFO - Online: Yes 06:24:31 INFO - Rotation: Supported 06:24:31 INFO - Return code: 0 06:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:24:31 INFO - Python 2.7.3 06:24:31 INFO - Return code: 0 06:24:31 INFO - grabbing minidump binary from tooltool 06:24:31 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')]} 06:24:31 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 0x108b34d30>, '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 0x108899db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:24:31 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 06:24:31 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 06:24:31 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 06:24:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:24:31 INFO - Return code: 0 06:24:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:24:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:24:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:24:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:24:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--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 06:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --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 06:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.49cX6uzle5/Render', 06:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:31 INFO - 'HOME': '/Users/cltbld', 06:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:31 INFO - 'LOGNAME': 'cltbld', 06:24:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:24:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:31 INFO - 'MOZ_NO_REMOTE': '1', 06:24:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:24:31 INFO - 'NO_EM_RESTART': '1', 06:24:31 INFO - 'PAGER': '/bin/cat', 06:24:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:31 INFO - 'PWD': '/builds/slave/test', 06:24:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:31 INFO - 'SHELL': '/bin/bash', 06:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BjP53ECy4k/Listeners', 06:24:31 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:31 INFO - 'USER': 'cltbld', 06:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:31 INFO - 'XPC_FLAGS': '0x0', 06:24:31 INFO - 'XPC_SERVICE_NAME': '0', 06:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--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 06:24:31 INFO - mozversion application_buildid: 20160704150257 06:24:31 INFO - mozversion application_changeset: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 06:24:31 INFO - mozversion application_display_name: Nightly 06:24:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:24:31 INFO - mozversion application_name: Firefox 06:24:31 INFO - mozversion application_remotingname: firefox 06:24:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:24:31 INFO - mozversion application_vendor: Mozilla 06:24:31 INFO - mozversion application_version: 50.0a1 06:24:31 INFO - mozversion platform_buildid: 20160704150257 06:24:31 INFO - mozversion platform_changeset: c9a70b64f2faa264296f0cc90d68a2ee2bac6ac5 06:24:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:24:31 INFO - mozversion platform_version: 50.0a1 06:24:31 INFO - using testdate: 1469625871 06:24:31 INFO - actual date: 1469625871 06:24:31 INFO - starting webserver on 'localhost:49665' 06:24:31 INFO - SUITE-START | Running 2 tests 06:24:31 INFO - TEST-START | dromaeo_css 06:24:31 INFO - operating with platform_type : mac_ 06:24:31 INFO - Initialising browser for dromaeo_css test... 06:24:31 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp4ZtFSh/profile http://localhost:49665/getInfo.html) 06:24:34 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 06:24:34 INFO - PROCESS | 1948 | colorDepth:24 06:24:34 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 06:24:34 INFO - PROCESS | 1948 | __metrics 06:24:34 INFO - PROCESS | 1948 | 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" 06:24:34 INFO - PROCESS | 1948 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:24:35 INFO - TEST-INFO | 1948: exit 0 06:24:35 INFO - Browser initialized. 06:24:35 INFO - Running cycle 1/1 for dromaeo_css test... 06:24:35 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp4ZtFSh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:24:47 INFO - PROCESS | 1950 | RSS: Main: 243806208 06:24:47 INFO - PROCESS | 1950 | 06:25:38 INFO - PROCESS | 1950 | 1469625938771 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] 06:27:49 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49665/tests/dromaeo/cssquery-ext.html) 06:27:50 INFO - PROCESS | 1950 | RSS: Main: 371691520 06:27:50 INFO - PROCESS | 1950 | 06:30:53 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-ext.html (next: http://localhost:49665/tests/dromaeo/cssquery-jquery.html) 06:30:54 INFO - PROCESS | 1950 | RSS: Main: 381874176 06:30:54 INFO - PROCESS | 1950 | 06:33:56 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49665/tests/dromaeo/cssquery-mootools.html) 06:33:57 INFO - PROCESS | 1950 | RSS: Main: 372158464 06:33:57 INFO - PROCESS | 1950 | 06:36:59 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49665/tests/dromaeo/cssquery-prototype.html) 06:37:00 INFO - PROCESS | 1950 | RSS: Main: 372432896 06:37:00 INFO - PROCESS | 1950 | 06:40:03 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49665/tests/dromaeo/cssquery-yui.html) 06:40:04 INFO - PROCESS | 1950 | RSS: Main: 340926464 06:40:04 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-yui.html (next: http://localhost:49665/tests/dromaeo/cssquery-dojo.html) 06:43:07 INFO - PROCESS | 1950 | RSS: Main: 437964800 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | __start_tp_report 06:43:07 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 06:43:07 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 06:43:07 INFO - PROCESS | 1950 | |i|pagename|runs| 06:43:07 INFO - PROCESS | 1950 | |0;dojo.html;3294.2917272786344;3297.1592243977784;3315.8342082895847;3345.096823857557;3358.4290670585997;5553.055980483317;5583.776648934037;5603.7478313475885;5671.177679236508;5711.514521265693;5670.461306175917;5683.431656834315;5694.743736531856;5720.742566584503;5731.340895796985;5114.693118412907;5133.717645529495;5173.6119791015635;5177.663451875635;5244;5214.843554693346;5225.320708307915;5226.425093239741;5235.973820130913;5236.502532259429;5104.515071068243;5134.614903882205;5137.178051511759;5148.459411761767;5219.739013049351;5220.634555581111;5237.973810130963;5247.658902171384;5255.947440525622;5314.495122963351;5053.595712343041;5056.747162641853;5074.923876141861;5165.354330708661;5177.352830896138;5650.180723795068;5664.093745000821;5664.320281566238;5665.12190610456;5713.457221563945;6249.0626406039;6250.624962502265;6257.186565746424;6257.8748425031245;6258.906116408258;5224.686518808846;6149.077638354238;6167.814965551041;6280.309165991783;6322.304546499881;5695.943040569583;5792.4497172768515;5814.970925145347;5815.069588865804;5815.621984571074;6310.558260921692;6326.873462530723;6328.71520781566;6331.810045698544;6341.778037768656;5917.408259174134;6060.606060606046;6135.723892424851;6152.2002139722445;6182.381761823872;6261.937380626136;6269.404406581368;6275.6862156892885;6281.842953926189;6290.150829638032;6107.114468402056;6193.38066193377;6201.131841542187;6204.441600255908;6224.564280500412;6023.969880150659;6077.088436734525;6095;6136.764169924236;6197.4732147767045;4848.7090774553635;4881.096997055556;4887.291342755351;4887.315775791392;4914.778834952435;4356.738595684269;4373.169097871394;4382.364557139261;4418.668599855024;4439.445069366329;5694.686792226385;5708.4862362387585;5719.113537401626;5768.134779782982;5771.624844385102;6087.756489740449;6126.853582554517;6235.937640623536;6283.874322513524;6286.245650521875;5626.352969408488;5629.943700562942;5655.80204692842;5661.2923384576925;5661.7735290588735;5332.200169974535;5338.439463856317;5342.465753424626;5353.759080841293;5364.91952620711;6270.3416141305415;6283.434490895841;6291.937080629135;6326.746930122744;6356.936430635635;5646.124850648156;5689.34572524165;6275.811725648238;6281.7173227203975;6285;6270.717817698123;6299.496040316672;6323.841903952438;6326.746930122744;6327.651979141191;5961.016432288624;6065.363136870565;6102.206713127265;6148.1699970503405;6167.352427995175;6197.752089916353;6206.162168107247;6207.844803879939;6220.7511699531515;6229.813105606839;5916.201312822714;6000.168850969197;6001.459868611846;6012.218411606638;6056.57603967718;5893.499052580407;5959;5962;5999.040153575407;6019.759209631566;5899.410058994066;5908.7045647718305;5912.201852749996;5921.61509501881;5950.047992321208;4893.9021219576125;6063.575549711654;6072.301685306246;6072.908906366496;6088.756449742138;5707.800226992202;5763.567732420001;5779.335376431596;5827.59206855516;5832.504237139721 06:43:07 INFO - PROCESS | 1950 | |1;ext.html;506.8124793826285;514.5549100028475;519.0035132545512;535.6598559914455;562.5864989232915;30564.38871222577;42331.78834105829;43449;43539;46117.53882461183;44387.778061109646;44804;44873;44963.32555011677;44975.55024449754;8813.42712723671;9726.513674316273;9905.207583393347;9921.851172232422;9931.304808663397;8779.078196789342;11975.700607484796;12204.20672656279;12229.877701223008;12389.38053097346;12165.513379464855;12198.878011219907;12237.938810305935;12240.693982650417;12251.387430628478;8746.69386571467;8952.060033696433;8960.103989601053;12057.975072118881;12130.029597632212;8919.152680495343;11629.88370116305;11901.404929753564;11907.106966977512;12175.695607609881;42180.15639687189;42512;42553.787231063645;42597;42699.7865010676;3522.7005704515145;3586.748927575071;3594.281143771256;4082.9183416331803;4118.128958778739;31660.683393166004;32963.670363296296;33188.33623327544;33268.6673133268;33408.665913340796;3016.9547456787946;3044.086773967823;3199.488081906907;3234.8059116453087;3310.470324748029;534632;538719;539157;541240;543382;34464.310713785584;62139.689301554106;62453;62618;62941.68529157325;34227.82886085554;36037.91886243417;36120.638793612256;36187.4571881422;36215.27569448649;14895.851041489446;15047.097174169367;15057.397704091933;15075.020123691926;15132.697346053237;11464.140189485655;11600.6519804406;11607.6517704469;11823.231489953125;12724.872751272369;7539.077535228591;7771.145174030852;7773.261540153676;7780.338671212897;7963.681452741941;3310.851011704431;3324.3185147044796;3334.9666503334656;3351.9329613407594;3362.09223509651;937.8827646544182;948.0093302498905;984.9162821160281;992.1368409483796;1019.6431249062769;30017.849910750305;30995.535066973564;31095.844520777253;31504.684953150634;31906.521402178987;1121.1815374776459;1128.9830652540218;1143.6569029291345;1172.2146162071263;1210.6852218423098;12917.547885823951;16135.757963630555;16349.836501635069;16714.331426743036;16784;178815;180033;180223;180601;180619.09690451465;62053.68973155195;62600;62620.37379626236;62780.37219627837;62812.68593657002;1301.7006088599676;1423.9605088285402;1440.0423718227262;1446.2551785830517;1455.5269537400388;30126.31200862038;31596.210094746897;31607.84196079005;32633.673663263064;32646;51586.74206628943;51867.481325186265;52152;52209.955800883006;52481.7375913118;589772;594062;595208;595494;595819;23612.881935590212;25328.493430130926;27238.72761272362;27239;27274.727252727218;49921.50235492941;50002;50409.49590504048;50768;51624;40316.79841600773;40445.797771010955;40934.9766255846;41428;41929.79035104805;30439.295052192556;41457;41484.58515414807;41917.79041104775;41984;39633.80183099066;39858.800705996284;40354.39468408035;40561.79719101386;40730.38904416496;31448.37103257876;34224.315513689085;35584.64415355813;36257.45613815848;36365;24275.75724242735;25938.351541210865;26017.869910650326;26137.73862261353;26606.201813944837 06:43:07 INFO - PROCESS | 1950 | |2;jquery.html;361.3748215587033;382.06394333881985;389.06235971309144;390.170886865411;410.1243844392222;16062.919685401572;16595;21593.56812863744;21662.783372166272;21815.01832417541;21431.928403579837;21887.781122188775;22241.55516889661;22276.331710048726;22291.331260062183;5830.416958304168;5841.766329346821;6154.661493617849;6171.7531298748;6191.195144631214;3921.117748506592;4142.440770495989;5873.911891321633;6172.9074063889075;6235.875282494348;6176.907346389808;6208.565400421972;6218.813435596917;6222.657753823538;6240.063990401431;5847.532197424196;6189.96905015472;6197.442230199259;6213.782517611862;6220.657863817488;5184.870378240536;6035.849103772397;6036.366181550918;6040.637561746309;6051.273847138327;13145.211287322696;13646.590602281947;13647.11293765902;13653.726925461435;16756.916215418965;2173.119886445992;2198.6482162854027;2199.3072182262536;2343.3321503371626;2866.6273384459887;10037.849432258521;10044.196464282913;10050.296479246455;10484;10552.366857988545;1818.8363047325672;1838.218757028271;1838.7885393179822;1896.8482521398255;1963.1656545967905;116226;126569;128758.99999999907;129644;129787.70212297945;11647.30116193031;11663.59177428786;11696.35670038156;11720.308102947543;11769.293842369485;14588.270586470633;14650.047746896418;14699.853001469848;14705.191214483306;14739.52604739539;8431.28334091609;8594.613242404106;8598.78503037429;8637.827243455222;8663.913360866436;8461.47693415179;8487.405881588229;8494.428530721712;8573.871391929126;8586.95706521476;4482.551744825557;4550.362949187116;4556.430446194226;4629.166749985035;4644.535546445329;1920.6459446090473;1927.6915693489254;1937.883726976386;1938.234397413014;1972.9210831566863;846.2341580869323;869.3523325122698;942.5805516545072;946.2619157057507;965.4931161140457;21532.677009844865;21949.890250548644;22087.889560552416;22103.889480552814;22124.336269911928;880.5817236812591;1007.1388962437157;1014.7767491151935;1049.6588608702202;1057.7197042783669;10948.726281842859;11480.598179063692;11544.711382215512;11545.191836571359;11569.710757231136;595264;611639;614825.9258703678;625039;632269;99877.50061249793;127942;131579;132354.33822831017;134194;950.7955789505289;983.852422136671;1024.3904876598413;1040.2042437535292;1053.7629033467408;16077.437289694804;20428.387148385573;20691.586168276852;20781.75309481436;20793.960301984538;56446.717766410904;57357.713211435344;57960.4203957955;58081.70959145177;58473.538161546305;74765.62617186879;78826.6058669703;84499.57750211455;86027.70958435503;86069.13930860611;7563.205863384485;7615.923840761521;7979.880301795594;8026.558539280396;8422.452540584842;10357.533910974174;12530.624081277576;12796.680082998;12815.743685126059;13094.869051309746;10576.788464230825;10969.50637221343;10974.561017559208;11254.212205145888;11306.717332066764;11044;11084.501197446296;11194.83207751868;11234.494447749707;11328;11341.376224307742;11362.715932101764;11504.827427588425;11633.011194048262;11799.587014454453;5901.645901245939;8054.597270136587;8164.7550573482895;8218.013838339437;8308.293795027248;9311.674091406769;11224.55101795919;11392.829107563228;11497.82753258685;11614.70963225926 06:43:07 INFO - PROCESS | 1950 | |3;mootools.html;1432.7062952094818;1581.4148764956967;1794.8294911983357;1925.528245579833;1953.433504283757;7952.125266220725;8219.794505137375;8605.784855378612;9050.683226087089;9058.818823623524;8060.596970151499;8174.6321415536295;8533.91466085339;8725.738227853175;8740.562971851397;2342.723215847359;2357.752435994217;2397.1010870923405;2397.1010870923405;2400.2799160251943;2111.9366419007456;2189.6934429179846;2379.6073647848084;2389.354874183969;2424.4787370715294;1865;2311.734150572689;2358.6697862299293;2375.904963801446;2377.8811059447044;2076.1487790006177;2368.7275963264187;2387.510560335127;2389.307100940725;2394.820388470872;2301.8849057547054;2314.0281081945623;2319.8724070176136;2333.241696448661;2334.3230463165833;8845.425047371964;8930.151635594608;8931.64273429062;8945.73162805117;8960.417572857743;5258.8685282867855;5370.087085195526;5432.945670543283;5436.266104075979;5455.1271796512365;3565.3760591896316;3638.126849556114;3715.758475699071;3723.8696645617542;3728.81355932205;1043.8068957242933;1059.4808543813476;1083.1767856429092;1099.4612639806517;1104.309806371018;11706.824397633956;11737.295762254207;11766.823497647541;11777.705557361049;11791.410429478492;4529.796159172845;4752.691075080109;4834.20235661119;4835.347228124228;4849.878753031202;9893.703188904201;9945.005499450142;9967.053129952643;9970.953049929798;9985.400875947467;5358.223057656618;5369.677819330853;5370.812021579304;5371.6239863209985;5375.81184658535;4416.381706561084;4913.287573301851;4927.088488629615;4944.233643785219;4950.5544500994365;3811.8284677189595;3812.294725475796;3857.8842634721004;3897.707671924617;3915.7063220258024;1095.2333366643381;1113.4043286841502;1147.913906457019;1164.004775916579;1167.9124065695107;840.2899549880464;856.7172832965107;949.2643201518878;964.3731574476647;1002.7192386131894;5987.970060149672;6029.879402412015;6121.510279177655;6128.724207410677;6363.681815909186;958.8322043642474;962.5860879821604;965.1699538396973;980.9509524523745;992.7319623701704;3866.6133386661672;4029.15387768566;4127.112670775738;4176.874693759192;4200.159968006411;10394.636187733393;10661.520231589597;10665.093467055232;10776.730581735519;10784.352938823538;9773.609055637695;10735.409552474688;10738.194635402377;10738.409387483607;10765.784684306427;7488.588127653032;7906.130325664172;8194.959025204837;8205.671773129128;8303.335733141334;5909.55678324136;6084.6349219047;6141.84645383869;6213.502919766317;6366.29970703213;9895.752606184902;9972.700818975442;10186.33788803755;10212.642557510748;10220.182385409;10386.870691508373;10418.114460270961;10442.791144177227;10470.476476176009;10492.213084018882;7557.508761930677;7603.7338693145475;7642.388608911385;7698.0762308523335;7758.806029849299;8248;8283.29591984688;8336.999560052833;8353.624086915981;8365.665373384996;7165.713371465083;7307.817304567428;7390;7431.628418579157;7479.401647868266;7240.782776516713;7323.7436689715605;7328.5969271690565;7343.082114735658;7387.889181662387;5882.499987500939;7145.071140751669;7147.3210045045635;7168.207948012997;7174.2108368078425;4816.133096042606;5014.623903207201;5094.108531006985;5146.871328216825;5254.789808407774;4368.563143685606;5097.8215762448135;5129.897402052013;5227.660202087004;5341.866453338542 06:43:07 INFO - PROCESS | 1950 | |4;prototype.html;301.62899633450854;304.53710360252416;316.3625295030513;321.02408677620036;321.8519481038722;4779.545943135406;5235.915985531282;5396.622236443451;5401.3248343957;5451.3458384993755;4452.706855969179;5136.460671629481;5243.056249875021;5244.527992480676;5392.921415716863;3327.866885324584;4064.4106604542326;4092.815823287959;4112.1775644870995;4117.917641647173;3769.754965927206;3821.273957947995;4101.97949010256;4106.322456794625;4133.090720041601;4077.4087757275324;4079.551249362568;4087.3255912774357;4107.609777071189;4108.424820525129;4015.518137823465;4044.6562042226446;4073.6130067643526;4088.6115818997146;4108.5275193352845;3560.287942411502;3572.910677233064;3579.427291633326;3582.390993531106;3597.964020359815;4994.001199760062;5249;5252.501012403815;5264.263003179558;5266.420693723688;3848.499695039655;3992.8819930419527;4654.743989080599;4704.317873908299;4704.341392205094;5227.71920879387;5519.365272993617;5590.301212348457;5636.859078523029;5652.547796176296;2726.740959608834;3856.5730679648427;3857.961420385788;3870.3226935286493;3883.3786594145085;4859.080663138076;4990.476000020019;5610.7194640268235;5877.9412205877825;5881.764729410818;3092.9845350773103;3366.629670736217;3834.9041273967814;3957.940630890539;3965.980170099131;3204.599425071866;3474.5656792900886;3923.7841918694744;3971.860984865516;3976.304146774303;4017.5580686124504;4249.873783447389;4369;4570.497245303014;4638.652101092432;4919.647097048341;4965.528274813887;4968.403791544965;5557.6109672323355;5776.942230577724;1010.6058637131524;1221.8900298973135;1281.4874050379922;1308.371981448891;1333.926634035118;820.6869009584736;824.7773101262626;869.8956125264882;885.017630430222;919.8298314811648;5292.629515933848;5294.364676238876;5298.6290959633225;5310.902193633493;5344.412114667383;5804.4775970162045;5866.560007999417;5876.823695289148;5878.764849405976;5911.793087242012;5122.846314610568;5196.222372128123;5263.6578622389425;5266.394364648115;5313.521783039545;2383.9165629202894;2753.4493101379803;2831;2915.3731947631363;3055.618047744032;5326.493983071602;5676.205331253628;5934.495567876779;5977.193078934288;6029.427204415573;5822.214001109881;6040.335563088057;6150.384961503813;6156.230471191101;6159.84600384994;5308.389535203501;5634.830955071437;5786.710664466676;5943.554233432424;6166.599171053867;4881.438634557071;5691.658500489983;5813.76744930198;5986;6006.579539432198;4857.757112144449;5168;5798.971005144947;5872.915115134237;5900.793472228452;5952.077427998667;6174.332930108935;6212.72042758086;6243.531735119794;6252.437280644764;3449.1549570355423;3910.3352430086384;3948.526176858795;3957.465742124776;3961.8811435656976;5104.515071068391;5538.418466061166;5807.041838096836;5914.645121292736;5920.957269741933;4688.413948256468;5411.756470958895;5681.630694004876;5740.68426236561;5755.223044888887;5318.893622127458;5622;5712.6286791358425;5718.913406452761;5734.595024219093;4858.732769697701;5304.814331498379;5566.610337276514;5595.804146854841;5624.240727501735;4898.118338699069;5044.646874718735;5721.5136713379825;5761.5390768739235;5767.250257466502;4005.639492445694;5450.754716037709;5606.411326810626;5608.719564021864;5613.410591887793 06:43:07 INFO - PROCESS | 1950 | |5;yui.html;746.8804991201407;780.2276242217553;843.628803326536;853.9624356406964;856.5777537636382;12981.286029268387;14912.403503859847;15983.76024359634;16075.115868627221;16156.919215403906;14607.707845843077;14796.55610331689;14796.6300842479;14813.555593332218;14828.110313381205;727.3526561360394;750.6959681329043;905.0768216419249;905.44767691708;934.5934518484447;899.6311512279966;915.199200699388;915.5513798238849;915.789368445258;917.7522069041355;959.1655259923876;959.7984423271087;959.8848138223423;960.4525420510312;963.4123184857232;763.900692909921;942.9009953954804;956.2206801456825;972.3193764364902;972.7227740094039;720.4524561333373;720.5064530796369;721.6283613938825;721.9639018049129;724.2395484741;15207.619809504851;15238.542843714706;15245.466408675755;15251.61870953224;15326.770098448533;14248.145111293356;14406.423743050265;14426.855731442656;14439.278036098152;14585.70828583433;10886;11976.88023119775;12812.87187128126;13181.60455186346;13209.33953302331;4583.661480699974;4598.287265473857;4847;4894.5105489451125;4902.681325713817;107093.46453267762;107956;108017.45991270072;108045.91954080516;108837;10812.297200681931;10990;11223.66329010131;11422.31466112036;11595.884041159481;22994.655080173812;23166.884165579064;23224.767752322598;23252.069917203124;23261.3021609352;8018;8030.638621262057;8086.989126359205;8239.835203295901;8245.917540824636;4448.733076015449;4507.842225522091;4517.277235642281;4536.228841097021;4719.079779442975;3357.244619960489;4234.513031001475;4235.745855248695;4262.062346283813;4272.6795490338345;750.1560744162816;752.5635131623645;755.5013690963938;755.6372940988356;770.1951460723558;586.3272835318279;614.2046050364788;639.8304449320933;682.4403988729293;704.6300692136567;9337.673181438617;9637.42175469474;9653.806923861483;9716.854247186298;9761.267904907161;598.2462097756771;609.1411110334407;615.5721773367557;658.7167517967224;692.3872372949976;4377.190219809346;4711.128441238382;4730.148573256847;4748.4539277982785;4796.736179510161;159476;160321;162460;162959;168648;142726;143296.28351858174;143757;145214.27392862967;148701;113302;116630.41684791521;117513.23730144055;119047;124312;8702.564871756513;8930.508822014852;8999.460032398078;9122.92269192158;9193.954030230074;57766;59987;60246;60649.69675151596;63599.682001589696;69490.26274343181;70039.6498017527;78506.42987140111;80332.59833700793;91832.54083729538;11941.223820451643;13511.864881351454;13591.86408135906;14085.577432677037;14116;14944.925275373553;14963.783722231316;15062.623434414227;15190.696186076437;15353.539393818204;11625.302481851117;14170.716585668435;14319.856801432268;14410.063345882483;14700;14294.142351458946;14336.641583960067;14351.569452916428;14459.855401445851;14770.261486925396;13640.249786261851;14103.365348559135;14493.20287384204;14532.418703251751;14552.27238638085;11518.251313664585;11540.884591154318;11541.307521548733;11607.76784464289;11838.940805295919;9236.399634023766;10279.640212592523;10454.484099805333;11677.591284305008;11688.824667629822 06:43:07 INFO - PROCESS | 1950 | __end_tp_report 06:43:07 INFO - PROCESS | 1950 | __start_cc_report 06:43:07 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,1222 06:43:07 INFO - PROCESS | 1950 | __end_cc_report 06:43:07 INFO - PROCESS | 1950 | __startTimestamp1469626987547__endTimestamp 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | ------- Summary: start ------- 06:43:07 INFO - PROCESS | 1950 | Number of tests: 6 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | [#0] dojo.html Cycles:165 Average:5695.27 Median:5827.59 stddev:641.70 (11.0%) stddev-sans-first:615.40 06:43:07 INFO - PROCESS | 1950 | Values: 3294.3 3297.2 3315.8 3345.1 3358.4 5553.1 5583.8 5603.7 5671.2 5711.5 5670.5 5683.4 5694.7 5720.7 5731.3 5114.7 5133.7 5173.6 5177.7 5244.0 5214.8 5225.3 5226.4 5236.0 5236.5 5104.5 5134.6 5137.2 5148.5 5219.7 5220.6 5238.0 5247.7 5255.9 5314.5 5053.6 5056.7 5074.9 5165.4 5177.4 5650.2 5664.1 5664.3 5665.1 5713.5 6249.1 6250.6 6257.2 6257.9 6258.9 5224.7 6149.1 6167.8 6280.3 6322.3 5695.9 5792.4 5815.0 5815.1 5815.6 6310.6 6326.9 6328.7 6331.8 6341.8 5917.4 6060.6 6135.7 6152.2 6182.4 6261.9 6269.4 6275.7 6281.8 6290.2 6107.1 6193.4 6201.1 6204.4 6224.6 6024.0 6077.1 6095.0 6136.8 6197.5 4848.7 4881.1 4887.3 4887.3 4914.8 4356.7 4373.2 4382.4 4418.7 4439.4 5694.7 5708.5 5719.1 5768.1 5771.6 6087.8 6126.9 6235.9 6283.9 6286.2 5626.4 5629.9 5655.8 5661.3 5661.8 5332.2 5338.4 5342.5 5353.8 5364.9 6270.3 6283.4 6291.9 6326.7 6356.9 5646.1 5689.3 6275.8 6281.7 6285.0 6270.7 6299.5 6323.8 6326.7 6327.7 5961.0 6065.4 6102.2 6148.2 6167.4 6197.8 6206.2 6207.8 6220.8 6229.8 5916.2 6000.2 6001.5 6012.2 6056.6 5893.5 5959.0 5962.0 5999.0 6019.8 5899.4 5908.7 5912.2 5921.6 5950.0 4893.9 6063.6 6072.3 6072.9 6088.8 5707.8 5763.6 5779.3 5827.6 5832.5 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | [#1] ext.html Cycles:180 Average:58964.09 Median:19816.10 stddev:127543.47 (643.6%) stddev-sans-first:127825.75 06:43:07 INFO - PROCESS | 1950 | Values: 506.8 514.6 519.0 535.7 562.6 30564.4 42331.8 43449.0 43539.0 46117.5 44387.8 44804.0 44873.0 44963.3 44975.6 8813.4 9726.5 9905.2 9921.9 9931.3 8779.1 11975.7 12204.2 12229.9 12389.4 12165.5 12198.9 12237.9 12240.7 12251.4 8746.7 8952.1 8960.1 12058.0 12130.0 8919.2 11629.9 11901.4 11907.1 12175.7 42180.2 42512.0 42553.8 42597.0 42699.8 3522.7 3586.7 3594.3 4082.9 4118.1 31660.7 32963.7 33188.3 33268.7 33408.7 3017.0 3044.1 3199.5 3234.8 3310.5 534632.0 538719.0 539157.0 541240.0 543382.0 34464.3 62139.7 62453.0 62618.0 62941.7 34227.8 36037.9 36120.6 36187.5 36215.3 14895.9 15047.1 15057.4 15075.0 15132.7 11464.1 11600.7 11607.7 11823.2 12724.9 7539.1 7771.1 7773.3 7780.3 7963.7 3310.9 3324.3 3335.0 3351.9 3362.1 937.9 948.0 984.9 992.1 1019.6 30017.8 30995.5 31095.8 31504.7 31906.5 1121.2 1129.0 1143.7 1172.2 1210.7 12917.5 16135.8 16349.8 16714.3 16784.0 178815.0 180033.0 180223.0 180601.0 180619.1 62053.7 62600.0 62620.4 62780.4 62812.7 1301.7 1424.0 1440.0 1446.3 1455.5 30126.3 31596.2 31607.8 32633.7 32646.0 51586.7 51867.5 52152.0 52210.0 52481.7 589772.0 594062.0 595208.0 595494.0 595819.0 23612.9 25328.5 27238.7 27239.0 27274.7 49921.5 50002.0 50409.5 50768.0 51624.0 40316.8 40445.8 40935.0 41428.0 41929.8 30439.3 41457.0 41484.6 41917.8 41984.0 39633.8 39858.8 40354.4 40561.8 40730.4 31448.4 34224.3 35584.6 36257.5 36365.0 24275.8 25938.4 26017.9 26137.7 26606.2 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | [#2] jquery.html Cycles:180 Average:35715.62 Median:2198.98 stddev:102890.86 (4679.0%) stddev-sans-first:103145.25 06:43:07 INFO - PROCESS | 1950 | Values: 361.4 382.1 389.1 390.2 410.1 16062.9 16595.0 21593.6 21662.8 21815.0 21431.9 21887.8 22241.6 22276.3 22291.3 5830.4 5841.8 6154.7 6171.8 6191.2 3921.1 4142.4 5873.9 6172.9 6235.9 6176.9 6208.6 6218.8 6222.7 6240.1 5847.5 6190.0 6197.4 6213.8 6220.7 5184.9 6035.8 6036.4 6040.6 6051.3 13145.2 13646.6 13647.1 13653.7 16756.9 2173.1 2198.6 2199.3 2343.3 2866.6 10037.8 10044.2 10050.3 10484.0 10552.4 1818.8 1838.2 1838.8 1896.8 1963.2 116226.0 126569.0 128759.0 129644.0 129787.7 11647.3 11663.6 11696.4 11720.3 11769.3 14588.3 14650.0 14699.9 14705.2 14739.5 8431.3 8594.6 8598.8 8637.8 8663.9 8461.5 8487.4 8494.4 8573.9 8587.0 4482.6 4550.4 4556.4 4629.2 4644.5 1920.6 1927.7 1937.9 1938.2 1972.9 846.2 869.4 942.6 946.3 965.5 21532.7 21949.9 22087.9 22103.9 22124.3 880.6 1007.1 1014.8 1049.7 1057.7 10948.7 11480.6 11544.7 11545.2 11569.7 595264.0 611639.0 614825.9 625039.0 632269.0 99877.5 127942.0 131579.0 132354.3 134194.0 950.8 983.9 1024.4 1040.2 1053.8 16077.4 20428.4 20691.6 20781.8 20794.0 56446.7 57357.7 57960.4 58081.7 58473.5 74765.6 78826.6 84499.6 86027.7 86069.1 7563.2 7615.9 7979.9 8026.6 8422.5 10357.5 12530.6 12796.7 12815.7 13094.9 10576.8 10969.5 10974.6 11254.2 11306.7 11044.0 11084.5 11194.8 11234.5 11328.0 11341.4 11362.7 11504.8 11633.0 11799.6 5901.6 8054.6 8164.8 8218.0 8308.3 9311.7 11224.6 11392.8 11497.8 11614.7 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | [#3] mootools.html Cycles:180 Average:5742.62 Median:5054.37 stddev:3207.96 (63.5%) stddev-sans-first:3200.60 06:43:07 INFO - PROCESS | 1950 | Values: 1432.7 1581.4 1794.8 1925.5 1953.4 7952.1 8219.8 8605.8 9050.7 9058.8 8060.6 8174.6 8533.9 8725.7 8740.6 2342.7 2357.8 2397.1 2397.1 2400.3 2111.9 2189.7 2379.6 2389.4 2424.5 1865.0 2311.7 2358.7 2375.9 2377.9 2076.1 2368.7 2387.5 2389.3 2394.8 2301.9 2314.0 2319.9 2333.2 2334.3 8845.4 8930.2 8931.6 8945.7 8960.4 5258.9 5370.1 5432.9 5436.3 5455.1 3565.4 3638.1 3715.8 3723.9 3728.8 1043.8 1059.5 1083.2 1099.5 1104.3 11706.8 11737.3 11766.8 11777.7 11791.4 4529.8 4752.7 4834.2 4835.3 4849.9 9893.7 9945.0 9967.1 9971.0 9985.4 5358.2 5369.7 5370.8 5371.6 5375.8 4416.4 4913.3 4927.1 4944.2 4950.6 3811.8 3812.3 3857.9 3897.7 3915.7 1095.2 1113.4 1147.9 1164.0 1167.9 840.3 856.7 949.3 964.4 1002.7 5988.0 6029.9 6121.5 6128.7 6363.7 958.8 962.6 965.2 981.0 992.7 3866.6 4029.2 4127.1 4176.9 4200.2 10394.6 10661.5 10665.1 10776.7 10784.4 9773.6 10735.4 10738.2 10738.4 10765.8 7488.6 7906.1 8195.0 8205.7 8303.3 5909.6 6084.6 6141.8 6213.5 6366.3 9895.8 9972.7 10186.3 10212.6 10220.2 10386.9 10418.1 10442.8 10470.5 10492.2 7557.5 7603.7 7642.4 7698.1 7758.8 8248.0 8283.3 8337.0 8353.6 8365.7 7165.7 7307.8 7390.0 7431.6 7479.4 7240.8 7323.7 7328.6 7343.1 7387.9 5882.5 7145.1 7147.3 7168.2 7174.2 4816.1 5014.6 5094.1 5146.9 5254.8 4368.6 5097.8 5129.9 5227.7 5341.9 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | [#4] prototype.html Cycles:180 Average:4567.32 Median:5231.82 stddev:1447.47 (27.7%) stddev-sans-first:1415.68 06:43:07 INFO - PROCESS | 1950 | Values: 301.6 304.5 316.4 321.0 321.9 4779.5 5235.9 5396.6 5401.3 5451.3 4452.7 5136.5 5243.1 5244.5 5392.9 3327.9 4064.4 4092.8 4112.2 4117.9 3769.8 3821.3 4102.0 4106.3 4133.1 4077.4 4079.6 4087.3 4107.6 4108.4 4015.5 4044.7 4073.6 4088.6 4108.5 3560.3 3572.9 3579.4 3582.4 3598.0 4994.0 5249.0 5252.5 5264.3 5266.4 3848.5 3992.9 4654.7 4704.3 4704.3 5227.7 5519.4 5590.3 5636.9 5652.5 2726.7 3856.6 3858.0 3870.3 3883.4 4859.1 4990.5 5610.7 5877.9 5881.8 3093.0 3366.6 3834.9 3957.9 3966.0 3204.6 3474.6 3923.8 3971.9 3976.3 4017.6 4249.9 4369.0 4570.5 4638.7 4919.6 4965.5 4968.4 5557.6 5776.9 1010.6 1221.9 1281.5 1308.4 1333.9 820.7 824.8 869.9 885.0 919.8 5292.6 5294.4 5298.6 5310.9 5344.4 5804.5 5866.6 5876.8 5878.8 5911.8 5122.8 5196.2 5263.7 5266.4 5313.5 2383.9 2753.4 2831.0 2915.4 3055.6 5326.5 5676.2 5934.5 5977.2 6029.4 5822.2 6040.3 6150.4 6156.2 6159.8 5308.4 5634.8 5786.7 5943.6 6166.6 4881.4 5691.7 5813.8 5986.0 6006.6 4857.8 5168.0 5799.0 5872.9 5900.8 5952.1 6174.3 6212.7 6243.5 6252.4 3449.2 3910.3 3948.5 3957.5 3961.9 5104.5 5538.4 5807.0 5914.6 5921.0 4688.4 5411.8 5681.6 5740.7 5755.2 5318.9 5622.0 5712.6 5718.9 5734.6 4858.7 5304.8 5566.6 5595.8 5624.2 4898.1 5044.6 5721.5 5761.5 5767.3 4005.6 5450.8 5606.4 5608.7 5613.4 06:43:07 INFO - PROCESS | 1950 | 06:43:07 INFO - PROCESS | 1950 | [#5] yui.html Cycles:180 Average:25666.89 Median:4235.13 stddev:41781.53 (986.5%) stddev-sans-first:41856.84 06:43:07 INFO - PROCESS | 1950 | Values: 746.9 780.2 843.6 854.0 856.6 12981.3 14912.4 15983.8 16075.1 16156.9 14607.7 14796.6 14796.6 14813.6 14828.1 727.4 750.7 905.1 905.4 934.6 899.6 915.2 915.6 915.8 917.8 959.2 959.8 959.9 960.5 963.4 763.9 942.9 956.2 972.3 972.7 720.5 720.5 721.6 722.0 724.2 15207.6 15238.5 15245.5 15251.6 15326.8 14248.1 14406.4 14426.9 14439.3 14585.7 10886.0 11976.9 12812.9 13181.6 13209.3 4583.7 4598.3 4847.0 4894.5 4902.7 107093.5 107956.0 108017.5 108045.9 108837.0 10812.3 10990.0 11223.7 11422.3 11595.9 22994.7 23166.9 23224.8 23252.1 23261.3 8018.0 8030.6 8087.0 8239.8 8245.9 4448.7 4507.8 4517.3 4536.2 4719.1 3357.2 4234.5 4235.7 4262.1 4272.7 750.2 752.6 755.5 755.6 770.2 586.3 614.2 639.8 682.4 704.6 9337.7 9637.4 9653.8 9716.9 9761.3 598.2 609.1 615.6 658.7 692.4 4377.2 4711.1 4730.1 4748.5 4796.7 159476.0 160321.0 162460.0 162959.0 168648.0 142726.0 143296.3 143757.0 145214.3 148701.0 113302.0 116630.4 117513.2 119047.0 124312.0 8702.6 8930.5 8999.5 9122.9 9194.0 57766.0 59987.0 60246.0 60649.7 63599.7 69490.3 70039.6 78506.4 80332.6 91832.5 11941.2 13511.9 13591.9 14085.6 14116.0 14944.9 14963.8 15062.6 15190.7 15353.5 11625.3 14170.7 14319.9 14410.1 14700.0 14294.1 14336.6 14351.6 14459.9 14770.3 13640.2 14103.4 14493.2 14532.4 14552.3 11518.3 11540.9 11541.3 11607.8 11838.9 9236.4 10279.6 10454.5 11677.6 11688.8 06:43:07 INFO - PROCESS | 1950 | -------- Summary: end -------- 06:43:07 INFO - PROCESS | 1950 | 06:43:08 INFO - TEST-INFO | 1950: exit 0 06:43:08 INFO - TEST-OK | dromaeo_css | took 1116387ms 06:43:08 INFO - TEST-START | kraken 06:43:08 INFO - operating with platform_type : mac_ 06:43:08 INFO - Initialising browser for kraken test... 06:43:08 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp4f04R8/profile http://localhost:49665/getInfo.html) 06:43:10 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 06:43:10 INFO - PROCESS | 1953 | colorDepth:24 06:43:10 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 06:43:10 INFO - PROCESS | 1953 | __metrics 06:43:10 INFO - PROCESS | 1953 | 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" 06:43:10 INFO - PROCESS | 1953 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:43:11 INFO - TEST-INFO | 1953: exit 0 06:43:11 INFO - Browser initialized. 06:43:11 INFO - Running cycle 1/1 for kraken test... 06:43:11 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp4f04R8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:43:22 INFO - PROCESS | 1954 | RSS: Main: 244064256 06:43:22 INFO - PROCESS | 1954 | 06:43:25 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-beat-detection) 06:43:26 INFO - PROCESS | 1954 | RSS: Main: 294686720 06:43:26 INFO - PROCESS | 1954 | 06:43:29 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-dft) 06:43:29 INFO - PROCESS | 1954 | RSS: Main: 305049600 06:43:29 INFO - PROCESS | 1954 | 06:43:33 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-fft) 06:43:33 INFO - PROCESS | 1954 | RSS: Main: 387809280 06:43:33 INFO - PROCESS | 1954 | 06:43:36 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-oscillator) 06:43:36 INFO - PROCESS | 1954 | RSS: Main: 289312768 06:43:36 INFO - PROCESS | 1954 | 06:43:38 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49665/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:43:39 INFO - PROCESS | 1954 | RSS: Main: 293490688 06:43:39 INFO - PROCESS | 1954 | 06:43:42 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49665/tests/kraken/driver.html?testName=imaging-darkroom) 06:43:42 INFO - PROCESS | 1954 | RSS: Main: 329846784 06:43:42 INFO - PROCESS | 1954 | 06:43:45 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49665/tests/kraken/driver.html?testName=imaging-desaturate) 06:43:46 INFO - PROCESS | 1954 | RSS: Main: 333889536 06:43:46 INFO - PROCESS | 1954 | 06:43:49 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49665/tests/kraken/driver.html?testName=json-parse-financial) 06:43:49 INFO - PROCESS | 1954 | RSS: Main: 331292672 06:43:49 INFO - PROCESS | 1954 | 06:43:51 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49665/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:43:51 INFO - PROCESS | 1954 | RSS: Main: 311930880 06:43:51 INFO - PROCESS | 1954 | 06:43:53 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:43:54 INFO - PROCESS | 1954 | RSS: Main: 318521344 06:43:54 INFO - PROCESS | 1954 | 06:43:56 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:43:57 INFO - PROCESS | 1954 | RSS: Main: 316022784 06:43:57 INFO - PROCESS | 1954 | 06:44:00 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:44:01 INFO - PROCESS | 1954 | RSS: Main: 324964352 06:44:01 INFO - PROCESS | 1954 | 06:44:03 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:44:04 INFO - PROCESS | 1954 | RSS: Main: 327675904 06:44:04 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49665/tests/kraken/driver.html?testName=ai-astar) 06:44:06 INFO - PROCESS | 1954 | RSS: Main: 371081216 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | __start_tp_report 06:44:06 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 06:44:06 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 06:44:06 INFO - PROCESS | 1954 | |i|pagename|runs| 06:44:06 INFO - PROCESS | 1954 | |0;ai-astar;100;114;113;113;113;114;112;113;112;113 06:44:06 INFO - PROCESS | 1954 | |1;audio-beat-detection;144;108;105;171;137;95;160;138;142;106 06:44:06 INFO - PROCESS | 1954 | |2;audio-dft;180;183;185;185;185;184;180;184;184;184 06:44:06 INFO - PROCESS | 1954 | |3;audio-fft;83;95;84;134;55;55;59;66;95;59 06:44:06 INFO - PROCESS | 1954 | |4;audio-oscillator;81;87;96;91;100;99;99;69;67;100 06:44:06 INFO - PROCESS | 1954 | |5;imaging-gaussian-blur;88;85;88;87;88;85;90;86;89;85 06:44:06 INFO - PROCESS | 1954 | |6;imaging-darkroom;96;92;93;92;92;93;93;93;92;91 06:44:06 INFO - PROCESS | 1954 | |7;imaging-desaturate;126;140;86;85;83;86;83;84;85;84 06:44:06 INFO - PROCESS | 1954 | |8;json-parse-financial;55;56;52;53;51;55;52;54;52;52 06:44:06 INFO - PROCESS | 1954 | |9;json-stringify-tinderbox;65;66;74;74;73;74;70;72;70;72 06:44:06 INFO - PROCESS | 1954 | |10;stanford-crypto-aes;83;93;101;103;75;84;92;100;102;96 06:44:06 INFO - PROCESS | 1954 | |11;stanford-crypto-ccm;134;137;137;136;130;135;136;149;229;137 06:44:06 INFO - PROCESS | 1954 | |12;stanford-crypto-pbkdf2;158;160;160;213;225;183;159;158;158;158 06:44:06 INFO - PROCESS | 1954 | |13;stanford-crypto-sha256-iterative;67;67;66;68;67;65;66;67;65;65 06:44:06 INFO - PROCESS | 1954 | __end_tp_report 06:44:06 INFO - PROCESS | 1954 | __start_cc_report 06:44:06 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,2004 06:44:06 INFO - PROCESS | 1954 | __end_cc_report 06:44:06 INFO - PROCESS | 1954 | __startTimestamp1469627046523__endTimestamp 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | ------- Summary: start ------- 06:44:06 INFO - PROCESS | 1954 | Number of tests: 14 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:4.16 (3.7%) stddev-sans-first:0.71 06:44:06 INFO - PROCESS | 1954 | Values: 100.0 114.0 113.0 113.0 113.0 114.0 112.0 113.0 112.0 113.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:143.00 stddev:25.69 (18.0%) stddev-sans-first:26.79 06:44:06 INFO - PROCESS | 1954 | Values: 144.0 108.0 105.0 171.0 137.0 95.0 160.0 138.0 142.0 106.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.90 (1.0%) stddev-sans-first:1.56 06:44:06 INFO - PROCESS | 1954 | Values: 180.0 183.0 185.0 185.0 185.0 184.0 180.0 184.0 184.0 184.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#3] audio-fft Cycles:10 Average:78.50 Median:74.50 stddev:25.16 (33.8%) stddev-sans-first:26.63 06:44:06 INFO - PROCESS | 1954 | Values: 83.0 95.0 84.0 134.0 55.0 55.0 59.0 66.0 95.0 59.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:89.00 stddev:12.68 (14.2%) stddev-sans-first:13.12 06:44:06 INFO - PROCESS | 1954 | Values: 81.0 87.0 96.0 91.0 100.0 99.0 99.0 69.0 67.0 100.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.79 (2.0%) stddev-sans-first:1.87 06:44:06 INFO - PROCESS | 1954 | Values: 88.0 85.0 88.0 87.0 88.0 85.0 90.0 86.0 89.0 85.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:1.34 (1.4%) stddev-sans-first:0.71 06:44:06 INFO - PROCESS | 1954 | Values: 96.0 92.0 93.0 92.0 92.0 93.0 93.0 93.0 92.0 91.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:84.50 stddev:20.74 (24.5%) stddev-sans-first:18.53 06:44:06 INFO - PROCESS | 1954 | Values: 126.0 140.0 86.0 85.0 83.0 86.0 83.0 84.0 85.0 84.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:1.69 (3.2%) stddev-sans-first:1.66 06:44:06 INFO - PROCESS | 1954 | Values: 55.0 56.0 52.0 53.0 51.0 55.0 52.0 54.0 52.0 52.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:72.50 stddev:3.27 (4.5%) stddev-sans-first:2.65 06:44:06 INFO - PROCESS | 1954 | Values: 65.0 66.0 74.0 74.0 73.0 74.0 70.0 72.0 70.0 72.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:83.50 stddev:9.48 (11.4%) stddev-sans-first:9.35 06:44:06 INFO - PROCESS | 1954 | Values: 83.0 93.0 101.0 103.0 75.0 84.0 92.0 100.0 102.0 96.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:137.00 stddev:29.56 (21.6%) stddev-sans-first:31.03 06:44:06 INFO - PROCESS | 1954 | Values: 134.0 137.0 137.0 136.0 130.0 135.0 136.0 149.0 229.0 137.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:160.00 stddev:25.47 (15.9%) stddev-sans-first:26.41 06:44:06 INFO - PROCESS | 1954 | Values: 158.0 160.0 160.0 213.0 225.0 183.0 159.0 158.0 158.0 158.0 06:44:06 INFO - PROCESS | 1954 | 06:44:06 INFO - PROCESS | 1954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 06:44:06 INFO - PROCESS | 1954 | Values: 67.0 67.0 66.0 68.0 67.0 65.0 66.0 67.0 65.0 65.0 06:44:06 INFO - PROCESS | 1954 | -------- Summary: end -------- 06:44:06 INFO - PROCESS | 1954 | 06:44:07 INFO - TEST-INFO | 1954: exit 0 06:44:07 INFO - TEST-OK | kraken | took 59176ms 06:44:07 INFO - SUITE-END | took 1175s 06:44:07 INFO - Completed test suite (00:19:35) 06:44:07 INFO - javascript benchmark 06:44:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.2917272786344, 3297.1592243977784, 3315.8342082895847, 3345.096823857557, 3358.4290670585997, 5553.055980483317, 5583.776648934037, 5603.7478313475885, 5671.177679236508, 5711.514521265693, 5670.461306175917, 5683.431656834315, 5694.743736531856, 5720.742566584503, 5731.340895796985, 5114.693118412907, 5133.717645529495, 5173.6119791015635, 5177.663451875635, 5244.0, 5214.843554693346, 5225.320708307915, 5226.425093239741, 5235.973820130913, 5236.502532259429, 5104.515071068243, 5134.614903882205, 5137.178051511759, 5148.459411761767, 5219.739013049351, 5220.634555581111, 5237.973810130963, 5247.658902171384, 5255.947440525622, 5314.495122963351, 5053.595712343041, 5056.747162641853, 5074.923876141861, 5165.354330708661, 5177.352830896138, 5650.180723795068, 5664.093745000821, 5664.320281566238, 5665.12190610456, 5713.457221563945, 6249.0626406039, 6250.624962502265, 6257.186565746424, 6257.8748425031245, 6258.906116408258, 5224.686518808846, 6149.077638354238, 6167.814965551041, 6280.309165991783, 6322.304546499881, 5695.943040569583, 5792.4497172768515, 5814.970925145347, 5815.069588865804, 5815.621984571074, 6310.558260921692, 6326.873462530723, 6328.71520781566, 6331.810045698544, 6341.778037768656, 5917.408259174134, 6060.606060606046, 6135.723892424851, 6152.2002139722445, 6182.381761823872, 6261.937380626136, 6269.404406581368, 6275.6862156892885, 6281.842953926189, 6290.150829638032, 6107.114468402056, 6193.38066193377, 6201.131841542187, 6204.441600255908, 6224.564280500412, 6023.969880150659, 6077.088436734525, 6095.0, 6136.764169924236, 6197.4732147767045, 4848.7090774553635, 4881.096997055556, 4887.291342755351, 4887.315775791392, 4914.778834952435, 4356.738595684269, 4373.169097871394, 4382.364557139261, 4418.668599855024, 4439.445069366329, 5694.686792226385, 5708.4862362387585, 5719.113537401626, 5768.134779782982, 5771.624844385102, 6087.756489740449, 6126.853582554517, 6235.937640623536, 6283.874322513524, 6286.245650521875, 5626.352969408488, 5629.943700562942, 5655.80204692842, 5661.2923384576925, 5661.7735290588735, 5332.200169974535, 5338.439463856317, 5342.465753424626, 5353.759080841293, 5364.91952620711, 6270.3416141305415, 6283.434490895841, 6291.937080629135, 6326.746930122744, 6356.936430635635, 5646.124850648156, 5689.34572524165, 6275.811725648238, 6281.7173227203975, 6285.0, 6270.717817698123, 6299.496040316672, 6323.841903952438, 6326.746930122744, 6327.651979141191, 5961.016432288624, 6065.363136870565, 6102.206713127265, 6148.1699970503405, 6167.352427995175, 6197.752089916353, 6206.162168107247, 6207.844803879939, 6220.7511699531515, 6229.813105606839, 5916.201312822714, 6000.168850969197, 6001.459868611846, 6012.218411606638, 6056.57603967718, 5893.499052580407, 5959.0, 5962.0, 5999.040153575407, 6019.759209631566, 5899.410058994066, 5908.7045647718305, 5912.201852749996, 5921.61509501881, 5950.047992321208, 4893.9021219576125, 6063.575549711654, 6072.301685306246, 6072.908906366496, 6088.756449742138, 5707.800226992202, 5763.567732420001, 5779.335376431596, 5827.59206855516, 5832.504237139721], "value": 5654.037657244986, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.8124793826285, 514.5549100028475, 519.0035132545512, 535.6598559914455, 562.5864989232915, 30564.38871222577, 42331.78834105829, 43449.0, 43539.0, 46117.53882461183, 44387.778061109646, 44804.0, 44873.0, 44963.32555011677, 44975.55024449754, 8813.42712723671, 9726.513674316273, 9905.207583393347, 9921.851172232422, 9931.304808663397, 8779.078196789342, 11975.700607484796, 12204.20672656279, 12229.877701223008, 12389.38053097346, 12165.513379464855, 12198.878011219907, 12237.938810305935, 12240.693982650417, 12251.387430628478, 8746.69386571467, 8952.060033696433, 8960.103989601053, 12057.975072118881, 12130.029597632212, 8919.152680495343, 11629.88370116305, 11901.404929753564, 11907.106966977512, 12175.695607609881, 42180.15639687189, 42512.0, 42553.787231063645, 42597.0, 42699.7865010676, 3522.7005704515145, 3586.748927575071, 3594.281143771256, 4082.9183416331803, 4118.128958778739, 31660.683393166004, 32963.670363296296, 33188.33623327544, 33268.6673133268, 33408.665913340796, 3016.9547456787946, 3044.086773967823, 3199.488081906907, 3234.8059116453087, 3310.470324748029, 534632.0, 538719.0, 539157.0, 541240.0, 543382.0, 34464.310713785584, 62139.689301554106, 62453.0, 62618.0, 62941.68529157325, 34227.82886085554, 36037.91886243417, 36120.638793612256, 36187.4571881422, 36215.27569448649, 14895.851041489446, 15047.097174169367, 15057.397704091933, 15075.020123691926, 15132.697346053237, 11464.140189485655, 11600.6519804406, 11607.6517704469, 11823.231489953125, 12724.872751272369, 7539.077535228591, 7771.145174030852, 7773.261540153676, 7780.338671212897, 7963.681452741941, 3310.851011704431, 3324.3185147044796, 3334.9666503334656, 3351.9329613407594, 3362.09223509651, 937.8827646544182, 948.0093302498905, 984.9162821160281, 992.1368409483796, 1019.6431249062769, 30017.849910750305, 30995.535066973564, 31095.844520777253, 31504.684953150634, 31906.521402178987, 1121.1815374776459, 1128.9830652540218, 1143.6569029291345, 1172.2146162071263, 1210.6852218423098, 12917.547885823951, 16135.757963630555, 16349.836501635069, 16714.331426743036, 16784.0, 178815.0, 180033.0, 180223.0, 180601.0, 180619.09690451465, 62053.68973155195, 62600.0, 62620.37379626236, 62780.37219627837, 62812.68593657002, 1301.7006088599676, 1423.9605088285402, 1440.0423718227262, 1446.2551785830517, 1455.5269537400388, 30126.31200862038, 31596.210094746897, 31607.84196079005, 32633.673663263064, 32646.0, 51586.74206628943, 51867.481325186265, 52152.0, 52209.955800883006, 52481.7375913118, 589772.0, 594062.0, 595208.0, 595494.0, 595819.0, 23612.881935590212, 25328.493430130926, 27238.72761272362, 27239.0, 27274.727252727218, 49921.50235492941, 50002.0, 50409.49590504048, 50768.0, 51624.0, 40316.79841600773, 40445.797771010955, 40934.9766255846, 41428.0, 41929.79035104805, 30439.295052192556, 41457.0, 41484.58515414807, 41917.79041104775, 41984.0, 39633.80183099066, 39858.800705996284, 40354.39468408035, 40561.79719101386, 40730.38904416496, 31448.37103257876, 34224.315513689085, 35584.64415355813, 36257.45613815848, 36365.0, 24275.75724242735, 25938.351541210865, 26017.869910650326, 26137.73862261353, 26606.201813944837], "value": 18881.107713006088, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.3748215587033, 382.06394333881985, 389.06235971309144, 390.170886865411, 410.1243844392222, 16062.919685401572, 16595.0, 21593.56812863744, 21662.783372166272, 21815.01832417541, 21431.928403579837, 21887.781122188775, 22241.55516889661, 22276.331710048726, 22291.331260062183, 5830.416958304168, 5841.766329346821, 6154.661493617849, 6171.7531298748, 6191.195144631214, 3921.117748506592, 4142.440770495989, 5873.911891321633, 6172.9074063889075, 6235.875282494348, 6176.907346389808, 6208.565400421972, 6218.813435596917, 6222.657753823538, 6240.063990401431, 5847.532197424196, 6189.96905015472, 6197.442230199259, 6213.782517611862, 6220.657863817488, 5184.870378240536, 6035.849103772397, 6036.366181550918, 6040.637561746309, 6051.273847138327, 13145.211287322696, 13646.590602281947, 13647.11293765902, 13653.726925461435, 16756.916215418965, 2173.119886445992, 2198.6482162854027, 2199.3072182262536, 2343.3321503371626, 2866.6273384459887, 10037.849432258521, 10044.196464282913, 10050.296479246455, 10484.0, 10552.366857988545, 1818.8363047325672, 1838.218757028271, 1838.7885393179822, 1896.8482521398255, 1963.1656545967905, 116226.0, 126569.0, 128758.99999999907, 129644.0, 129787.70212297945, 11647.30116193031, 11663.59177428786, 11696.35670038156, 11720.308102947543, 11769.293842369485, 14588.270586470633, 14650.047746896418, 14699.853001469848, 14705.191214483306, 14739.52604739539, 8431.28334091609, 8594.613242404106, 8598.78503037429, 8637.827243455222, 8663.913360866436, 8461.47693415179, 8487.405881588229, 8494.428530721712, 8573.871391929126, 8586.95706521476, 4482.551744825557, 4550.362949187116, 4556.430446194226, 4629.166749985035, 4644.535546445329, 1920.6459446090473, 1927.6915693489254, 1937.883726976386, 1938.234397413014, 1972.9210831566863, 846.2341580869323, 869.3523325122698, 942.5805516545072, 946.2619157057507, 965.4931161140457, 21532.677009844865, 21949.890250548644, 22087.889560552416, 22103.889480552814, 22124.336269911928, 880.5817236812591, 1007.1388962437157, 1014.7767491151935, 1049.6588608702202, 1057.7197042783669, 10948.726281842859, 11480.598179063692, 11544.711382215512, 11545.191836571359, 11569.710757231136, 595264.0, 611639.0, 614825.9258703678, 625039.0, 632269.0, 99877.50061249793, 127942.0, 131579.0, 132354.33822831017, 134194.0, 950.7955789505289, 983.852422136671, 1024.3904876598413, 1040.2042437535292, 1053.7629033467408, 16077.437289694804, 20428.387148385573, 20691.586168276852, 20781.75309481436, 20793.960301984538, 56446.717766410904, 57357.713211435344, 57960.4203957955, 58081.70959145177, 58473.538161546305, 74765.62617186879, 78826.6058669703, 84499.57750211455, 86027.70958435503, 86069.13930860611, 7563.205863384485, 7615.923840761521, 7979.880301795594, 8026.558539280396, 8422.452540584842, 10357.533910974174, 12530.624081277576, 12796.680082998, 12815.743685126059, 13094.869051309746, 10576.788464230825, 10969.50637221343, 10974.561017559208, 11254.212205145888, 11306.717332066764, 11044.0, 11084.501197446296, 11194.83207751868, 11234.494447749707, 11328.0, 11341.376224307742, 11362.715932101764, 11504.827427588425, 11633.011194048262, 11799.587014454453, 5901.645901245939, 8054.597270136587, 8164.7550573482895, 8218.013838339437, 8308.293795027248, 9311.674091406769, 11224.55101795919, 11392.829107563228, 11497.82753258685, 11614.70963225926], "value": 9577.07085140754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.7062952094818, 1581.4148764956967, 1794.8294911983357, 1925.528245579833, 1953.433504283757, 7952.125266220725, 8219.794505137375, 8605.784855378612, 9050.683226087089, 9058.818823623524, 8060.596970151499, 8174.6321415536295, 8533.91466085339, 8725.738227853175, 8740.562971851397, 2342.723215847359, 2357.752435994217, 2397.1010870923405, 2397.1010870923405, 2400.2799160251943, 2111.9366419007456, 2189.6934429179846, 2379.6073647848084, 2389.354874183969, 2424.4787370715294, 1865.0, 2311.734150572689, 2358.6697862299293, 2375.904963801446, 2377.8811059447044, 2076.1487790006177, 2368.7275963264187, 2387.510560335127, 2389.307100940725, 2394.820388470872, 2301.8849057547054, 2314.0281081945623, 2319.8724070176136, 2333.241696448661, 2334.3230463165833, 8845.425047371964, 8930.151635594608, 8931.64273429062, 8945.73162805117, 8960.417572857743, 5258.8685282867855, 5370.087085195526, 5432.945670543283, 5436.266104075979, 5455.1271796512365, 3565.3760591896316, 3638.126849556114, 3715.758475699071, 3723.8696645617542, 3728.81355932205, 1043.8068957242933, 1059.4808543813476, 1083.1767856429092, 1099.4612639806517, 1104.309806371018, 11706.824397633956, 11737.295762254207, 11766.823497647541, 11777.705557361049, 11791.410429478492, 4529.796159172845, 4752.691075080109, 4834.20235661119, 4835.347228124228, 4849.878753031202, 9893.703188904201, 9945.005499450142, 9967.053129952643, 9970.953049929798, 9985.400875947467, 5358.223057656618, 5369.677819330853, 5370.812021579304, 5371.6239863209985, 5375.81184658535, 4416.381706561084, 4913.287573301851, 4927.088488629615, 4944.233643785219, 4950.5544500994365, 3811.8284677189595, 3812.294725475796, 3857.8842634721004, 3897.707671924617, 3915.7063220258024, 1095.2333366643381, 1113.4043286841502, 1147.913906457019, 1164.004775916579, 1167.9124065695107, 840.2899549880464, 856.7172832965107, 949.2643201518878, 964.3731574476647, 1002.7192386131894, 5987.970060149672, 6029.879402412015, 6121.510279177655, 6128.724207410677, 6363.681815909186, 958.8322043642474, 962.5860879821604, 965.1699538396973, 980.9509524523745, 992.7319623701704, 3866.6133386661672, 4029.15387768566, 4127.112670775738, 4176.874693759192, 4200.159968006411, 10394.636187733393, 10661.520231589597, 10665.093467055232, 10776.730581735519, 10784.352938823538, 9773.609055637695, 10735.409552474688, 10738.194635402377, 10738.409387483607, 10765.784684306427, 7488.588127653032, 7906.130325664172, 8194.959025204837, 8205.671773129128, 8303.335733141334, 5909.55678324136, 6084.6349219047, 6141.84645383869, 6213.502919766317, 6366.29970703213, 9895.752606184902, 9972.700818975442, 10186.33788803755, 10212.642557510748, 10220.182385409, 10386.870691508373, 10418.114460270961, 10442.791144177227, 10470.476476176009, 10492.213084018882, 7557.508761930677, 7603.7338693145475, 7642.388608911385, 7698.0762308523335, 7758.806029849299, 8248.0, 8283.29591984688, 8336.999560052833, 8353.624086915981, 8365.665373384996, 7165.713371465083, 7307.817304567428, 7390.0, 7431.628418579157, 7479.401647868266, 7240.782776516713, 7323.7436689715605, 7328.5969271690565, 7343.082114735658, 7387.889181662387, 5882.499987500939, 7145.071140751669, 7147.3210045045635, 7168.207948012997, 7174.2108368078425, 4816.133096042606, 5014.623903207201, 5094.108531006985, 5146.871328216825, 5254.789808407774, 4368.563143685606, 5097.8215762448135, 5129.897402052013, 5227.660202087004, 5341.866453338542], "value": 4613.315178137311, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.62899633450854, 304.53710360252416, 316.3625295030513, 321.02408677620036, 321.8519481038722, 4779.545943135406, 5235.915985531282, 5396.622236443451, 5401.3248343957, 5451.3458384993755, 4452.706855969179, 5136.460671629481, 5243.056249875021, 5244.527992480676, 5392.921415716863, 3327.866885324584, 4064.4106604542326, 4092.815823287959, 4112.1775644870995, 4117.917641647173, 3769.754965927206, 3821.273957947995, 4101.97949010256, 4106.322456794625, 4133.090720041601, 4077.4087757275324, 4079.551249362568, 4087.3255912774357, 4107.609777071189, 4108.424820525129, 4015.518137823465, 4044.6562042226446, 4073.6130067643526, 4088.6115818997146, 4108.5275193352845, 3560.287942411502, 3572.910677233064, 3579.427291633326, 3582.390993531106, 3597.964020359815, 4994.001199760062, 5249.0, 5252.501012403815, 5264.263003179558, 5266.420693723688, 3848.499695039655, 3992.8819930419527, 4654.743989080599, 4704.317873908299, 4704.341392205094, 5227.71920879387, 5519.365272993617, 5590.301212348457, 5636.859078523029, 5652.547796176296, 2726.740959608834, 3856.5730679648427, 3857.961420385788, 3870.3226935286493, 3883.3786594145085, 4859.080663138076, 4990.476000020019, 5610.7194640268235, 5877.9412205877825, 5881.764729410818, 3092.9845350773103, 3366.629670736217, 3834.9041273967814, 3957.940630890539, 3965.980170099131, 3204.599425071866, 3474.5656792900886, 3923.7841918694744, 3971.860984865516, 3976.304146774303, 4017.5580686124504, 4249.873783447389, 4369.0, 4570.497245303014, 4638.652101092432, 4919.647097048341, 4965.528274813887, 4968.403791544965, 5557.6109672323355, 5776.942230577724, 1010.6058637131524, 1221.8900298973135, 1281.4874050379922, 1308.371981448891, 1333.926634035118, 820.6869009584736, 824.7773101262626, 869.8956125264882, 885.017630430222, 919.8298314811648, 5292.629515933848, 5294.364676238876, 5298.6290959633225, 5310.902193633493, 5344.412114667383, 5804.4775970162045, 5866.560007999417, 5876.823695289148, 5878.764849405976, 5911.793087242012, 5122.846314610568, 5196.222372128123, 5263.6578622389425, 5266.394364648115, 5313.521783039545, 2383.9165629202894, 2753.4493101379803, 2831.0, 2915.3731947631363, 3055.618047744032, 5326.493983071602, 5676.205331253628, 5934.495567876779, 5977.193078934288, 6029.427204415573, 5822.214001109881, 6040.335563088057, 6150.384961503813, 6156.230471191101, 6159.84600384994, 5308.389535203501, 5634.830955071437, 5786.710664466676, 5943.554233432424, 6166.599171053867, 4881.438634557071, 5691.658500489983, 5813.76744930198, 5986.0, 6006.579539432198, 4857.757112144449, 5168.0, 5798.971005144947, 5872.915115134237, 5900.793472228452, 5952.077427998667, 6174.332930108935, 6212.72042758086, 6243.531735119794, 6252.437280644764, 3449.1549570355423, 3910.3352430086384, 3948.526176858795, 3957.465742124776, 3961.8811435656976, 5104.515071068391, 5538.418466061166, 5807.041838096836, 5914.645121292736, 5920.957269741933, 4688.413948256468, 5411.756470958895, 5681.630694004876, 5740.68426236561, 5755.223044888887, 5318.893622127458, 5622.0, 5712.6286791358425, 5718.913406452761, 5734.595024219093, 4858.732769697701, 5304.814331498379, 5566.610337276514, 5595.804146854841, 5624.240727501735, 4898.118338699069, 5044.646874718735, 5721.5136713379825, 5761.5390768739235, 5767.250257466502, 4005.639492445694, 5450.754716037709, 5606.411326810626, 5608.719564021864, 5613.410591887793], "value": 4103.202489056546, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.8804991201407, 780.2276242217553, 843.628803326536, 853.9624356406964, 856.5777537636382, 12981.286029268387, 14912.403503859847, 15983.76024359634, 16075.115868627221, 16156.919215403906, 14607.707845843077, 14796.55610331689, 14796.6300842479, 14813.555593332218, 14828.110313381205, 727.3526561360394, 750.6959681329043, 905.0768216419249, 905.44767691708, 934.5934518484447, 899.6311512279966, 915.199200699388, 915.5513798238849, 915.789368445258, 917.7522069041355, 959.1655259923876, 959.7984423271087, 959.8848138223423, 960.4525420510312, 963.4123184857232, 763.900692909921, 942.9009953954804, 956.2206801456825, 972.3193764364902, 972.7227740094039, 720.4524561333373, 720.5064530796369, 721.6283613938825, 721.9639018049129, 724.2395484741, 15207.619809504851, 15238.542843714706, 15245.466408675755, 15251.61870953224, 15326.770098448533, 14248.145111293356, 14406.423743050265, 14426.855731442656, 14439.278036098152, 14585.70828583433, 10886.0, 11976.88023119775, 12812.87187128126, 13181.60455186346, 13209.33953302331, 4583.661480699974, 4598.287265473857, 4847.0, 4894.5105489451125, 4902.681325713817, 107093.46453267762, 107956.0, 108017.45991270072, 108045.91954080516, 108837.0, 10812.297200681931, 10990.0, 11223.66329010131, 11422.31466112036, 11595.884041159481, 22994.655080173812, 23166.884165579064, 23224.767752322598, 23252.069917203124, 23261.3021609352, 8018.0, 8030.638621262057, 8086.989126359205, 8239.835203295901, 8245.917540824636, 4448.733076015449, 4507.842225522091, 4517.277235642281, 4536.228841097021, 4719.079779442975, 3357.244619960489, 4234.513031001475, 4235.745855248695, 4262.062346283813, 4272.6795490338345, 750.1560744162816, 752.5635131623645, 755.5013690963938, 755.6372940988356, 770.1951460723558, 586.3272835318279, 614.2046050364788, 639.8304449320933, 682.4403988729293, 704.6300692136567, 9337.673181438617, 9637.42175469474, 9653.806923861483, 9716.854247186298, 9761.267904907161, 598.2462097756771, 609.1411110334407, 615.5721773367557, 658.7167517967224, 692.3872372949976, 4377.190219809346, 4711.128441238382, 4730.148573256847, 4748.4539277982785, 4796.736179510161, 159476.0, 160321.0, 162460.0, 162959.0, 168648.0, 142726.0, 143296.28351858174, 143757.0, 145214.27392862967, 148701.0, 113302.0, 116630.41684791521, 117513.23730144055, 119047.0, 124312.0, 8702.564871756513, 8930.508822014852, 8999.460032398078, 9122.92269192158, 9193.954030230074, 57766.0, 59987.0, 60246.0, 60649.69675151596, 63599.682001589696, 69490.26274343181, 70039.6498017527, 78506.42987140111, 80332.59833700793, 91832.54083729538, 11941.223820451643, 13511.864881351454, 13591.86408135906, 14085.577432677037, 14116.0, 14944.925275373553, 14963.783722231316, 15062.623434414227, 15190.696186076437, 15353.539393818204, 11625.302481851117, 14170.716585668435, 14319.856801432268, 14410.063345882483, 14700.0, 14294.142351458946, 14336.641583960067, 14351.569452916428, 14459.855401445851, 14770.261486925396, 13640.249786261851, 14103.365348559135, 14493.20287384204, 14532.418703251751, 14552.27238638085, 11518.251313664585, 11540.884591154318, 11541.307521548733, 11607.76784464289, 11838.940805295919, 9236.399634023766, 10279.640212592523, 10454.484099805333, 11677.591284305008, 11688.824667629822], "value": 8134.604084714249, "unit": "score"}], "name": "dromaeo_css", "value": 7348.339495393095, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 114.0, 113.0, 113.0, 113.0, 114.0, 112.0, 113.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 105.0, 171.0, 137.0, 95.0, 160.0, 138.0, 142.0, 106.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 185.0, 185.0, 185.0, 184.0, 180.0, 184.0, 184.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 95.0, 84.0, 134.0, 55.0, 55.0, 59.0, 66.0, 95.0, 59.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 87.0, 96.0, 91.0, 100.0, 99.0, 99.0, 69.0, 67.0, 100.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 87.0, 88.0, 85.0, 90.0, 86.0, 89.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 92.0, 93.0, 92.0, 92.0, 93.0, 93.0, 93.0, 92.0, 91.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 140.0, 86.0, 85.0, 83.0, 86.0, 83.0, 84.0, 85.0, 84.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 52.0, 53.0, 51.0, 55.0, 52.0, 54.0, 52.0, 52.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 74.0, 74.0, 73.0, 74.0, 70.0, 72.0, 70.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 101.0, 103.0, 75.0, 84.0, 92.0, 100.0, 102.0, 96.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 137.0, 136.0, 130.0, 135.0, 136.0, 149.0, 229.0, 137.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 160.0, 213.0, 225.0, 183.0, 159.0, 158.0, 158.0, 158.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 68.0, 67.0, 65.0, 66.0, 67.0, 65.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1469.7000000000003, "alertThreshold": 2.0}]} 06:44:07 INFO - Return code: 0 06:44:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:44:07 INFO - # TBPL SUCCESS # 06:44:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:44:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:44:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:44:07 INFO - 2016-07-27 06:44:07.820 screenresolution[1958:15076] starting screenresolution argv=screenresolution get 06:44:07 INFO - 2016-07-27 06:44:07.823 screenresolution[1958:15076] Display 0: 1600x1200x32@60 06:44:07 INFO - 2016-07-27 06:44:07.829 screenresolution[1959:15079] starting screenresolution argv=screenresolution list 06:44:07 INFO - Available Modes on Display 0 06:44:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:44:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:44:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:44:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:44:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:44:08 INFO - Intel Iris: 06:44:08 INFO - Chipset Model: Intel Iris 06:44:08 INFO - Type: GPU 06:44:08 INFO - Bus: Built-In 06:44:08 INFO - VRAM (Dynamic, Max): 1536 MB 06:44:08 INFO - Vendor: Intel (0x8086) 06:44:08 INFO - Device ID: 0x0a2e 06:44:08 INFO - Revision ID: 0x0009 06:44:08 INFO - Displays: 06:44:08 INFO - Display: 06:44:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:44:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:44:08 INFO - Display Serial Number: 1600x1200 60 06:44:08 INFO - Main Display: Yes 06:44:08 INFO - Mirror: Off 06:44:08 INFO - Online: Yes 06:44:08 INFO - Rotation: Supported 06:44:08 INFO - Return code: 0 06:44:08 INFO - Running post-action listener: _resource_record_post_action 06:44:08 INFO - ##### 06:44:08 INFO - ##### Finished run-tests step (success) 06:44:08 INFO - ##### 06:44:08 INFO - Running post-run listener: _resource_record_post_run 06:44:08 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 20992; Write bytes: 708232192; Read time: 2; Write time: 1956 06:44:08 INFO - TinderboxPrint: CPU usage
53.5% 06:44:08 INFO - TinderboxPrint: I/O read bytes / time
20,992 / 2 06:44:08 INFO - TinderboxPrint: I/O write bytes / time
708,232,192 / 1,956 06:44:08 INFO - TinderboxPrint: CPU idle
2,253.5 (46.5%) 06:44:08 INFO - TinderboxPrint: CPU system
970.8 (20.0%) 06:44:08 INFO - TinderboxPrint: CPU user
1,621.3 (33.5%) 06:44:08 INFO - TinderboxPrint: Swap in / out
406,319,104 / 0 06:44:08 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396249600; Write bytes: 391652864; Read time: 29355; Write time: 727 06:44:08 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 20992; Write bytes: 296713728; Read time: 2; Write time: 1177 06:44:08 INFO - Running post-run listener: _upload_blobber_files 06:44:08 INFO - Blob upload gear active. 06:44:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:44:08 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 06:44:08 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'] 06:44:08 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 06:44:08 INFO - (blobuploader) - INFO - Open directory for files ... 06:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:44:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:09 INFO - (blobuploader) - INFO - Done attempting. 06:44:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:44:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:44:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:10 INFO - (blobuploader) - INFO - Done attempting. 06:44:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:44:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:44:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:13 INFO - (blobuploader) - INFO - Done attempting. 06:44:13 INFO - (blobuploader) - INFO - Iteration through files over. 06:44:13 INFO - Return code: 0 06:44:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:44:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:44:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbd84e92492ffd7b29339ca2b2c7428a6092da6b040f8d8b4d690872459f1eeb27dfd14c2afe181e4b6602fe2626917d30b22f6218d24d070b78a856a1577b5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71271299f428d8d23343347bc31e700ee33b066d1b1deb06cd427a92d764386ca45da78fa07fb1bc685a12d2a7a8f4a9e7faeeba8c02d6b4626ab46c3031f4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d852220f111fb70242ea8d5937af9e1b8f18eb405d8b20da8cdebccfbcb28efe6ac319cd6a7bd4080da134d11dc6eb465763e7e837916ce4e63c46844433337d"} 06:44:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:44:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:44:13 INFO - Contents: 06:44:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbd84e92492ffd7b29339ca2b2c7428a6092da6b040f8d8b4d690872459f1eeb27dfd14c2afe181e4b6602fe2626917d30b22f6218d24d070b78a856a1577b5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71271299f428d8d23343347bc31e700ee33b066d1b1deb06cd427a92d764386ca45da78fa07fb1bc685a12d2a7a8f4a9e7faeeba8c02d6b4626ab46c3031f4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d852220f111fb70242ea8d5937af9e1b8f18eb405d8b20da8cdebccfbcb28efe6ac319cd6a7bd4080da134d11dc6eb465763e7e837916ce4e63c46844433337d"} 06:44:13 INFO - Running post-run listener: copy_logs_to_upload_dir 06:44:13 INFO - Copying logs to upload dir... 06:44:13 INFO - mkdir: /builds/slave/test/build/upload/logs 06:44:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.527539 ========= master_lag: 0.67 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-27 06:44:13.915446) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:13.918857) ========= 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.49cX6uzle5/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.BjP53ECy4k/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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/fbd84e92492ffd7b29339ca2b2c7428a6092da6b040f8d8b4d690872459f1eeb27dfd14c2afe181e4b6602fe2626917d30b22f6218d24d070b78a856a1577b5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71271299f428d8d23343347bc31e700ee33b066d1b1deb06cd427a92d764386ca45da78fa07fb1bc685a12d2a7a8f4a9e7faeeba8c02d6b4626ab46c3031f4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d852220f111fb70242ea8d5937af9e1b8f18eb405d8b20da8cdebccfbcb28efe6ac319cd6a7bd4080da134d11dc6eb465763e7e837916ce4e63c46844433337d"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011223 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467669777/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbd84e92492ffd7b29339ca2b2c7428a6092da6b040f8d8b4d690872459f1eeb27dfd14c2afe181e4b6602fe2626917d30b22f6218d24d070b78a856a1577b5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71271299f428d8d23343347bc31e700ee33b066d1b1deb06cd427a92d764386ca45da78fa07fb1bc685a12d2a7a8f4a9e7faeeba8c02d6b4626ab46c3031f4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d852220f111fb70242ea8d5937af9e1b8f18eb405d8b20da8cdebccfbcb28efe6ac319cd6a7bd4080da134d11dc6eb465763e7e837916ce4e63c46844433337d"}' ========= master_lag: 0.89 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:14.815577) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:14.815905) ========= 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.49cX6uzle5/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.BjP53ECy4k/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005184 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 06:44:14.843728) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 06:44:14.844326) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 06:44:14.845056) ========= ========= Total master_lag: 1.65 =========