builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0164 starttime: 1470522172.86 results: success (0) buildid: 20160712030202 builduid: 053e73d400964a3fae7b1d8480188d03 revision: aac8ff1024c553d9c92b85b8b6ba90f65de2ed08 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:52.860694) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:52.861105) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:52.861604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:53.005535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:53.005833) ========= 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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-08-06 15:22:53-- 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% 9.38M=0.001s 2016-08-06 15:22:53 (9.38 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.472653 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:53.500893) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:53.501218) ========= 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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.027580 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:53.550233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-06 15:22:53.550583) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev aac8ff1024c553d9c92b85b8b6ba90f65de2ed08 --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 aac8ff1024c553d9c92b85b8b6ba90f65de2ed08 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-08-06 15:22:53,627 truncating revision to first 12 chars 2016-08-06 15:22:53,627 Setting DEBUG logging. 2016-08-06 15:22:53,627 attempt 1/10 2016-08-06 15:22:53,628 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/aac8ff1024c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-06 15:22:54,380 unpacking tar archive at: mozilla-central-aac8ff1024c5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.041821 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-06 15:22:54.614947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:54.615225) ========= script_repo_revision: aac8ff1024c553d9c92b85b8b6ba90f65de2ed08 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:54.615629) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:54.615952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 15:22:54.634142) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-06 15:22:54.634468) ========= /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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:22:54 INFO - MultiFileLogger online at 20160806 15:22:54 in /builds/slave/test 15:22:54 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 15:22:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:22:54 INFO - {'append_to_log': False, 15:22:54 INFO - 'base_work_dir': '/builds/slave/test', 15:22:54 INFO - 'blob_upload_branch': 'Firefox', 15:22:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:22:54 INFO - 'branch': 'Firefox', 15:22:54 INFO - 'buildbot_json_path': 'buildprops.json', 15:22:54 INFO - 'config_files': ('talos/mac_config.py',), 15:22:54 INFO - 'default_actions': ('clobber', 15:22:54 INFO - 'read-buildbot-config', 15:22:54 INFO - 'download-and-extract', 15:22:54 INFO - 'populate-webroot', 15:22:54 INFO - 'create-virtualenv', 15:22:54 INFO - 'install', 15:22:54 INFO - 'run-tests'), 15:22:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:22:54 INFO - 'download_minidump_stackwalk': True, 15:22:54 INFO - 'download_symbols': 'ondemand', 15:22:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:22:54 INFO - 'tooltool.py': '/tools/tooltool.py', 15:22:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:22:54 INFO - '/tools/misc-python/virtualenv.py')}, 15:22:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:22:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:22:54 INFO - 'installer_path': 'installer.exe', 15:22:54 INFO - 'log_level': 'info', 15:22:54 INFO - 'log_name': 'talos', 15:22:54 INFO - 'log_to_console': True, 15:22:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:22:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:22:54 INFO - 'opt_config_files': (), 15:22:54 INFO - 'pip_index': False, 15:22:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:22:54 INFO - 'cmd': ('bash', 15:22:54 INFO - '-c', 15:22:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:22:54 INFO - 'enabled': True, 15:22:54 INFO - 'halt_on_failure': False, 15:22:54 INFO - 'name': 'check_screen_resolution'},), 15:22:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:22:54 INFO - 'cmd': ('bash', 15:22:54 INFO - '-c', 15:22:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:22:54 INFO - 'enabled': True, 15:22:54 INFO - 'halt_on_failure': False, 15:22:54 INFO - 'name': 'check_screen_resolution'},), 15:22:54 INFO - 'run_cmd_checks_enabled': True, 15:22:54 INFO - 'sps_profile': False, 15:22:54 INFO - 'sps_profile_interval': 0, 15:22:54 INFO - 'suite': 'dromaeojs', 15:22:54 INFO - 'system_bits': '32', 15:22:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:22:54 INFO - 'title': 't-yosemite-r7-0164', 15:22:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:22:54 INFO - 'use_talos_json': True, 15:22:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:22:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:22:54 INFO - 'work_dir': 'build'} 15:22:54 INFO - ##### 15:22:54 INFO - ##### Running clobber step. 15:22:54 INFO - ##### 15:22:54 INFO - Running pre-action listener: _resource_record_pre_action 15:22:54 INFO - Running main action method: clobber 15:22:54 INFO - rmtree: /builds/slave/test/build 15:22:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:22:56 INFO - Running post-action listener: _resource_record_post_action 15:22:56 INFO - ##### 15:22:56 INFO - ##### Finished clobber step (success) 15:22:56 INFO - ##### 15:22:56 INFO - ##### 15:22:56 INFO - ##### Running read-buildbot-config step. 15:22:56 INFO - ##### 15:22:56 INFO - Running pre-action listener: _resource_record_pre_action 15:22:56 INFO - Running main action method: read_buildbot_config 15:22:56 INFO - Using buildbot properties: 15:22:56 INFO - { 15:22:56 INFO - "project": "", 15:22:56 INFO - "product": "firefox", 15:22:56 INFO - "script_repo_revision": "production", 15:22:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:22:56 INFO - "repository": "", 15:22:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:22:56 INFO - "buildid": "20160712030202", 15:22:56 INFO - "stage_platform": "macosx64", 15:22:56 INFO - "basedir": "/builds/slave/test", 15:22:56 INFO - "nightly_build": "True", 15:22:56 INFO - "buildnumber": 0, 15:22:56 INFO - "slavename": "t-yosemite-r7-0164", 15:22:56 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:22:56 INFO - "platform": "yosemite_r7", 15:22:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:22:56 INFO - "branch": "mozilla-central", 15:22:56 INFO - "repo_path": "mozilla-central", 15:22:56 INFO - "revision": "aac8ff1024c553d9c92b85b8b6ba90f65de2ed08", 15:22:56 INFO - "pgo_build": "False", 15:22:56 INFO - "builduid": "053e73d400964a3fae7b1d8480188d03", 15:22:56 INFO - "slavebuilddir": "test" 15:22:56 INFO - } 15:22:56 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg. 15:22:56 INFO - Running post-action listener: _resource_record_post_action 15:22:56 INFO - ##### 15:22:56 INFO - ##### Finished read-buildbot-config step (success) 15:22:56 INFO - ##### 15:22:56 INFO - ##### 15:22:56 INFO - ##### Running download-and-extract step. 15:22:56 INFO - ##### 15:22:56 INFO - Running pre-action listener: _resource_record_pre_action 15:22:56 INFO - Running main action method: download_and_extract 15:22:56 INFO - mkdir: /builds/slave/test/build/tests 15:22: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')]} 15:22:56 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.test_packages.json 15:22:56 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:22:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/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 15:22:57 INFO - Downloaded 1199 bytes. 15:22:57 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:22:57 INFO - Using the following test package requirements: 15:22:57 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:22:57 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 15:22:57 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 15:22:57 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'jsshell-mac.zip'], 15:22:57 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 15:22:57 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:22:57 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 15:22:57 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 15:22:57 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 15:22:57 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:22:57 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 15:22:57 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.common.tests.zip 15:22:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/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 15:23:05 INFO - Downloaded 18772477 bytes. 15:23:05 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'] 15:23:05 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 15:23:08 INFO - Return code: 0 15:23:08 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 15: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')]} 15:23:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.common.tests.zip 15:23:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:23:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/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 15:23:08 INFO - Downloaded 18772477 bytes. 15: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'] 15: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 15:23:12 INFO - Return code: 0 15:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.talos.tests.zip 15:23:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/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 15:23:17 INFO - Downloaded 14403317 bytes. 15:23:17 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'] 15:23:17 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 15:23:17 INFO - Return code: 0 15:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg 15:23:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 15:23:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 15:23:19 INFO - Downloaded 112594890 bytes. 15:23:19 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg 15:23:19 INFO - mkdir: /builds/slave/test/properties 15:23:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:23:19 INFO - Writing to file /builds/slave/test/properties/build_url 15:23:19 INFO - Contents: 15:23:19 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg 15:23:19 INFO - Running post-action listener: _resource_record_post_action 15:23:19 INFO - Running post-action listener: set_extra_try_arguments 15:23:19 INFO - ##### 15:23:19 INFO - ##### Finished download-and-extract step (success) 15:23:19 INFO - ##### 15:23:19 INFO - ##### 15:23:19 INFO - ##### Running populate-webroot step. 15:23:19 INFO - ##### 15:23:19 INFO - Running pre-action listener: _resource_record_pre_action 15:23:19 INFO - Running main action method: populate_webroot 15:23:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:23:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:23:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:23:19 INFO - '--tppagecycles', 15:23:19 INFO - '7'], 15:23:19 INFO - 'tests': ['tsvgm']}}, 15:23:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:23:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:23:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:23:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:23:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:23:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:23:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['damp', 'tps']}, 15:23:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'tests': ['damp', 'tps']}, 15:23:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['dromaeo_dom']}, 15:23:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:23:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['basic_compositor_video']}, 15:23:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:23:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['a11yr', 15:23:19 INFO - 'ts_paint', 15:23:19 INFO - 'tpaint', 15:23:19 INFO - 'sessionrestore', 15:23:19 INFO - 'sessionrestore_no_auto_restore', 15:23:19 INFO - 'tabpaint']}, 15:23:19 INFO - 'other-e10s': {'tests': ['a11yr', 15:23:19 INFO - 'ts_paint', 15:23:19 INFO - 'tpaint', 15:23:19 INFO - 'sessionrestore', 15:23:19 INFO - 'sessionrestore_no_auto_restore', 15:23:19 INFO - 'tabpaint']}, 15:23:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:23:19 INFO - 'ts_paint', 15:23:19 INFO - 'tpaint', 15:23:19 INFO - 'sessionrestore', 15:23:19 INFO - 'sessionrestore_no_auto_restore', 15:23:19 INFO - 'tabpaint']}, 15:23:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:23:19 INFO - 'ts_paint', 15:23:19 INFO - 'tpaint', 15:23:19 INFO - 'sessionrestore', 15:23:19 INFO - 'sessionrestore_no_auto_restore', 15:23:19 INFO - 'tabpaint']}, 15:23:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['a11yr', 15:23:19 INFO - 'ts_paint', 15:23:19 INFO - 'tpaint', 15:23:19 INFO - 'sessionrestore', 15:23:19 INFO - 'sessionrestore_no_auto_restore', 15:23:19 INFO - 'tabpaint']}, 15:23:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['a11yr', 15:23:19 INFO - 'ts_paint', 15:23:19 INFO - 'tpaint', 15:23:19 INFO - 'sessionrestore', 15:23:19 INFO - 'sessionrestore_no_auto_restore', 15:23:19 INFO - 'tabpaint']}, 15:23:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['tsvgx', 15:23:19 INFO - 'tsvgr_opacity', 15:23:19 INFO - 'tart', 15:23:19 INFO - 'tscrollx', 15:23:19 INFO - 'cart']}, 15:23:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:23:19 INFO - 'tsvgr_opacity', 15:23:19 INFO - 'tart', 15:23:19 INFO - 'tscrollx', 15:23:19 INFO - 'cart']}, 15:23:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'talos_options': ['--disable-e10s'], 15:23:19 INFO - 'tests': ['tp5o']}, 15:23:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'tests': ['tp5o']}, 15:23:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'talos_options': ['--disable-e10s', 15:23:19 INFO - '--xperf_path', 15:23:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:23:19 INFO - 'tests': ['tp5n']}, 15:23:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:19 INFO - 'talos_options': ['--xperf_path', 15:23:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:23:19 INFO - 'tests': ['tp5n']}}, 15:23:19 INFO - 'talos.zip': {'path': '', 15:23:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:23:19 INFO - Running post-action listener: _resource_record_post_action 15:23:19 INFO - ##### 15:23:19 INFO - ##### Finished populate-webroot step (success) 15:23:19 INFO - ##### 15:23:19 INFO - ##### 15:23:19 INFO - ##### Running create-virtualenv step. 15:23:19 INFO - ##### 15:23:19 INFO - Running pre-action listener: _resource_record_pre_action 15:23:19 INFO - Running main action method: create_virtualenv 15:23:19 INFO - Creating virtualenv /builds/slave/test/build/venv 15:23:19 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 15:23:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:23:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:23:19 INFO - Using real prefix '/tools/python27' 15:23:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:23:20 INFO - Installing distribute.............................................................................................................................................................................................done. 15:23:23 INFO - Installing pip.................done. 15:23:23 INFO - Return code: 0 15:23:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:23:23 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')]} 15:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:23 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')]} 15:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:23 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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:23 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 15:23:23 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 15:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:23 INFO - 'HOME': '/Users/cltbld', 15:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:23 INFO - 'LOGNAME': 'cltbld', 15:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:23 INFO - 'MOZ_NO_REMOTE': '1', 15:23:23 INFO - 'NO_EM_RESTART': '1', 15:23:23 INFO - 'PAGER': '/bin/cat', 15:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:23 INFO - 'PWD': '/builds/slave/test', 15:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:23 INFO - 'SHELL': '/bin/bash', 15:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:23 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:23 INFO - 'USER': 'cltbld', 15:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:23 INFO - 'XPC_FLAGS': '0x0', 15:23:23 INFO - 'XPC_SERVICE_NAME': '0', 15:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:23 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 15:23:23 INFO - Cleaning up... 15:23:23 INFO - Return code: 0 15:23:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:23:23 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')]} 15:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:23 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')]} 15:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:23 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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:23 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 15:23:23 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 15:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:23 INFO - 'HOME': '/Users/cltbld', 15:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:23 INFO - 'LOGNAME': 'cltbld', 15:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:23 INFO - 'MOZ_NO_REMOTE': '1', 15:23:23 INFO - 'NO_EM_RESTART': '1', 15:23:23 INFO - 'PAGER': '/bin/cat', 15:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:23 INFO - 'PWD': '/builds/slave/test', 15:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:23 INFO - 'SHELL': '/bin/bash', 15:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:23 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:23 INFO - 'USER': 'cltbld', 15:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:23 INFO - 'XPC_FLAGS': '0x0', 15:23:23 INFO - 'XPC_SERVICE_NAME': '0', 15:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:23 INFO - Downloading/unpacking psutil>=3.1.1 15:23:23 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 15:23:23 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 15:23:23 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 15:23:23 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 15:23:23 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 15:23:23 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 15:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:23:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:23:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:23:27 INFO - Installing collected packages: psutil 15:23:27 INFO - Running setup.py install for psutil 15:23:28 INFO - building 'psutil._psutil_osx' extension 15:23:28 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 15:23:28 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 15:23:28 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 15:23:28 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 15:23:28 INFO - building 'psutil._psutil_posix' extension 15:23:28 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 15:23:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:23:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:23:28 INFO - ^ 15:23:28 INFO - 1 warning generated. 15:23:28 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 15:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:23:29 INFO - Successfully installed psutil 15:23:29 INFO - Cleaning up... 15:23:29 INFO - Return code: 0 15:23:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15: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')]} 15:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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')]} 15:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15: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 mozsystemmonitor==0.3 15:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:29 INFO - 'HOME': '/Users/cltbld', 15:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:29 INFO - 'LOGNAME': 'cltbld', 15:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:29 INFO - 'MOZ_NO_REMOTE': '1', 15:23:29 INFO - 'NO_EM_RESTART': '1', 15:23:29 INFO - 'PAGER': '/bin/cat', 15:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:29 INFO - 'PWD': '/builds/slave/test', 15:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:29 INFO - 'SHELL': '/bin/bash', 15:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:29 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:29 INFO - 'USER': 'cltbld', 15:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:29 INFO - 'XPC_FLAGS': '0x0', 15:23:29 INFO - 'XPC_SERVICE_NAME': '0', 15:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:23:29 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 15:23:29 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 15:23:29 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 15:23:29 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 15:23:29 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 15:23:29 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 15:23:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:23:31 INFO - Installing collected packages: mozsystemmonitor 15:23:31 INFO - Running setup.py install for mozsystemmonitor 15:23:32 INFO - Successfully installed mozsystemmonitor 15:23:32 INFO - Cleaning up... 15:23:32 INFO - Return code: 0 15:23:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:32 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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:32 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 15:23:32 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 15:23:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:32 INFO - 'HOME': '/Users/cltbld', 15:23:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:32 INFO - 'LOGNAME': 'cltbld', 15:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:32 INFO - 'MOZ_NO_REMOTE': '1', 15:23:32 INFO - 'NO_EM_RESTART': '1', 15:23:32 INFO - 'PAGER': '/bin/cat', 15:23:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:32 INFO - 'PWD': '/builds/slave/test', 15:23:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:32 INFO - 'SHELL': '/bin/bash', 15:23:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:32 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:32 INFO - 'USER': 'cltbld', 15:23:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:32 INFO - 'XPC_FLAGS': '0x0', 15:23:32 INFO - 'XPC_SERVICE_NAME': '0', 15:23:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:32 INFO - Downloading/unpacking jsonschema==2.5.1 15:23:32 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 15:23:32 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 15:23:32 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 15:23:32 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 15:23:32 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 15:23:32 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 15:23:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:23:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:23:34 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 15:23:34 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 15:23:34 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 15:23:34 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 15:23:34 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 15:23:34 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 15:23:35 INFO - Downloading functools32-3.2.3-2.tar.gz 15:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:23:35 INFO - warning: no files found matching '*.txt' 15:23:35 INFO - no previously-included directories found matching 'build' 15:23:35 INFO - no previously-included directories found matching 'dist' 15:23:35 INFO - no previously-included directories found matching '.git*' 15:23:35 INFO - Installing collected packages: jsonschema, functools32 15:23:35 INFO - Running setup.py install for functools32 15:23:35 INFO - warning: no files found matching '*.txt' 15:23:35 INFO - no previously-included directories found matching 'build' 15:23:35 INFO - no previously-included directories found matching 'dist' 15:23:35 INFO - no previously-included directories found matching '.git*' 15:23:35 INFO - Successfully installed jsonschema functools32 15:23:35 INFO - Cleaning up... 15:23:35 INFO - Return code: 0 15:23:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:35 INFO - 'HOME': '/Users/cltbld', 15:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:35 INFO - 'LOGNAME': 'cltbld', 15:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:35 INFO - 'MOZ_NO_REMOTE': '1', 15:23:35 INFO - 'NO_EM_RESTART': '1', 15:23:35 INFO - 'PAGER': '/bin/cat', 15:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:35 INFO - 'PWD': '/builds/slave/test', 15:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:35 INFO - 'SHELL': '/bin/bash', 15:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:35 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:35 INFO - 'USER': 'cltbld', 15:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:35 INFO - 'XPC_FLAGS': '0x0', 15:23:35 INFO - 'XPC_SERVICE_NAME': '0', 15:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:35 INFO - Downloading/unpacking blobuploader==1.2.4 15: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 15: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 15: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 15: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 15: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 15: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 15:23:38 INFO - Downloading blobuploader-1.2.4.tar.gz 15:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:23:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15: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 15: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 15: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 15: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 15: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 15: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 15:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:23:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15: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 15: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 15: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 15: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 15: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 15: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 15:23:39 INFO - Downloading docopt-0.6.1.tar.gz 15:23:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:23:39 INFO - Installing collected packages: blobuploader, requests, docopt 15:23:39 INFO - Running setup.py install for blobuploader 15:23:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:23:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:23:39 INFO - Running setup.py install for requests 15:23:40 INFO - Running setup.py install for docopt 15:23:40 INFO - Successfully installed blobuploader requests docopt 15:23:40 INFO - Cleaning up... 15:23:40 INFO - Return code: 0 15:23:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:23:40 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')]} 15:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:40 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')]} 15:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:40 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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:40 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 15:23:40 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 15:23:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:40 INFO - 'HOME': '/Users/cltbld', 15:23:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:40 INFO - 'LOGNAME': 'cltbld', 15:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:40 INFO - 'MOZ_NO_REMOTE': '1', 15:23:40 INFO - 'NO_EM_RESTART': '1', 15:23:40 INFO - 'PAGER': '/bin/cat', 15:23:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:40 INFO - 'PWD': '/builds/slave/test', 15:23:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:40 INFO - 'SHELL': '/bin/bash', 15:23:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:40 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:40 INFO - 'USER': 'cltbld', 15:23:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:40 INFO - 'XPC_FLAGS': '0x0', 15:23:40 INFO - 'XPC_SERVICE_NAME': '0', 15:23:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:23:40 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-5YHaEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:23:40 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-TwjZ4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:23:40 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-GCrIeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-xLoI93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-A2QXMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_PcVuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-3wd0BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ANTwf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-9yZvy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-mRecDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-pOmIG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-v71ZPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:23:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-J8lAvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:23:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-0ZceGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Xpgakw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:23:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-yfHvId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:23:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-mXDLF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:23:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:23:42 INFO - Running setup.py install for manifestparser 15:23:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:23:42 INFO - Running setup.py install for mozcrash 15:23:42 INFO - Running setup.py install for mozdebug 15:23:42 INFO - Running setup.py install for mozdevice 15:23:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Running setup.py install for mozfile 15:23:43 INFO - Running setup.py install for mozhttpd 15:23:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Running setup.py install for mozinfo 15:23:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Running setup.py install for mozInstall 15:23:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Running setup.py install for mozleak 15:23:43 INFO - Running setup.py install for mozlog 15:23:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:23:43 INFO - Running setup.py install for moznetwork 15:23:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:23:44 INFO - Running setup.py install for mozprocess 15:23:44 INFO - Running setup.py install for mozprofile 15:23:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:23:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:23:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:23:44 INFO - Running setup.py install for mozrunner 15:23:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:23:44 INFO - Running setup.py install for mozscreenshot 15:23:44 INFO - Running setup.py install for moztest 15:23:44 INFO - Running setup.py install for mozversion 15:23:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:23:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:23:45 INFO - Cleaning up... 15:23:45 INFO - Return code: 0 15:23:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:23:45 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')]} 15:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:45 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')]} 15:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:45 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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:45 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 15:23:45 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 15:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:45 INFO - 'HOME': '/Users/cltbld', 15:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:45 INFO - 'LOGNAME': 'cltbld', 15:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:45 INFO - 'MOZ_NO_REMOTE': '1', 15:23:45 INFO - 'NO_EM_RESTART': '1', 15:23:45 INFO - 'PAGER': '/bin/cat', 15:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:45 INFO - 'PWD': '/builds/slave/test', 15:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:45 INFO - 'SHELL': '/bin/bash', 15:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:45 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:45 INFO - 'USER': 'cltbld', 15:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:45 INFO - 'XPC_FLAGS': '0x0', 15:23:45 INFO - 'XPC_SERVICE_NAME': '0', 15:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:23:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-mU5hor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:23:45 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)) 15:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:23:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-era0LB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:23:45 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)) 15:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:23:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Bo_srJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:23:45 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)) 15:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:23:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Qcnv39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:23:45 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)) 15:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:23:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-dbf4WV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:23:45 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)) 15:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:23:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-INZc27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:23:45 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)) 15:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:23:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-pLSqEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:23:46 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)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Yn6ykN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:23:46 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)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-zPjI96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:23:46 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)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-9WrImj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:23:46 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)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-pFiz3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:23:46 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)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ltDHMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-y8VPsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:23:46 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)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-qtjd7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-9bt3QK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:46 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)) 15:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:23:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZQJ3Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:23:47 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)) 15:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:23:47 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-1AoxKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:23:47 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)) 15:23:47 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)) 15:23:47 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)) 15:23:47 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)) 15:23:47 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)) 15:23:47 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)) 15:23:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:49 INFO - Downloading blessings-1.6.tar.gz 15:23:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:23:49 INFO - Installing collected packages: blessings 15:23:49 INFO - Running setup.py install for blessings 15:23:49 INFO - Successfully installed blessings 15:23:49 INFO - Cleaning up... 15:23:50 INFO - Return code: 0 15:23:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:23:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:23:50 INFO - Reading from file tmpfile_stdout 15:23:50 INFO - Current package versions: 15:23:50 INFO - blessings == 1.6 15:23:50 INFO - blobuploader == 1.2.4 15:23:50 INFO - docopt == 0.6.1 15:23:50 INFO - functools32 == 3.2.3-2 15:23:50 INFO - jsonschema == 2.5.1 15:23:50 INFO - manifestparser == 1.1 15:23:50 INFO - mozInstall == 1.12 15:23:50 INFO - mozcrash == 0.17 15:23:50 INFO - mozdebug == 0.1 15:23:50 INFO - mozdevice == 0.48 15:23:50 INFO - mozfile == 1.2 15:23:50 INFO - mozhttpd == 0.7 15:23:50 INFO - mozinfo == 0.9 15:23:50 INFO - mozleak == 0.1 15:23:50 INFO - mozlog == 3.2 15:23:50 INFO - moznetwork == 0.27 15:23:50 INFO - mozprocess == 0.23 15:23:50 INFO - mozprofile == 0.28 15:23:50 INFO - mozrunner == 6.12 15:23:50 INFO - mozscreenshot == 0.1 15:23:50 INFO - mozsystemmonitor == 0.3 15:23:50 INFO - moztest == 0.7 15:23:50 INFO - mozversion == 1.4 15:23:50 INFO - psutil == 3.1.1 15:23:50 INFO - requests == 1.2.3 15:23:50 INFO - wsgiref == 0.1.2 15:23:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15: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')]} 15:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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')]} 15:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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/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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:50 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 15:23:50 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 15:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:50 INFO - 'HOME': '/Users/cltbld', 15:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:50 INFO - 'LOGNAME': 'cltbld', 15:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:50 INFO - 'MOZ_NO_REMOTE': '1', 15:23:50 INFO - 'NO_EM_RESTART': '1', 15:23:50 INFO - 'PAGER': '/bin/cat', 15:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:50 INFO - 'PWD': '/builds/slave/test', 15:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:50 INFO - 'SHELL': '/bin/bash', 15:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:50 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:50 INFO - 'USER': 'cltbld', 15:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:50 INFO - 'XPC_FLAGS': '0x0', 15:23:50 INFO - 'XPC_SERVICE_NAME': '0', 15:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 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)) 15:23:50 INFO - Cleaning up... 15:23:50 INFO - Return code: 0 15:23:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15: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')]} 15:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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')]} 15:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:50 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 0x105b68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bfd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f808a577350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, '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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 15:23:50 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 15:23:50 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 15:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:50 INFO - 'HOME': '/Users/cltbld', 15:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:50 INFO - 'LOGNAME': 'cltbld', 15:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:50 INFO - 'MOZ_NO_REMOTE': '1', 15:23:50 INFO - 'NO_EM_RESTART': '1', 15:23:50 INFO - 'PAGER': '/bin/cat', 15:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:50 INFO - 'PWD': '/builds/slave/test', 15:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:50 INFO - 'SHELL': '/bin/bash', 15:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:23:50 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:50 INFO - 'USER': 'cltbld', 15:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:50 INFO - 'XPC_FLAGS': '0x0', 15:23:50 INFO - 'XPC_SERVICE_NAME': '0', 15:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:23:50 INFO - Cleaning up... 15:23:50 INFO - Return code: 0 15:23:50 INFO - Running post-action listener: _resource_record_post_action 15:23:50 INFO - Running post-action listener: _start_resource_monitoring 15:23:50 INFO - Starting resource monitoring. 15:23:50 INFO - ##### 15:23:50 INFO - ##### Finished create-virtualenv step (success) 15:23:50 INFO - ##### 15:23:50 INFO - ##### 15:23:50 INFO - ##### Running install step. 15:23:50 INFO - ##### 15:23:50 INFO - Running pre-action listener: _resource_record_pre_action 15:23:50 INFO - Running main action method: install 15:23:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:23:51 INFO - Reading from file tmpfile_stdout 15:23:51 INFO - Detecting whether we're running mozinstall >=1.0... 15:23:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:23:51 INFO - Reading from file tmpfile_stdout 15:23:51 INFO - Output received: 15:23:51 INFO - Usage: mozinstall [options] installer 15:23:51 INFO - Options: 15:23:51 INFO - -h, --help show this help message and exit 15:23:51 INFO - -d DEST, --destination=DEST 15:23:51 INFO - Directory to install application into. [default: 15:23:51 INFO - "/builds/slave/test"] 15:23:51 INFO - --app=APP Application being installed. [default: firefox] 15:23:51 INFO - mkdir: /builds/slave/test/build/application 15:23:51 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'] 15:23:51 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 15:24:25 INFO - Reading from file tmpfile_stdout 15:24:25 INFO - Output received: 15:24:25 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 15:24:25 INFO - Running post-action listener: _resource_record_post_action 15:24:25 INFO - ##### 15:24:25 INFO - ##### Finished install step (success) 15:24:25 INFO - ##### 15:24:25 INFO - ##### 15:24:25 INFO - ##### Running run-tests step. 15:24:25 INFO - ##### 15:24:25 INFO - Running pre-action listener: _resource_record_pre_action 15:24:25 INFO - Running main action method: run_tests 15:24:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:24:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:24:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:24:25 INFO - 2016-08-06 15:24:25.759 screenresolution[1641:9263] starting screenresolution argv=screenresolution get 15:24:25 INFO - 2016-08-06 15:24:25.761 screenresolution[1641:9263] Display 0: 1600x1200x32@60 15:24:25 INFO - 2016-08-06 15:24:25.767 screenresolution[1642:9265] starting screenresolution argv=screenresolution list 15:24:25 INFO - Available Modes on Display 0 15:24:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:24:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:24:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:24:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:24:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:24:25 INFO - Intel Iris: 15:24:25 INFO - Chipset Model: Intel Iris 15:24:25 INFO - Type: GPU 15:24:25 INFO - Bus: Built-In 15:24:25 INFO - VRAM (Dynamic, Max): 1536 MB 15:24:25 INFO - Vendor: Intel (0x8086) 15:24:25 INFO - Device ID: 0x0a2e 15:24:25 INFO - Revision ID: 0x0009 15:24:25 INFO - Displays: 15:24:25 INFO - Display: 15:24:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:24:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:24:25 INFO - Display Serial Number: 1600x1200 60 15:24:25 INFO - Main Display: Yes 15:24:25 INFO - Mirror: Off 15:24:25 INFO - Online: Yes 15:24:25 INFO - Rotation: Supported 15:24:26 INFO - Return code: 0 15:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:24:26 INFO - Python 2.7.3 15:24:26 INFO - Return code: 0 15:24:26 INFO - grabbing minidump binary from tooltool 15:24:26 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')]} 15:24:26 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 0x105accd30>, '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 0x105831db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ac4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:24:26 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 15:24:26 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 15:24:26 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 15:24:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:24:26 INFO - Return code: 0 15:24:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:24:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:24:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:24:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:24:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 15:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --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 15:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZgmBVBvwXe/Render', 15:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:26 INFO - 'HOME': '/Users/cltbld', 15:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:26 INFO - 'LOGNAME': 'cltbld', 15:24:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:24:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:26 INFO - 'MOZ_NO_REMOTE': '1', 15:24:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:24:26 INFO - 'NO_EM_RESTART': '1', 15:24:26 INFO - 'PAGER': '/bin/cat', 15:24:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:26 INFO - 'PWD': '/builds/slave/test', 15:24:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:26 INFO - 'SHELL': '/bin/bash', 15:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xbYKKhIsGH/Listeners', 15:24:26 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 15:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:26 INFO - 'USER': 'cltbld', 15:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:26 INFO - 'XPC_FLAGS': '0x0', 15:24:26 INFO - 'XPC_SERVICE_NAME': '0', 15:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 15:24:26 INFO - mozversion application_buildid: 20160712030202 15:24:26 INFO - mozversion application_changeset: aac8ff1024c553d9c92b85b8b6ba90f65de2ed08 15:24:26 INFO - mozversion application_display_name: Nightly 15:24:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:24:26 INFO - mozversion application_name: Firefox 15:24:26 INFO - mozversion application_remotingname: firefox 15:24:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:24:26 INFO - mozversion application_vendor: Mozilla 15:24:26 INFO - mozversion application_version: 50.0a1 15:24:26 INFO - mozversion platform_buildid: 20160712030202 15:24:26 INFO - mozversion platform_changeset: aac8ff1024c553d9c92b85b8b6ba90f65de2ed08 15:24:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:24:26 INFO - mozversion platform_version: 50.0a1 15:24:26 INFO - using testdate: 1470522266 15:24:26 INFO - actual date: 1470522266 15:24:26 INFO - starting webserver on 'localhost:49271' 15:24:26 INFO - SUITE-START | Running 2 tests 15:24:26 INFO - TEST-START | dromaeo_css 15:24:26 INFO - operating with platform_type : mac_ 15:24:26 INFO - Initialising browser for dromaeo_css test... 15:24:26 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpz3LLJB/profile http://localhost:49271/getInfo.html) 15:24:28 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 15:24:28 INFO - PROCESS | 1652 | colorDepth:24 15:24:28 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 15:24:28 INFO - PROCESS | 1652 | __metrics 15:24:28 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:24:28 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:24:29 INFO - TEST-INFO | 1652: exit 0 15:24:29 INFO - Browser initialized. 15:24:29 INFO - Running cycle 1/1 for dromaeo_css test... 15:24:29 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpz3LLJB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:24:41 INFO - PROCESS | 1656 | RSS: Main: 243376128 15:24:41 INFO - PROCESS | 1656 | 15:25:33 INFO - PROCESS | 1656 | 1470522333000 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] 15:27:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 15:27:45 INFO - PROCESS | 1656 | RSS: Main: 355467264 15:27:45 INFO - PROCESS | 1656 | 15:30:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 15:30:49 INFO - PROCESS | 1656 | RSS: Main: 379604992 15:30:49 INFO - PROCESS | 1656 | 15:33:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 15:33:52 INFO - PROCESS | 1656 | RSS: Main: 370106368 15:33:52 INFO - PROCESS | 1656 | 15:36:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 15:36:55 INFO - PROCESS | 1656 | RSS: Main: 370098176 15:36:55 INFO - PROCESS | 1656 | 15:39:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 15:39:59 INFO - PROCESS | 1656 | RSS: Main: 345247744 15:39:59 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 15:43:02 INFO - PROCESS | 1656 | RSS: Main: 381050880 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | __start_tp_report 15:43:02 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 15:43:02 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 15:43:02 INFO - PROCESS | 1656 | |i|pagename|runs| 15:43:02 INFO - PROCESS | 1656 | |0;dojo.html;3360.613529444114;3379.715708030948;3380.290139070795;3399.2861499085193;3448.293099914518;5648.576356773238;5654.971725141374;5719.084946408576;5735.397783232758;5784.2480477537865;4759.262314341274;5699.914501282474;5783.681897495636;5797.246357973468;5835.883282334361;5156.716380599066;5161.225816127592;5231.006108839328;5234.555062819646;5351.143816989283;5334.413214546396;5341.545968592674;5365.624406291561;5371.167469042305;5413.864653383657;4300.9139817203795;5159.1229490986625;5218.478152184789;5236.2931004314505;5305.708186049785;5185.714785686786;5245.213218017289;5260.579153667697;5273.31446911899;5282.656627319212;5172.66377685453;5175.171972484384;5215.16557350822;5227.085260079471;5240.947590524084;5764.7117644117625;5779.537636989031;5793.102069179284;5795.652260864362;5848.298204215479;5275;6111.938880611181;6177.104319873638;6267.185265915402;6290.3709629037185;6297.937020629781;6300.621962682208;6332.955062414696;6354.142190804228;6372.076048972873;5847.356790753014;5859.531237501075;5898.616589921641;5900.409959004108;5936.1095835625;6213.130161777355;6251.374862513803;6316.526260530479;6320.336364681734;6352.4918006559365;6188.721507532172;6196.907046394308;6203.472704820141;6212.285587157445;6221.3156552779155;6321.589096708699;6339.0808332791485;6339.302676705559;6349.079383489368;6351.2060992375955;6103.115048317969;6113.228576830862;6151.815445536641;6224.37756224374;6275.748970041239;6287.654179020198;6316.936830631635;6320.241571011508;6324.6521441321165;6327.557071005078;4943.01139772047;4950.579200767975;4953.90092198154;4978.178600530944;4986.601071914238;4395.274779661384;4403.8458653946955;4404.66964977628;4457.8439754608435;4477.552244775497;5800.5939584229545;5808;5813.476787089182;5821.243238378984;5872.442117998783;6299.0484416184045;6314.557980941382;6329.525285603599;6360.236771587439;6373.74505019794;5658.236138121383;5706.971465142731;5739.311282646109;5781.53747700183;5808.506276966421;5403.702796346239;5410.1343784994215;5416.729163541807;5429.457054294618;5433;6293.496520278367;6341.746330146835;6345.809625711236;6360.332165122781;6370.171877655967;5109.897802044013;6189.690515474298;6228.937710622836;6291.559590828781;6293.496520278458;6314.273858506147;6326;6343.809685709436;6345.397187267202;6371.681415929278;6227.314995350417;6228.03465462854;6241.812745617639;6255.437010669061;6257.43683068526;6100.914243738686;6170;6186.319504854372;6222.284437289589;6294.276158241861;6076.392360763888;6092.238470191226;6098.176746139215;6113.633182009094;6117.635300584571;5990.31111422192;6119.07606531266;6121.204243448323;6123.496151642784;6123.8162855114415;5808.82573522795;5879.147523608967;5907.763689452546;5945.078512830518;5988.5508586855285;6138.785142519991;6141.293751218667;6145.477634401126;6148.877022459436;6166.229221347332;5783.710814459176;5966.164736936919;5974.402559744165;5987.13186587951;6000.459958603747 15:43:02 INFO - PROCESS | 1656 | |1;ext.html;493.37587951241665;500.9749512524373;523.0846019465934;547.0426753181931;559.5188138201146;26791.464170716597;43119;43911.34132988008;44062.77968610157;44232.557674423246;43009;43191.920201994966;43299.56700432995;43374.349384759254;43511.78244108783;7005.684744186498;9560.378575392577;9718.854217186748;9724.416535007887;9733.902660973406;8612.956935215316;11675.24110932791;11835;11838.881611183864;12075.516979320817;11898.821517677241;11952.282863028246;11954.402279885971;11968.461419236111;11981.460834262436;8544.487330760112;8859.29125669945;11767.941160294187;11848.940755296167;11852.644420667393;8801.67974803778;8878.511681857495;11379.829302560469;11633.302001879829;11647.184697071209;39045;40771.1845763086;41039.384409233884;41125;41136.177276454306;3563.3051554946796;3581.8925432237074;3624.5650521937537;3641.635836416364;3665.6151104134215;30934.53598196029;31635.20911977196;31814.363712725848;31978.360432791447;32242;2945.955810662842;3239.1416274687226;3278.704916557497;3280.114369120348;3396.745244106702;527322;527964.7203527993;529644;530258.3487082579;530679.9999999962;59242.40757592369;59715;59790.10314845281;59794.10308845371;59892;62440.75118497605;62521;62651.686741566;62706.68646656738;62751;16473;16667.83332166687;16675.332986680638;16683.332666693197;16748.330066797436;11820.40897955116;12070;12097.274163550215;12102.455389507493;12111.87888121125;15455.690886182214;15804.656604188543;15812.288447019911;16254.18729063542;16294.755578666329;6077.513798896078;6418.967905160538;6466.288708242089;6480.870382592416;6487;884.2660591708866;924.58393722825;949.7435692363161;1091.8853520380405;1141.3095077478006;30467.390652186834;30497;30561.541576876363;31460.528092078635;31538.684613154033;1040.8802987656516;1087.1574529739514;1115.59838458155;1162.91859569831;1228.3182833527478;15567.143807090491;15899.920500397655;16003.27985240667;16386.09876456806;16394.590135246715;689654;698530;698963;699932.0040797467;700423;48479.75760121247;59137.70431147817;59145;59307.40692593105;59466.70266648639;1257.3084803358117;1297.9935100324249;1374.5738820965532;1413.0956188039663;1430.1133297355707;30803;31028.0691579253;31350.843245783624;31719;32250.516242255915;49968;50078.499215008844;50422.24366634577;50563.2415513775;50637.24044139267;576702;578965;579948;580783;580787;27418.5887211696;34841;34857.302853943285;38016.61983380241;40484.1903161941;40030.39954400744;46557.30164047474;48256;48438;49643.25535117048;39414.83451067495;39426.21147576975;40166.598334016286;40404.16047548291;41847.37228941507;39773.801130995314;40297.597024030554;40680.59319406768;41129.79435102805;41235.38146927858;38435.42346864743;38874.64210133201;39104.2179156421;39277;39284.80357598194;34323.97028089161;34360.656393436744;35031.82484087665;35188.82405587956;35313.64686353104;23155.768442315362;25350;25361.49277014486;25379.85790639389;25852.224602660855 15:43:02 INFO - PROCESS | 1656 | |2;jquery.html;344.22892720306487;385.6259428354496;394.54036048004076;395.21549723798256;418.2074967935762;15385.538433846974;15500.844991550095;20550;20973.895130524365;21004.8949755251;20662.140407363335;21032;21491.355259342246;21992.230271940487;22155.667664984998;5271.920921186185;5902.881942361139;6154.938450615481;6155.26136863577;6167.259928808527;4222.619964203221;4462.352958820969;6060.272767267912;6417.93582064178;6777.390034896858;5941.643501389931;6026.975414179578;6391.712372943205;6731.764388246413;6797.422219111357;5757.913631295534;6572.605643661372;6734.19189697237;6757.290484499155;6782.72869085236;3718.3678774608384;5510.724463776835;5817.301923769175;5827.329857066449;5840.678762668052;26384.73615263842;26780.330491737666;27120.457590848073;27856.860715696494;27895.302617434525;2061.093119027623;2197.1870407949;2265.3996690876766;2303.5392921415782;2393.365758074112;8199.467034642728;10529.368237905674;10569.471526423647;10808.189385796097;10818.513166907507;1559.9532014039596;1954.697021961584;1956.3152896486258;1993.1230258686132;1999.6500612392917;129560;130549.34725326313;132102;132419;133778;11644.126690498246;11791.646250612495;11798.351090689923;11816.231944923553;11981.663575602848;14898.404063837324;14976.775348369783;14981.550553483414;14991;14993.925030374778;8797.780055498664;8831.072737362612;8831.3376496763;8858.911410885808;8946.731598052069;8829.69096081634;8841.778955526035;8853.64585416589;8962;8980.8652870207;3985.903876434004;4593.47175074864;4629.421322334708;4657.510961349078;4675.789589468414;1742.538227369748;1777.7863474878243;1816.3551939061706;1894.0245773426695;1928.9035548222532;898.4070513461215;934.7287688743304;997.1474389396961;1006.1548299428515;1042.103790739963;20933.581328373348;22009.812974489247;22227.444313892283;22229.11083556672;22241.332760017227;924.630147940829;932.911373419524;934.0052843721415;952.9094736000069;967.583938906263;11164.497597608128;11598.362090084958;11605.535778568932;11607.187496875307;11616.419179041015;594453;600064;600196;615274;617113;135515;136023;139476.3026184883;141337;144232.27883860722;982.0964712464549;985.5466485416645;1021.2442792333768;1054.9683509494728;1065.2649671726483;19122.80877191266;19392.515187120436;19418.90290548538;19452.610947780682;19507.317243896963;52679.73660131675;52885;53155.468445315055;54839.72580137074;55067.72466137644;79547;80454;81795.37412789946;82157.58921205356;82905;8005.719799806979;8107.4324797263625;8370.204830541088;8578.65685372578;8662.480251184948;11224.831627525431;12814;12930.254415653766;13042.478300867859;13076.084674072723;11555.768884622428;11607.071434285068;11630.53477860876;11654.359010254178;11786.882131178578;11150.609728659458;11195.72010699739;11250.887491125311;11272.062934270505;11480.024197943269;10404;10919.39943303126;10995.285306455055;11653.6503904883;11716.06271498295;7245.529040612343;8255.174482551696;8306.83386332258;8396.538190399586;8550.78623034429;9777.706668799947;11441.771164576496;11513.654590362303;11818.940905295418;11916.642500724993 15:43:02 INFO - PROCESS | 1656 | |3;mootools.html;1568.3569736408072;1586.2227508520825;1829.3962992212562;1947.396307144786;1969.7636283645957;8537.14628537146;8866.512341821197;9038.502882341476;9064.63741450342;9091;7845.411594130434;8109.472884262534;8627.352948528855;8821.382503224777;8828.51443170627;2344.4021774447533;2374.8812559371963;2377.132346693453;2379.7144342678903;2383.3803211165136;2110.757262914769;2361.2680069178523;2365.0185173153122;2402.039184326266;2414.2757172848164;2159.492519257973;2293.7018187635585;2354.2937118864356;2365.7989070928993;2367.3489790307717;2347.436615212354;2358.905643774247;2359.728631207416;2362.2204672458047;2364.9408764780846;2356.5404746074523;2357.5167090746354;2361.6184532048774;2368.301351101412;2370.5495955768347;8967.506787126706;9052.728418147466;9057.184853363164;9062.637494500212;9066.138716821892;5248.81629142982;5329.600279979017;5400.972995135038;5402.486763757437;5417.864553386157;3439.3809114359406;3506.6493350664987;3622.9275414491563;3626.3110009098186;3643.3988391915304;941.5621735892843;1080.200651517879;1101.1686176936362;1107.5403707461394;1125.538529203031;11693.298402095901;11704.941475292655;11705.824412633818;11709.590164344207;11728.061755059489;3371.7976921384798;4660.883477913011;4809.278608208797;4810.687305325143;4824.396950381202;9907.45508997012;9913.752156196153;9920.751981200383;9964.950175249078;10014.699559013241;5379.381371142291;5380.488853558905;5385.219143224211;5405.108157154104;5426.538744206708;4782.115308667908;4965.255211718271;4967.528084831935;4970.453250142481;4984.850454486371;3809.5999920008;3821.923561528754;3849.749766265184;3905.2970465316516;3917.4515567820526;1089.8801131875487;1177.2289150606366;1178.952841886315;1179.280638810325;1187.940602969848;678.8811957907346;785.2775446589152;917.1699611851285;933.4306073295285;960.1406740966935;6068.939310606926;6114.6255502216445;6175.382461753788;6203.689815509207;6226.034964580497;941.2564074381162;955.5365647660878;962.3648392061206;1001.8797744270588;1036.264252380803;3732.4774531565604;3945.132070944445;4138.882501724517;4171.374293855946;4180.435641188462;10602.469876506144;11052.392118433403;11070;11078.279911805706;11085.612003579998;9966.850497242547;10404.375737455777;10808.297460665031;10834.295770774874;10934.289271197347;7014.579125252501;7628.46600737943;7797.376209903194;7804.882926756103;7994.120646728855;5388.784448622011;6032.095185722177;6099.542534309856;6227.252729672287;6230.968845155745;9821.803563928825;10057.446840423554;10195.286329956833;10285.74285642835;10294.227932905213;10065.547050382604;10260.230482713678;10459.633912813015;10468.476576171313;10539.841902371623;7023.578585284899;7052.576845389293;7121;7130.893036604351;7245;8310.12743661931;8506.404551681322;8532.957335213285;8551.401401901807;8567.314614830673;6091.543134264859;7460.440466964891;7470.439717021354;7523;7694.461387702807;7403.519296140901;7493.063367079115;7494.625268736432;7627.084749830056;7637.923620763721;7105.111861017373;7164.641767911688;7208.56748595086;7217.386522145676;7221.783346499614;4866.951330486791;4995.475475075064;5069.493050694901;5071.4421413645205;5097.28637990689;5024.974875125601;5058.620603454682;5093.18509038552;5160.664556803796;5221.216817477409 15:43:02 INFO - PROCESS | 1656 | |4;prototype.html;277.88884446221516;314.52192667145937;317.6172711882181;319.9152224660464;323.2323232323232;4565.132624801286;5000.549950504458;5338.27933229014;5361.088614935461;5448.509634132927;5292.205035486146;5340.225667278242;5381.623286369946;5383.273258110164;5405;3243.4323993301196;3916.9804150979207;4049.7445791804635;4072.226277007374;4118.38224266361;3734.5518537775506;4086.8569600064;4105.4252404663375;4134.235166494208;4136.627703506684;3669.5596528416627;4006.7395619284653;4070.715049946505;4086.7752273625088;4117.258893399187;3530.958367281658;3716.2567486502808;3850.84596616135;4037.899052523681;4105.527864295615;3217.420864244435;3324.2852786650747;3550.0947258449187;3554.591222009476;3557.8576856925692;4399.934000989988;4531.297648864432;4539.001419687696;4962;5009.273655319958;4066.38092098033;4486.259767138418;4705.1530724469585;4737.2420412734145;4799.920017995958;5646.632968857052;5727.885442291173;5761.481466667978;5778.595498315078;5778.913316300259;3762.3980163174033;4078.163976384834;4087.691938579656;4089.8568550100604;4111.8972025699295;4799.904001919942;5035.77339019745;5755.913661295084;5839.686070634159;6104.642446654322;3619.529461170084;3883.86406475772;3910.687145028391;3923.3330333842746;3967.8412863485137;3986.946722437947;3997.920041599152;4021.617946295156;4046.251443482965;4052.452918855976;3718.8140592970244;4566.589006989387;4658.231391820312;4710.764461776897;4745.905081898342;4805.927911081337;5656.264685590847;5782.2483077199695;5782.681952492653;5808.244928159313;1188.3998580716855;1400.6708423520465;1402.712443948988;1420.7939848721878;1424.508544552128;845.3364109174269;885.5085427587751;893.2005854759954;900.5317235037744;901.6438506789781;4914.385701787277;5118.513741194581;5168.9224661630105;5315;5320.547753441001;4916.188828843272;5785.623934444247;5805.94194058054;5813.767449302065;5885.5585831062845;4932.284818701268;5037.496250375007;5148.253503242009;5177.352830896138;5336.332958380202;2672.6124711916664;3039.8176109433507;3050.2984313607994;3063.234191452137;3076;5988.221531200916;5998.730057147439;6045.486133678599;6062.090686397076;6067.605605635619;5310.150141147637;5623.409541998113;5757.769689212469;5868.295804503487;5896.587238893237;4769.523047695272;4848.224284114524;5381.892362152813;5961.940380596138;5971.731272092767;5557.527610153183;5748.971255143698;5774.711264436677;5840.123981402823;5847.649141051551;5241.645346977861;5662.745176467151;5717.628354156966;5743.540516758733;5784.768609255751;5227.340861397113;5585.5531557476115;5686.374498805211;5992.580519363777;5994.820155395345;3651.634836516327;3674.724395670282;3983.6813054954955;3985.202959408188;4007.6192761687594;5419.945800541944;5554.2779438672715;5808.340745330414;5836.336644056423;5866.354700982974;4331.155424692281;5297.8145764898045;5502.147167189092;5801.767929282782;5831.125331200354;4987.476314986874;5254.264402983509;5307.9734601326745;5746.626469279484;5778.682172480554;4421.734695918255;5641.238432811518;5651.88696226065;5655.85860353478;5658.85852853682;5584.441555844383;5694.63328801093;5700;5701.377366411497;5757.5393968481585;4936.851894443173;5552.5557955362665;5604.719764011865;5634.591072401138;5657.971710141423 15:43:02 INFO - PROCESS | 1656 | |5;yui.html;683.1938312790907;758.6093162021557;760.4714723267329;804.8269622031265;851.2041241439251;15503.379864805409;16299;16324.428644997428;16329.591760206;16347.34610615574;14814.259287035658;14950.252487375616;15018.324175412105;15030.549083527485;15074.095554266723;725.310954593136;746.4327111395352;907;912.858506931425;943.8725772020758;914.021996463784;920.5351297594706;926.7451450851003;927.8654595083709;929.907009299068;942.2132519346354;961.6538046303325;965.1362030982267;966.5408930757869;967.5307475874231;744.8175197076697;930.930180236485;954.4321128928242;956.1967946924582;961.3174646001347;721.8448033672786;730.8063363208701;731.1847290271362;731.356406362403;731.6159016516318;13610.523631672844;15462.768058479132;15478.148701821396;15493.380264789395;15502.61243468911;14107.78838317426;14278.785818212735;14295.142291462545;14320.498782542561;14538.491152809602;12942.402143176087;13106.541271055466;13230.86769132306;13300.268485233308;13617.523386681418;4526.79629416677;4607.769611519444;4614.2617181250835;4639.280911458729;4676.7427791471455;110978.44510777475;112301.43849280784;116969.99999999916;117317;119547;11398.316101033965;11399;11503.712407189887;11574.247673901169;11690.590829321103;22648.207312743976;22673.979670914847;22681.432964176027;22695;22714.091436342693;8078.7172448964;8080.676772929017;8087.231712987257;8218.589070546448;8323.126071762377;4308.202982448257;4402.273624851928;4465.129299786576;4531.796069176895;4555.521670224645;3493.2664140530774;3517.9472307915403;3568.6966607838103;3615.493830863681;3616.150204701893;636.3922454056458;645.3353046362255;648.6367634124811;660.8017594721564;662.986740265192;554.6866020698293;607.4502575169479;627.4885967936117;638.2819328255713;662.8309781005813;8828.205461508494;8950.194482496476;9003.234725048313;9028.683996060106;9153.63385464574;613.5275837605019;622.8726005929283;638.9424951754364;646.5668002438377;648.9643069631215;3668.738584957767;4362.345648152803;4371.519132895379;4388.188185185751;4404;120018;124292;125627;147615;153480;116681.24978125335;121874.3906280463;122607.38696306461;126498;127288;104357.47821260845;105990.47004764927;107089.46455267674;108160;110482;9719.951400242953;9893.25800564946;9907.801843962936;9960.750981225527;9960.950195248977;60480.69759651174;60811;61555.38444615676;61849.69075154595;61863;65393.67303163644;82610.58694706488;92274;93652.53173734088;93655;11051.72370690707;11351.772964540498;11386.146039046938;11754.118441117122;13922.373493193034;13278.468861245441;14697.85302146965;15007.77488337654;15515.612109697347;16083.597910052342;13253.220924991101;13528.39122239482;13672.794908076587;13737.793933090812;13857.653558661113;12356.320402377956;12401;13022.413991370222;13063.477460901457;13924.503904112915;13762.793558096437;13967.580972570839;13993.325584759294;14096.08375455592;14326;9561.713148605553;11482.827757583476;11712.94143529311;11718.70703232426;11811.468483918416;11040.503177356877;11413.201075924606;11459.942700286445;11712.648620541397;11828.408579570814 15:43:02 INFO - PROCESS | 1656 | __end_tp_report 15:43:02 INFO - PROCESS | 1656 | __start_cc_report 15:43:02 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1203 15:43:02 INFO - PROCESS | 1656 | __end_cc_report 15:43:02 INFO - PROCESS | 1656 | __startTimestamp1470523382818__endTimestamp 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | ------- Summary: start ------- 15:43:02 INFO - PROCESS | 1656 | Number of tests: 6 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5762.86 Median:5966.16 stddev:654.17 (11.0%) stddev-sans-first:628.44 15:43:02 INFO - PROCESS | 1656 | Values: 3360.6 3379.7 3380.3 3399.3 3448.3 5648.6 5655.0 5719.1 5735.4 5784.2 4759.3 5699.9 5783.7 5797.2 5835.9 5156.7 5161.2 5231.0 5234.6 5351.1 5334.4 5341.5 5365.6 5371.2 5413.9 4300.9 5159.1 5218.5 5236.3 5305.7 5185.7 5245.2 5260.6 5273.3 5282.7 5172.7 5175.2 5215.2 5227.1 5240.9 5764.7 5779.5 5793.1 5795.7 5848.3 5275.0 6111.9 6177.1 6267.2 6290.4 6297.9 6300.6 6333.0 6354.1 6372.1 5847.4 5859.5 5898.6 5900.4 5936.1 6213.1 6251.4 6316.5 6320.3 6352.5 6188.7 6196.9 6203.5 6212.3 6221.3 6321.6 6339.1 6339.3 6349.1 6351.2 6103.1 6113.2 6151.8 6224.4 6275.7 6287.7 6316.9 6320.2 6324.7 6327.6 4943.0 4950.6 4953.9 4978.2 4986.6 4395.3 4403.8 4404.7 4457.8 4477.6 5800.6 5808.0 5813.5 5821.2 5872.4 6299.0 6314.6 6329.5 6360.2 6373.7 5658.2 5707.0 5739.3 5781.5 5808.5 5403.7 5410.1 5416.7 5429.5 5433.0 6293.5 6341.7 6345.8 6360.3 6370.2 5109.9 6189.7 6228.9 6291.6 6293.5 6314.3 6326.0 6343.8 6345.4 6371.7 6227.3 6228.0 6241.8 6255.4 6257.4 6100.9 6170.0 6186.3 6222.3 6294.3 6076.4 6092.2 6098.2 6113.6 6117.6 5990.3 6119.1 6121.2 6123.5 6123.8 5808.8 5879.1 5907.8 5945.1 5988.6 6138.8 6141.3 6145.5 6148.9 6166.2 5783.7 5966.2 5974.4 5987.1 6000.5 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:73525.70 Median:39190.61 stddev:162107.62 (413.6%) stddev-sans-first:162469.63 15:43:02 INFO - PROCESS | 1656 | Values: 493.4 501.0 523.1 547.0 559.5 26791.5 43119.0 43911.3 44062.8 44232.6 43009.0 43191.9 43299.6 43374.3 43511.8 7005.7 9560.4 9718.9 9724.4 9733.9 8613.0 11675.2 11835.0 11838.9 12075.5 11898.8 11952.3 11954.4 11968.5 11981.5 8544.5 8859.3 11767.9 11848.9 11852.6 8801.7 8878.5 11379.8 11633.3 11647.2 39045.0 40771.2 41039.4 41125.0 41136.2 3563.3 3581.9 3624.6 3641.6 3665.6 30934.5 31635.2 31814.4 31978.4 32242.0 2946.0 3239.1 3278.7 3280.1 3396.7 527322.0 527964.7 529644.0 530258.3 530680.0 59242.4 59715.0 59790.1 59794.1 59892.0 62440.8 62521.0 62651.7 62706.7 62751.0 16473.0 16667.8 16675.3 16683.3 16748.3 11820.4 12070.0 12097.3 12102.5 12111.9 15455.7 15804.7 15812.3 16254.2 16294.8 6077.5 6419.0 6466.3 6480.9 6487.0 884.3 924.6 949.7 1091.9 1141.3 30467.4 30497.0 30561.5 31460.5 31538.7 1040.9 1087.2 1115.6 1162.9 1228.3 15567.1 15899.9 16003.3 16386.1 16394.6 689654.0 698530.0 698963.0 699932.0 700423.0 48479.8 59137.7 59145.0 59307.4 59466.7 1257.3 1298.0 1374.6 1413.1 1430.1 30803.0 31028.1 31350.8 31719.0 32250.5 49968.0 50078.5 50422.2 50563.2 50637.2 576702.0 578965.0 579948.0 580783.0 580787.0 27418.6 34841.0 34857.3 38016.6 40484.2 40030.4 46557.3 48256.0 48438.0 49643.3 39414.8 39426.2 40166.6 40404.2 41847.4 39773.8 40297.6 40680.6 41129.8 41235.4 38435.4 38874.6 39104.2 39277.0 39284.8 34324.0 34360.7 35031.8 35188.8 35313.6 23155.8 25350.0 25361.5 25379.9 25852.2 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:36234.21 Median:2284.47 stddev:101637.78 (4449.1%) stddev-sans-first:101887.18 15:43:02 INFO - PROCESS | 1656 | Values: 344.2 385.6 394.5 395.2 418.2 15385.5 15500.8 20550.0 20973.9 21004.9 20662.1 21032.0 21491.4 21992.2 22155.7 5271.9 5902.9 6154.9 6155.3 6167.3 4222.6 4462.4 6060.3 6417.9 6777.4 5941.6 6027.0 6391.7 6731.8 6797.4 5757.9 6572.6 6734.2 6757.3 6782.7 3718.4 5510.7 5817.3 5827.3 5840.7 26384.7 26780.3 27120.5 27856.9 27895.3 2061.1 2197.2 2265.4 2303.5 2393.4 8199.5 10529.4 10569.5 10808.2 10818.5 1560.0 1954.7 1956.3 1993.1 1999.7 129560.0 130549.3 132102.0 132419.0 133778.0 11644.1 11791.6 11798.4 11816.2 11981.7 14898.4 14976.8 14981.6 14991.0 14993.9 8797.8 8831.1 8831.3 8858.9 8946.7 8829.7 8841.8 8853.6 8962.0 8980.9 3985.9 4593.5 4629.4 4657.5 4675.8 1742.5 1777.8 1816.4 1894.0 1928.9 898.4 934.7 997.1 1006.2 1042.1 20933.6 22009.8 22227.4 22229.1 22241.3 924.6 932.9 934.0 952.9 967.6 11164.5 11598.4 11605.5 11607.2 11616.4 594453.0 600064.0 600196.0 615274.0 617113.0 135515.0 136023.0 139476.3 141337.0 144232.3 982.1 985.5 1021.2 1055.0 1065.3 19122.8 19392.5 19418.9 19452.6 19507.3 52679.7 52885.0 53155.5 54839.7 55067.7 79547.0 80454.0 81795.4 82157.6 82905.0 8005.7 8107.4 8370.2 8578.7 8662.5 11224.8 12814.0 12930.3 13042.5 13076.1 11555.8 11607.1 11630.5 11654.4 11786.9 11150.6 11195.7 11250.9 11272.1 11480.0 10404.0 10919.4 10995.3 11653.7 11716.1 7245.5 8255.2 8306.8 8396.5 8550.8 9777.7 11441.8 11513.7 11818.9 11916.6 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5745.80 Median:4990.16 stddev:3226.32 (64.7%) stddev-sans-first:3220.10 15:43:02 INFO - PROCESS | 1656 | Values: 1568.4 1586.2 1829.4 1947.4 1969.8 8537.1 8866.5 9038.5 9064.6 9091.0 7845.4 8109.5 8627.4 8821.4 8828.5 2344.4 2374.9 2377.1 2379.7 2383.4 2110.8 2361.3 2365.0 2402.0 2414.3 2159.5 2293.7 2354.3 2365.8 2367.3 2347.4 2358.9 2359.7 2362.2 2364.9 2356.5 2357.5 2361.6 2368.3 2370.5 8967.5 9052.7 9057.2 9062.6 9066.1 5248.8 5329.6 5401.0 5402.5 5417.9 3439.4 3506.6 3622.9 3626.3 3643.4 941.6 1080.2 1101.2 1107.5 1125.5 11693.3 11704.9 11705.8 11709.6 11728.1 3371.8 4660.9 4809.3 4810.7 4824.4 9907.5 9913.8 9920.8 9965.0 10014.7 5379.4 5380.5 5385.2 5405.1 5426.5 4782.1 4965.3 4967.5 4970.5 4984.9 3809.6 3821.9 3849.7 3905.3 3917.5 1089.9 1177.2 1179.0 1179.3 1187.9 678.9 785.3 917.2 933.4 960.1 6068.9 6114.6 6175.4 6203.7 6226.0 941.3 955.5 962.4 1001.9 1036.3 3732.5 3945.1 4138.9 4171.4 4180.4 10602.5 11052.4 11070.0 11078.3 11085.6 9966.9 10404.4 10808.3 10834.3 10934.3 7014.6 7628.5 7797.4 7804.9 7994.1 5388.8 6032.1 6099.5 6227.3 6231.0 9821.8 10057.4 10195.3 10285.7 10294.2 10065.5 10260.2 10459.6 10468.5 10539.8 7023.6 7052.6 7121.0 7130.9 7245.0 8310.1 8506.4 8533.0 8551.4 8567.3 6091.5 7460.4 7470.4 7523.0 7694.5 7403.5 7493.1 7494.6 7627.1 7637.9 7105.1 7164.6 7208.6 7217.4 7221.8 4867.0 4995.5 5069.5 5071.4 5097.3 5025.0 5058.6 5093.2 5160.7 5221.2 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4575.48 Median:5133.38 stddev:1405.47 (27.4%) stddev-sans-first:1371.90 15:43:02 INFO - PROCESS | 1656 | Values: 277.9 314.5 317.6 319.9 323.2 4565.1 5000.5 5338.3 5361.1 5448.5 5292.2 5340.2 5381.6 5383.3 5405.0 3243.4 3917.0 4049.7 4072.2 4118.4 3734.6 4086.9 4105.4 4134.2 4136.6 3669.6 4006.7 4070.7 4086.8 4117.3 3531.0 3716.3 3850.8 4037.9 4105.5 3217.4 3324.3 3550.1 3554.6 3557.9 4399.9 4531.3 4539.0 4962.0 5009.3 4066.4 4486.3 4705.2 4737.2 4799.9 5646.6 5727.9 5761.5 5778.6 5778.9 3762.4 4078.2 4087.7 4089.9 4111.9 4799.9 5035.8 5755.9 5839.7 6104.6 3619.5 3883.9 3910.7 3923.3 3967.8 3986.9 3997.9 4021.6 4046.3 4052.5 3718.8 4566.6 4658.2 4710.8 4745.9 4805.9 5656.3 5782.2 5782.7 5808.2 1188.4 1400.7 1402.7 1420.8 1424.5 845.3 885.5 893.2 900.5 901.6 4914.4 5118.5 5168.9 5315.0 5320.5 4916.2 5785.6 5805.9 5813.8 5885.6 4932.3 5037.5 5148.3 5177.4 5336.3 2672.6 3039.8 3050.3 3063.2 3076.0 5988.2 5998.7 6045.5 6062.1 6067.6 5310.2 5623.4 5757.8 5868.3 5896.6 4769.5 4848.2 5381.9 5961.9 5971.7 5557.5 5749.0 5774.7 5840.1 5847.6 5241.6 5662.7 5717.6 5743.5 5784.8 5227.3 5585.6 5686.4 5992.6 5994.8 3651.6 3674.7 3983.7 3985.2 4007.6 5419.9 5554.3 5808.3 5836.3 5866.4 4331.2 5297.8 5502.1 5801.8 5831.1 4987.5 5254.3 5308.0 5746.6 5778.7 4421.7 5641.2 5651.9 5655.9 5658.9 5584.4 5694.6 5700.0 5701.4 5757.5 4936.9 5552.6 5604.7 5634.6 5658.0 15:43:02 INFO - PROCESS | 1656 | 15:43:02 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24326.59 Median:3543.32 stddev:37853.16 (1068.3%) stddev-sans-first:37917.72 15:43:02 INFO - PROCESS | 1656 | Values: 683.2 758.6 760.5 804.8 851.2 15503.4 16299.0 16324.4 16329.6 16347.3 14814.3 14950.3 15018.3 15030.5 15074.1 725.3 746.4 907.0 912.9 943.9 914.0 920.5 926.7 927.9 929.9 942.2 961.7 965.1 966.5 967.5 744.8 930.9 954.4 956.2 961.3 721.8 730.8 731.2 731.4 731.6 13610.5 15462.8 15478.1 15493.4 15502.6 14107.8 14278.8 14295.1 14320.5 14538.5 12942.4 13106.5 13230.9 13300.3 13617.5 4526.8 4607.8 4614.3 4639.3 4676.7 110978.4 112301.4 116970.0 117317.0 119547.0 11398.3 11399.0 11503.7 11574.2 11690.6 22648.2 22674.0 22681.4 22695.0 22714.1 8078.7 8080.7 8087.2 8218.6 8323.1 4308.2 4402.3 4465.1 4531.8 4555.5 3493.3 3517.9 3568.7 3615.5 3616.2 636.4 645.3 648.6 660.8 663.0 554.7 607.5 627.5 638.3 662.8 8828.2 8950.2 9003.2 9028.7 9153.6 613.5 622.9 638.9 646.6 649.0 3668.7 4362.3 4371.5 4388.2 4404.0 120018.0 124292.0 125627.0 147615.0 153480.0 116681.2 121874.4 122607.4 126498.0 127288.0 104357.5 105990.5 107089.5 108160.0 110482.0 9720.0 9893.3 9907.8 9960.8 9961.0 60480.7 60811.0 61555.4 61849.7 61863.0 65393.7 82610.6 92274.0 93652.5 93655.0 11051.7 11351.8 11386.1 11754.1 13922.4 13278.5 14697.9 15007.8 15515.6 16083.6 13253.2 13528.4 13672.8 13737.8 13857.7 12356.3 12401.0 13022.4 13063.5 13924.5 13762.8 13967.6 13993.3 14096.1 14326.0 9561.7 11482.8 11712.9 11718.7 11811.5 11040.5 11413.2 11459.9 11712.6 11828.4 15:43:02 INFO - PROCESS | 1656 | -------- Summary: end -------- 15:43:02 INFO - PROCESS | 1656 | 15:43:03 INFO - TEST-INFO | 1656: exit 0 15:43:03 INFO - TEST-OK | dromaeo_css | took 1117048ms 15:43:03 INFO - TEST-START | kraken 15:43:03 INFO - operating with platform_type : mac_ 15:43:03 INFO - Initialising browser for kraken test... 15:43:03 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpB8tAgp/profile http://localhost:49271/getInfo.html) 15:43:05 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:43:05 INFO - PROCESS | 1663 | colorDepth:24 15:43:05 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:43:05 INFO - PROCESS | 1663 | __metrics 15:43:05 INFO - PROCESS | 1663 | 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" 15:43:05 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:43:06 INFO - TEST-INFO | 1663: exit 0 15:43:06 INFO - Browser initialized. 15:43:06 INFO - Running cycle 1/1 for kraken test... 15:43:06 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpB8tAgp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:18 INFO - PROCESS | 1665 | RSS: Main: 243466240 15:43:18 INFO - PROCESS | 1665 | 15:43:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 15:43:21 INFO - PROCESS | 1665 | RSS: Main: 294162432 15:43:21 INFO - PROCESS | 1665 | 15:43:24 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 15:43:25 INFO - PROCESS | 1665 | RSS: Main: 303697920 15:43:25 INFO - PROCESS | 1665 | 15:43:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 15:43:29 INFO - PROCESS | 1665 | RSS: Main: 389623808 15:43:29 INFO - PROCESS | 1665 | 15:43:31 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 15:43:31 INFO - PROCESS | 1665 | RSS: Main: 292438016 15:43:31 INFO - PROCESS | 1665 | 15:43:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:43:34 INFO - PROCESS | 1665 | RSS: Main: 296468480 15:43:34 INFO - PROCESS | 1665 | 15:43:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 15:43:37 INFO - PROCESS | 1665 | RSS: Main: 332738560 15:43:37 INFO - PROCESS | 1665 | 15:43:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 15:43:41 INFO - PROCESS | 1665 | RSS: Main: 336121856 15:43:41 INFO - PROCESS | 1665 | 15:43:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 15:43:44 INFO - PROCESS | 1665 | RSS: Main: 334417920 15:43:44 INFO - PROCESS | 1665 | 15:43:46 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:43:47 INFO - PROCESS | 1665 | RSS: Main: 313335808 15:43:47 INFO - PROCESS | 1665 | 15:43:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:43:49 INFO - PROCESS | 1665 | RSS: Main: 320180224 15:43:49 INFO - PROCESS | 1665 | 15:43:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:43:52 INFO - PROCESS | 1665 | RSS: Main: 317333504 15:43:52 INFO - PROCESS | 1665 | 15:43:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:43:55 INFO - PROCESS | 1665 | RSS: Main: 331866112 15:43:55 INFO - PROCESS | 1665 | 15:43:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:43:59 INFO - PROCESS | 1665 | RSS: Main: 328056832 15:43:59 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 15:44:01 INFO - PROCESS | 1665 | RSS: Main: 371490816 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | __start_tp_report 15:44:01 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 15:44:01 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 15:44:01 INFO - PROCESS | 1665 | |i|pagename|runs| 15:44:01 INFO - PROCESS | 1665 | |0;ai-astar;101;113;113;112;114;112;112;113;114;113 15:44:01 INFO - PROCESS | 1665 | |1;audio-beat-detection;144;112;107;153;106;107;127;106;140;108 15:44:01 INFO - PROCESS | 1665 | |2;audio-dft;183;186;184;182;184;182;184;179;182;186 15:44:01 INFO - PROCESS | 1665 | |3;audio-fft;77;74;58;105;57;56;61;64;95;82 15:44:01 INFO - PROCESS | 1665 | |4;audio-oscillator;80;91;91;71;70;68;95;70;67;97 15:44:01 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;87;85;88;85;87;85;130;85;88;85 15:44:01 INFO - PROCESS | 1665 | |6;imaging-darkroom;97;93;94;93;93;94;112;149;150;92 15:44:01 INFO - PROCESS | 1665 | |7;imaging-desaturate;84;84;84;86;84;84;90;85;93;132 15:44:01 INFO - PROCESS | 1665 | |8;json-parse-financial;56;56;55;55;55;54;53;53;54;55 15:44:01 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;67;68;82;79;73;70;71;72;72;71 15:44:01 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;81;86;82;81;81;86;84;83;85;79 15:44:01 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;145;126;142;150;147;147;138;136;181;134 15:44:01 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;162;143;208;233;202;164;162;162;162;161 15:44:01 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;68;65;66;66;67;67;74;83;84;83 15:44:01 INFO - PROCESS | 1665 | __end_tp_report 15:44:01 INFO - PROCESS | 1665 | __start_cc_report 15:44:01 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2031 15:44:01 INFO - PROCESS | 1665 | __end_cc_report 15:44:01 INFO - PROCESS | 1665 | __startTimestamp1470523441586__endTimestamp 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | ------- Summary: start ------- 15:44:01 INFO - PROCESS | 1665 | Number of tests: 14 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.83 (3.4%) stddev-sans-first:0.78 15:44:01 INFO - PROCESS | 1665 | Values: 101.0 113.0 113.0 112.0 114.0 112.0 112.0 113.0 114.0 113.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:119.50 stddev:18.38 (15.4%) stddev-sans-first:17.52 15:44:01 INFO - PROCESS | 1665 | Values: 144.0 112.0 107.0 153.0 106.0 107.0 127.0 106.0 140.0 108.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:2.10 (1.1%) stddev-sans-first:2.22 15:44:01 INFO - PROCESS | 1665 | Values: 183.0 186.0 184.0 182.0 184.0 182.0 184.0 179.0 182.0 186.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:72.90 Median:69.00 stddev:17.00 (24.6%) stddev-sans-first:17.97 15:44:01 INFO - PROCESS | 1665 | Values: 77.0 74.0 58.0 105.0 57.0 56.0 61.0 64.0 95.0 82.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:85.50 stddev:12.25 (14.3%) stddev-sans-first:12.99 15:44:01 INFO - PROCESS | 1665 | Values: 80.0 91.0 91.0 71.0 70.0 68.0 95.0 70.0 67.0 97.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:86.00 stddev:13.94 (16.2%) stddev-sans-first:14.73 15:44:01 INFO - PROCESS | 1665 | Values: 87.0 85.0 88.0 85.0 87.0 85.0 130.0 85.0 88.0 85.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:93.00 stddev:23.30 (25.1%) stddev-sans-first:24.45 15:44:01 INFO - PROCESS | 1665 | Values: 97.0 93.0 94.0 93.0 93.0 94.0 112.0 149.0 150.0 92.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:90.60 Median:84.50 stddev:14.87 (17.6%) stddev-sans-first:15.58 15:44:01 INFO - PROCESS | 1665 | Values: 84.0 84.0 84.0 86.0 84.0 84.0 90.0 85.0 93.0 132.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:1.01 15:44:01 INFO - PROCESS | 1665 | Values: 56.0 56.0 55.0 55.0 55.0 54.0 53.0 53.0 54.0 55.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.00 stddev:4.65 (6.5%) stddev-sans-first:4.48 15:44:01 INFO - PROCESS | 1665 | Values: 67.0 68.0 82.0 79.0 73.0 70.0 71.0 72.0 72.0 71.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.50 stddev:2.39 (2.9%) stddev-sans-first:2.45 15:44:01 INFO - PROCESS | 1665 | Values: 81.0 86.0 82.0 81.0 81.0 86.0 84.0 83.0 85.0 79.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:146.00 stddev:14.71 (10.1%) stddev-sans-first:15.61 15:44:01 INFO - PROCESS | 1665 | Values: 145.0 126.0 142.0 150.0 147.0 147.0 138.0 136.0 181.0 134.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:163.00 stddev:28.27 (17.3%) stddev-sans-first:29.53 15:44:01 INFO - PROCESS | 1665 | Values: 162.0 143.0 208.0 233.0 202.0 164.0 162.0 162.0 162.0 161.0 15:44:01 INFO - PROCESS | 1665 | 15:44:01 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:71.00 stddev:8.00 (11.3%) stddev-sans-first:8.33 15:44:01 INFO - PROCESS | 1665 | Values: 68.0 65.0 66.0 66.0 67.0 67.0 74.0 83.0 84.0 83.0 15:44:01 INFO - PROCESS | 1665 | -------- Summary: end -------- 15:44:01 INFO - PROCESS | 1665 | 15:44:02 INFO - TEST-INFO | 1665: exit 0 15:44:02 INFO - TEST-OK | kraken | took 59019ms 15:44:02 INFO - SUITE-END | took 1176s 15:44:02 INFO - Completed test suite (00:19:36) 15:44:02 INFO - javascript benchmark 15:44:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.613529444114, 3379.715708030948, 3380.290139070795, 3399.2861499085193, 3448.293099914518, 5648.576356773238, 5654.971725141374, 5719.084946408576, 5735.397783232758, 5784.2480477537865, 4759.262314341274, 5699.914501282474, 5783.681897495636, 5797.246357973468, 5835.883282334361, 5156.716380599066, 5161.225816127592, 5231.006108839328, 5234.555062819646, 5351.143816989283, 5334.413214546396, 5341.545968592674, 5365.624406291561, 5371.167469042305, 5413.864653383657, 4300.9139817203795, 5159.1229490986625, 5218.478152184789, 5236.2931004314505, 5305.708186049785, 5185.714785686786, 5245.213218017289, 5260.579153667697, 5273.31446911899, 5282.656627319212, 5172.66377685453, 5175.171972484384, 5215.16557350822, 5227.085260079471, 5240.947590524084, 5764.7117644117625, 5779.537636989031, 5793.102069179284, 5795.652260864362, 5848.298204215479, 5275.0, 6111.938880611181, 6177.104319873638, 6267.185265915402, 6290.3709629037185, 6297.937020629781, 6300.621962682208, 6332.955062414696, 6354.142190804228, 6372.076048972873, 5847.356790753014, 5859.531237501075, 5898.616589921641, 5900.409959004108, 5936.1095835625, 6213.130161777355, 6251.374862513803, 6316.526260530479, 6320.336364681734, 6352.4918006559365, 6188.721507532172, 6196.907046394308, 6203.472704820141, 6212.285587157445, 6221.3156552779155, 6321.589096708699, 6339.0808332791485, 6339.302676705559, 6349.079383489368, 6351.2060992375955, 6103.115048317969, 6113.228576830862, 6151.815445536641, 6224.37756224374, 6275.748970041239, 6287.654179020198, 6316.936830631635, 6320.241571011508, 6324.6521441321165, 6327.557071005078, 4943.01139772047, 4950.579200767975, 4953.90092198154, 4978.178600530944, 4986.601071914238, 4395.274779661384, 4403.8458653946955, 4404.66964977628, 4457.8439754608435, 4477.552244775497, 5800.5939584229545, 5808.0, 5813.476787089182, 5821.243238378984, 5872.442117998783, 6299.0484416184045, 6314.557980941382, 6329.525285603599, 6360.236771587439, 6373.74505019794, 5658.236138121383, 5706.971465142731, 5739.311282646109, 5781.53747700183, 5808.506276966421, 5403.702796346239, 5410.1343784994215, 5416.729163541807, 5429.457054294618, 5433.0, 6293.496520278367, 6341.746330146835, 6345.809625711236, 6360.332165122781, 6370.171877655967, 5109.897802044013, 6189.690515474298, 6228.937710622836, 6291.559590828781, 6293.496520278458, 6314.273858506147, 6326.0, 6343.809685709436, 6345.397187267202, 6371.681415929278, 6227.314995350417, 6228.03465462854, 6241.812745617639, 6255.437010669061, 6257.43683068526, 6100.914243738686, 6170.0, 6186.319504854372, 6222.284437289589, 6294.276158241861, 6076.392360763888, 6092.238470191226, 6098.176746139215, 6113.633182009094, 6117.635300584571, 5990.31111422192, 6119.07606531266, 6121.204243448323, 6123.496151642784, 6123.8162855114415, 5808.82573522795, 5879.147523608967, 5907.763689452546, 5945.078512830518, 5988.5508586855285, 6138.785142519991, 6141.293751218667, 6145.477634401126, 6148.877022459436, 6166.229221347332, 5783.710814459176, 5966.164736936919, 5974.402559744165, 5987.13186587951, 6000.459958603747], "value": 5721.203330240327, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.37587951241665, 500.9749512524373, 523.0846019465934, 547.0426753181931, 559.5188138201146, 26791.464170716597, 43119.0, 43911.34132988008, 44062.77968610157, 44232.557674423246, 43009.0, 43191.920201994966, 43299.56700432995, 43374.349384759254, 43511.78244108783, 7005.684744186498, 9560.378575392577, 9718.854217186748, 9724.416535007887, 9733.902660973406, 8612.956935215316, 11675.24110932791, 11835.0, 11838.881611183864, 12075.516979320817, 11898.821517677241, 11952.282863028246, 11954.402279885971, 11968.461419236111, 11981.460834262436, 8544.487330760112, 8859.29125669945, 11767.941160294187, 11848.940755296167, 11852.644420667393, 8801.67974803778, 8878.511681857495, 11379.829302560469, 11633.302001879829, 11647.184697071209, 39045.0, 40771.1845763086, 41039.384409233884, 41125.0, 41136.177276454306, 3563.3051554946796, 3581.8925432237074, 3624.5650521937537, 3641.635836416364, 3665.6151104134215, 30934.53598196029, 31635.20911977196, 31814.363712725848, 31978.360432791447, 32242.0, 2945.955810662842, 3239.1416274687226, 3278.704916557497, 3280.114369120348, 3396.745244106702, 527322.0, 527964.7203527993, 529644.0, 530258.3487082579, 530679.9999999962, 59242.40757592369, 59715.0, 59790.10314845281, 59794.10308845371, 59892.0, 62440.75118497605, 62521.0, 62651.686741566, 62706.68646656738, 62751.0, 16473.0, 16667.83332166687, 16675.332986680638, 16683.332666693197, 16748.330066797436, 11820.40897955116, 12070.0, 12097.274163550215, 12102.455389507493, 12111.87888121125, 15455.690886182214, 15804.656604188543, 15812.288447019911, 16254.18729063542, 16294.755578666329, 6077.513798896078, 6418.967905160538, 6466.288708242089, 6480.870382592416, 6487.0, 884.2660591708866, 924.58393722825, 949.7435692363161, 1091.8853520380405, 1141.3095077478006, 30467.390652186834, 30497.0, 30561.541576876363, 31460.528092078635, 31538.684613154033, 1040.8802987656516, 1087.1574529739514, 1115.59838458155, 1162.91859569831, 1228.3182833527478, 15567.143807090491, 15899.920500397655, 16003.27985240667, 16386.09876456806, 16394.590135246715, 689654.0, 698530.0, 698963.0, 699932.0040797467, 700423.0, 48479.75760121247, 59137.70431147817, 59145.0, 59307.40692593105, 59466.70266648639, 1257.3084803358117, 1297.9935100324249, 1374.5738820965532, 1413.0956188039663, 1430.1133297355707, 30803.0, 31028.0691579253, 31350.843245783624, 31719.0, 32250.516242255915, 49968.0, 50078.499215008844, 50422.24366634577, 50563.2415513775, 50637.24044139267, 576702.0, 578965.0, 579948.0, 580783.0, 580787.0, 27418.5887211696, 34841.0, 34857.302853943285, 38016.61983380241, 40484.1903161941, 40030.39954400744, 46557.30164047474, 48256.0, 48438.0, 49643.25535117048, 39414.83451067495, 39426.21147576975, 40166.598334016286, 40404.16047548291, 41847.37228941507, 39773.801130995314, 40297.597024030554, 40680.59319406768, 41129.79435102805, 41235.38146927858, 38435.42346864743, 38874.64210133201, 39104.2179156421, 39277.0, 39284.80357598194, 34323.97028089161, 34360.656393436744, 35031.82484087665, 35188.82405587956, 35313.64686353104, 23155.768442315362, 25350.0, 25361.49277014486, 25379.85790639389, 25852.224602660855], "value": 20574.51087759796, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.22892720306487, 385.6259428354496, 394.54036048004076, 395.21549723798256, 418.2074967935762, 15385.538433846974, 15500.844991550095, 20550.0, 20973.895130524365, 21004.8949755251, 20662.140407363335, 21032.0, 21491.355259342246, 21992.230271940487, 22155.667664984998, 5271.920921186185, 5902.881942361139, 6154.938450615481, 6155.26136863577, 6167.259928808527, 4222.619964203221, 4462.352958820969, 6060.272767267912, 6417.93582064178, 6777.390034896858, 5941.643501389931, 6026.975414179578, 6391.712372943205, 6731.764388246413, 6797.422219111357, 5757.913631295534, 6572.605643661372, 6734.19189697237, 6757.290484499155, 6782.72869085236, 3718.3678774608384, 5510.724463776835, 5817.301923769175, 5827.329857066449, 5840.678762668052, 26384.73615263842, 26780.330491737666, 27120.457590848073, 27856.860715696494, 27895.302617434525, 2061.093119027623, 2197.1870407949, 2265.3996690876766, 2303.5392921415782, 2393.365758074112, 8199.467034642728, 10529.368237905674, 10569.471526423647, 10808.189385796097, 10818.513166907507, 1559.9532014039596, 1954.697021961584, 1956.3152896486258, 1993.1230258686132, 1999.6500612392917, 129560.0, 130549.34725326313, 132102.0, 132419.0, 133778.0, 11644.126690498246, 11791.646250612495, 11798.351090689923, 11816.231944923553, 11981.663575602848, 14898.404063837324, 14976.775348369783, 14981.550553483414, 14991.0, 14993.925030374778, 8797.780055498664, 8831.072737362612, 8831.3376496763, 8858.911410885808, 8946.731598052069, 8829.69096081634, 8841.778955526035, 8853.64585416589, 8962.0, 8980.8652870207, 3985.903876434004, 4593.47175074864, 4629.421322334708, 4657.510961349078, 4675.789589468414, 1742.538227369748, 1777.7863474878243, 1816.3551939061706, 1894.0245773426695, 1928.9035548222532, 898.4070513461215, 934.7287688743304, 997.1474389396961, 1006.1548299428515, 1042.103790739963, 20933.581328373348, 22009.812974489247, 22227.444313892283, 22229.11083556672, 22241.332760017227, 924.630147940829, 932.911373419524, 934.0052843721415, 952.9094736000069, 967.583938906263, 11164.497597608128, 11598.362090084958, 11605.535778568932, 11607.187496875307, 11616.419179041015, 594453.0, 600064.0, 600196.0, 615274.0, 617113.0, 135515.0, 136023.0, 139476.3026184883, 141337.0, 144232.27883860722, 982.0964712464549, 985.5466485416645, 1021.2442792333768, 1054.9683509494728, 1065.2649671726483, 19122.80877191266, 19392.515187120436, 19418.90290548538, 19452.610947780682, 19507.317243896963, 52679.73660131675, 52885.0, 53155.468445315055, 54839.72580137074, 55067.72466137644, 79547.0, 80454.0, 81795.37412789946, 82157.58921205356, 82905.0, 8005.719799806979, 8107.4324797263625, 8370.204830541088, 8578.65685372578, 8662.480251184948, 11224.831627525431, 12814.0, 12930.254415653766, 13042.478300867859, 13076.084674072723, 11555.768884622428, 11607.071434285068, 11630.53477860876, 11654.359010254178, 11786.882131178578, 11150.609728659458, 11195.72010699739, 11250.887491125311, 11272.062934270505, 11480.024197943269, 10404.0, 10919.39943303126, 10995.285306455055, 11653.6503904883, 11716.06271498295, 7245.529040612343, 8255.174482551696, 8306.83386332258, 8396.538190399586, 8550.78623034429, 9777.706668799947, 11441.771164576496, 11513.654590362303, 11818.940905295418, 11916.642500724993], "value": 9805.407181842145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1568.3569736408072, 1586.2227508520825, 1829.3962992212562, 1947.396307144786, 1969.7636283645957, 8537.14628537146, 8866.512341821197, 9038.502882341476, 9064.63741450342, 9091.0, 7845.411594130434, 8109.472884262534, 8627.352948528855, 8821.382503224777, 8828.51443170627, 2344.4021774447533, 2374.8812559371963, 2377.132346693453, 2379.7144342678903, 2383.3803211165136, 2110.757262914769, 2361.2680069178523, 2365.0185173153122, 2402.039184326266, 2414.2757172848164, 2159.492519257973, 2293.7018187635585, 2354.2937118864356, 2365.7989070928993, 2367.3489790307717, 2347.436615212354, 2358.905643774247, 2359.728631207416, 2362.2204672458047, 2364.9408764780846, 2356.5404746074523, 2357.5167090746354, 2361.6184532048774, 2368.301351101412, 2370.5495955768347, 8967.506787126706, 9052.728418147466, 9057.184853363164, 9062.637494500212, 9066.138716821892, 5248.81629142982, 5329.600279979017, 5400.972995135038, 5402.486763757437, 5417.864553386157, 3439.3809114359406, 3506.6493350664987, 3622.9275414491563, 3626.3110009098186, 3643.3988391915304, 941.5621735892843, 1080.200651517879, 1101.1686176936362, 1107.5403707461394, 1125.538529203031, 11693.298402095901, 11704.941475292655, 11705.824412633818, 11709.590164344207, 11728.061755059489, 3371.7976921384798, 4660.883477913011, 4809.278608208797, 4810.687305325143, 4824.396950381202, 9907.45508997012, 9913.752156196153, 9920.751981200383, 9964.950175249078, 10014.699559013241, 5379.381371142291, 5380.488853558905, 5385.219143224211, 5405.108157154104, 5426.538744206708, 4782.115308667908, 4965.255211718271, 4967.528084831935, 4970.453250142481, 4984.850454486371, 3809.5999920008, 3821.923561528754, 3849.749766265184, 3905.2970465316516, 3917.4515567820526, 1089.8801131875487, 1177.2289150606366, 1178.952841886315, 1179.280638810325, 1187.940602969848, 678.8811957907346, 785.2775446589152, 917.1699611851285, 933.4306073295285, 960.1406740966935, 6068.939310606926, 6114.6255502216445, 6175.382461753788, 6203.689815509207, 6226.034964580497, 941.2564074381162, 955.5365647660878, 962.3648392061206, 1001.8797744270588, 1036.264252380803, 3732.4774531565604, 3945.132070944445, 4138.882501724517, 4171.374293855946, 4180.435641188462, 10602.469876506144, 11052.392118433403, 11070.0, 11078.279911805706, 11085.612003579998, 9966.850497242547, 10404.375737455777, 10808.297460665031, 10834.295770774874, 10934.289271197347, 7014.579125252501, 7628.46600737943, 7797.376209903194, 7804.882926756103, 7994.120646728855, 5388.784448622011, 6032.095185722177, 6099.542534309856, 6227.252729672287, 6230.968845155745, 9821.803563928825, 10057.446840423554, 10195.286329956833, 10285.74285642835, 10294.227932905213, 10065.547050382604, 10260.230482713678, 10459.633912813015, 10468.476576171313, 10539.841902371623, 7023.578585284899, 7052.576845389293, 7121.0, 7130.893036604351, 7245.0, 8310.12743661931, 8506.404551681322, 8532.957335213285, 8551.401401901807, 8567.314614830673, 6091.543134264859, 7460.440466964891, 7470.439717021354, 7523.0, 7694.461387702807, 7403.519296140901, 7493.063367079115, 7494.625268736432, 7627.084749830056, 7637.923620763721, 7105.111861017373, 7164.641767911688, 7208.56748595086, 7217.386522145676, 7221.783346499614, 4866.951330486791, 4995.475475075064, 5069.493050694901, 5071.4421413645205, 5097.28637990689, 5024.974875125601, 5058.620603454682, 5093.18509038552, 5160.664556803796, 5221.216817477409], "value": 4610.307635402995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.88884446221516, 314.52192667145937, 317.6172711882181, 319.9152224660464, 323.2323232323232, 4565.132624801286, 5000.549950504458, 5338.27933229014, 5361.088614935461, 5448.509634132927, 5292.205035486146, 5340.225667278242, 5381.623286369946, 5383.273258110164, 5405.0, 3243.4323993301196, 3916.9804150979207, 4049.7445791804635, 4072.226277007374, 4118.38224266361, 3734.5518537775506, 4086.8569600064, 4105.4252404663375, 4134.235166494208, 4136.627703506684, 3669.5596528416627, 4006.7395619284653, 4070.715049946505, 4086.7752273625088, 4117.258893399187, 3530.958367281658, 3716.2567486502808, 3850.84596616135, 4037.899052523681, 4105.527864295615, 3217.420864244435, 3324.2852786650747, 3550.0947258449187, 3554.591222009476, 3557.8576856925692, 4399.934000989988, 4531.297648864432, 4539.001419687696, 4962.0, 5009.273655319958, 4066.38092098033, 4486.259767138418, 4705.1530724469585, 4737.2420412734145, 4799.920017995958, 5646.632968857052, 5727.885442291173, 5761.481466667978, 5778.595498315078, 5778.913316300259, 3762.3980163174033, 4078.163976384834, 4087.691938579656, 4089.8568550100604, 4111.8972025699295, 4799.904001919942, 5035.77339019745, 5755.913661295084, 5839.686070634159, 6104.642446654322, 3619.529461170084, 3883.86406475772, 3910.687145028391, 3923.3330333842746, 3967.8412863485137, 3986.946722437947, 3997.920041599152, 4021.617946295156, 4046.251443482965, 4052.452918855976, 3718.8140592970244, 4566.589006989387, 4658.231391820312, 4710.764461776897, 4745.905081898342, 4805.927911081337, 5656.264685590847, 5782.2483077199695, 5782.681952492653, 5808.244928159313, 1188.3998580716855, 1400.6708423520465, 1402.712443948988, 1420.7939848721878, 1424.508544552128, 845.3364109174269, 885.5085427587751, 893.2005854759954, 900.5317235037744, 901.6438506789781, 4914.385701787277, 5118.513741194581, 5168.9224661630105, 5315.0, 5320.547753441001, 4916.188828843272, 5785.623934444247, 5805.94194058054, 5813.767449302065, 5885.5585831062845, 4932.284818701268, 5037.496250375007, 5148.253503242009, 5177.352830896138, 5336.332958380202, 2672.6124711916664, 3039.8176109433507, 3050.2984313607994, 3063.234191452137, 3076.0, 5988.221531200916, 5998.730057147439, 6045.486133678599, 6062.090686397076, 6067.605605635619, 5310.150141147637, 5623.409541998113, 5757.769689212469, 5868.295804503487, 5896.587238893237, 4769.523047695272, 4848.224284114524, 5381.892362152813, 5961.940380596138, 5971.731272092767, 5557.527610153183, 5748.971255143698, 5774.711264436677, 5840.123981402823, 5847.649141051551, 5241.645346977861, 5662.745176467151, 5717.628354156966, 5743.540516758733, 5784.768609255751, 5227.340861397113, 5585.5531557476115, 5686.374498805211, 5992.580519363777, 5994.820155395345, 3651.634836516327, 3674.724395670282, 3983.6813054954955, 3985.202959408188, 4007.6192761687594, 5419.945800541944, 5554.2779438672715, 5808.340745330414, 5836.336644056423, 5866.354700982974, 4331.155424692281, 5297.8145764898045, 5502.147167189092, 5801.767929282782, 5831.125331200354, 4987.476314986874, 5254.264402983509, 5307.9734601326745, 5746.626469279484, 5778.682172480554, 4421.734695918255, 5641.238432811518, 5651.88696226065, 5655.85860353478, 5658.85852853682, 5584.441555844383, 5694.63328801093, 5700.0, 5701.377366411497, 5757.5393968481585, 4936.851894443173, 5552.5557955362665, 5604.719764011865, 5634.591072401138, 5657.971710141423], "value": 4128.986064137912, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.1938312790907, 758.6093162021557, 760.4714723267329, 804.8269622031265, 851.2041241439251, 15503.379864805409, 16299.0, 16324.428644997428, 16329.591760206, 16347.34610615574, 14814.259287035658, 14950.252487375616, 15018.324175412105, 15030.549083527485, 15074.095554266723, 725.310954593136, 746.4327111395352, 907.0, 912.858506931425, 943.8725772020758, 914.021996463784, 920.5351297594706, 926.7451450851003, 927.8654595083709, 929.907009299068, 942.2132519346354, 961.6538046303325, 965.1362030982267, 966.5408930757869, 967.5307475874231, 744.8175197076697, 930.930180236485, 954.4321128928242, 956.1967946924582, 961.3174646001347, 721.8448033672786, 730.8063363208701, 731.1847290271362, 731.356406362403, 731.6159016516318, 13610.523631672844, 15462.768058479132, 15478.148701821396, 15493.380264789395, 15502.61243468911, 14107.78838317426, 14278.785818212735, 14295.142291462545, 14320.498782542561, 14538.491152809602, 12942.402143176087, 13106.541271055466, 13230.86769132306, 13300.268485233308, 13617.523386681418, 4526.79629416677, 4607.769611519444, 4614.2617181250835, 4639.280911458729, 4676.7427791471455, 110978.44510777475, 112301.43849280784, 116969.99999999916, 117317.0, 119547.0, 11398.316101033965, 11399.0, 11503.712407189887, 11574.247673901169, 11690.590829321103, 22648.207312743976, 22673.979670914847, 22681.432964176027, 22695.0, 22714.091436342693, 8078.7172448964, 8080.676772929017, 8087.231712987257, 8218.589070546448, 8323.126071762377, 4308.202982448257, 4402.273624851928, 4465.129299786576, 4531.796069176895, 4555.521670224645, 3493.2664140530774, 3517.9472307915403, 3568.6966607838103, 3615.493830863681, 3616.150204701893, 636.3922454056458, 645.3353046362255, 648.6367634124811, 660.8017594721564, 662.986740265192, 554.6866020698293, 607.4502575169479, 627.4885967936117, 638.2819328255713, 662.8309781005813, 8828.205461508494, 8950.194482496476, 9003.234725048313, 9028.683996060106, 9153.63385464574, 613.5275837605019, 622.8726005929283, 638.9424951754364, 646.5668002438377, 648.9643069631215, 3668.738584957767, 4362.345648152803, 4371.519132895379, 4388.188185185751, 4404.0, 120018.0, 124292.0, 125627.0, 147615.0, 153480.0, 116681.24978125335, 121874.3906280463, 122607.38696306461, 126498.0, 127288.0, 104357.47821260845, 105990.47004764927, 107089.46455267674, 108160.0, 110482.0, 9719.951400242953, 9893.25800564946, 9907.801843962936, 9960.750981225527, 9960.950195248977, 60480.69759651174, 60811.0, 61555.38444615676, 61849.69075154595, 61863.0, 65393.67303163644, 82610.58694706488, 92274.0, 93652.53173734088, 93655.0, 11051.72370690707, 11351.772964540498, 11386.146039046938, 11754.118441117122, 13922.373493193034, 13278.468861245441, 14697.85302146965, 15007.77488337654, 15515.612109697347, 16083.597910052342, 13253.220924991101, 13528.39122239482, 13672.794908076587, 13737.793933090812, 13857.653558661113, 12356.320402377956, 12401.0, 13022.413991370222, 13063.477460901457, 13924.503904112915, 13762.793558096437, 13967.580972570839, 13993.325584759294, 14096.08375455592, 14326.0, 9561.713148605553, 11482.827757583476, 11712.94143529311, 11718.70703232426, 11811.468483918416, 11040.503177356877, 11413.201075924606, 11459.942700286445, 11712.648620541397, 11828.408579570814], "value": 7938.060261009249, "unit": "score"}], "name": "dromaeo_css", "value": 7474.856148494695, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 112.0, 114.0, 112.0, 112.0, 113.0, 114.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 107.0, 153.0, 106.0, 107.0, 127.0, 106.0, 140.0, 108.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 184.0, 182.0, 184.0, 182.0, 184.0, 179.0, 182.0, 186.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 58.0, 105.0, 57.0, 56.0, 61.0, 64.0, 95.0, 82.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 91.0, 91.0, 71.0, 70.0, 68.0, 95.0, 70.0, 67.0, 97.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 85.0, 130.0, 85.0, 88.0, 85.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 94.0, 93.0, 93.0, 94.0, 112.0, 149.0, 150.0, 92.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 86.0, 84.0, 84.0, 90.0, 85.0, 93.0, 132.0], "value": 90.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 55.0, 55.0, 54.0, 53.0, 53.0, 54.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 82.0, 79.0, 73.0, 70.0, 71.0, 72.0, 72.0, 71.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 82.0, 81.0, 81.0, 86.0, 84.0, 83.0, 85.0, 79.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 126.0, 142.0, 150.0, 147.0, 147.0, 138.0, 136.0, 181.0, 134.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 143.0, 208.0, 233.0, 202.0, 164.0, 162.0, 162.0, 162.0, 161.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 66.0, 67.0, 67.0, 74.0, 83.0, 84.0, 83.0], "value": 72.3, "unit": "score"}], "name": "kraken", "value": 1459.3, "alertThreshold": 2.0}]} 15:44:02 INFO - Return code: 0 15:44:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:44:02 INFO - # TBPL SUCCESS # 15:44:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:44:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:44:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:44:02 INFO - 2016-08-06 15:44:02.883 screenresolution[1669:11329] starting screenresolution argv=screenresolution get 15:44:02 INFO - 2016-08-06 15:44:02.885 screenresolution[1669:11329] Display 0: 1600x1200x32@60 15:44:02 INFO - 2016-08-06 15:44:02.891 screenresolution[1670:11333] starting screenresolution argv=screenresolution list 15:44:02 INFO - Available Modes on Display 0 15:44:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:44:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:44:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:44:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:44:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:44:03 INFO - Intel Iris: 15:44:03 INFO - Chipset Model: Intel Iris 15:44:03 INFO - Type: GPU 15:44:03 INFO - Bus: Built-In 15:44:03 INFO - VRAM (Dynamic, Max): 1536 MB 15:44:03 INFO - Vendor: Intel (0x8086) 15:44:03 INFO - Device ID: 0x0a2e 15:44:03 INFO - Revision ID: 0x0009 15:44:03 INFO - Displays: 15:44:03 INFO - Display: 15:44:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:44:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:44:03 INFO - Display Serial Number: 1600x1200 60 15:44:03 INFO - Main Display: Yes 15:44:03 INFO - Mirror: Off 15:44:03 INFO - Online: Yes 15:44:03 INFO - Rotation: Supported 15:44:03 INFO - Return code: 0 15:44:03 INFO - Running post-action listener: _resource_record_post_action 15:44:03 INFO - ##### 15:44:03 INFO - ##### Finished run-tests step (success) 15:44:03 INFO - ##### 15:44:03 INFO - Running post-run listener: _resource_record_post_run 15:44:03 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32538112; Write bytes: 705261056; Read time: 323; Write time: 1848 15:44:03 INFO - TinderboxPrint: CPU usage
53.7% 15:44:03 INFO - TinderboxPrint: I/O read bytes / time
32,538,112 / 323 15:44:03 INFO - TinderboxPrint: I/O write bytes / time
705,261,056 / 1,848 15:44:03 INFO - TinderboxPrint: CPU idle
2,245.5 (46.3%) 15:44:03 INFO - TinderboxPrint: CPU system
970.2 (20.0%) 15:44:03 INFO - TinderboxPrint: CPU user
1,629.9 (33.6%) 15:44:03 INFO - TinderboxPrint: Swap in / out
434,995,200 / 0 15:44:03 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387631616; Write bytes: 382649856; Read time: 29098; Write time: 733 15:44:03 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31616512; Write bytes: 295385088; Read time: 310; Write time: 1067 15:44:03 INFO - Running post-run listener: _upload_blobber_files 15:44:03 INFO - Blob upload gear active. 15:44:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:44:03 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 15:44:03 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'] 15:44:03 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 15:44:04 INFO - (blobuploader) - INFO - Open directory for files ... 15:44:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:44:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:44:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:05 INFO - (blobuploader) - INFO - Done attempting. 15:44:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:44:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:44:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:06 INFO - (blobuploader) - INFO - Done attempting. 15:44:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:44:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:08 INFO - (blobuploader) - INFO - Done attempting. 15:44:08 INFO - (blobuploader) - INFO - Iteration through files over. 15:44:08 INFO - Return code: 0 15:44:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:44:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:44:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e38a517551f715b8d849cf6b9d8d5502b08499f87b8d2baf656c6050580be0628852d60c53608d0243e4f9778a30b2fb8d1af1305f77fd6c644829dc4c03b92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc7bf3b0ac18981ef4db476d28ed0b6ae9c83677fcfbf5155ff1adc56efa360ab768dfbc200591bfaf469a234c4605cc4f5307254dd943da6484285deabb47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8c128ad679fb45c366d02a17d6c29ae4e0a42f382fbccc7a9f2f96c5cbc023494e8f7d5283f0f01736fc03ab1ababcdf431ed56dec49aa4b07531dd0f7aff90"} 15:44:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:44:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:44:08 INFO - Contents: 15:44:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e38a517551f715b8d849cf6b9d8d5502b08499f87b8d2baf656c6050580be0628852d60c53608d0243e4f9778a30b2fb8d1af1305f77fd6c644829dc4c03b92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc7bf3b0ac18981ef4db476d28ed0b6ae9c83677fcfbf5155ff1adc56efa360ab768dfbc200591bfaf469a234c4605cc4f5307254dd943da6484285deabb47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8c128ad679fb45c366d02a17d6c29ae4e0a42f382fbccc7a9f2f96c5cbc023494e8f7d5283f0f01736fc03ab1ababcdf431ed56dec49aa4b07531dd0f7aff90"} 15:44:08 INFO - Running post-run listener: copy_logs_to_upload_dir 15:44:08 INFO - Copying logs to upload dir... 15:44:08 INFO - mkdir: /builds/slave/test/build/upload/logs 15:44:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.585328 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-06 15:44:08.256781) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 15:44:08.260051) ========= 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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/5e38a517551f715b8d849cf6b9d8d5502b08499f87b8d2baf656c6050580be0628852d60c53608d0243e4f9778a30b2fb8d1af1305f77fd6c644829dc4c03b92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc7bf3b0ac18981ef4db476d28ed0b6ae9c83677fcfbf5155ff1adc56efa360ab768dfbc200591bfaf469a234c4605cc4f5307254dd943da6484285deabb47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8c128ad679fb45c366d02a17d6c29ae4e0a42f382fbccc7a9f2f96c5cbc023494e8f7d5283f0f01736fc03ab1ababcdf431ed56dec49aa4b07531dd0f7aff90"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011285 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468317722/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e38a517551f715b8d849cf6b9d8d5502b08499f87b8d2baf656c6050580be0628852d60c53608d0243e4f9778a30b2fb8d1af1305f77fd6c644829dc4c03b92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc7bf3b0ac18981ef4db476d28ed0b6ae9c83677fcfbf5155ff1adc56efa360ab768dfbc200591bfaf469a234c4605cc4f5307254dd943da6484285deabb47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8c128ad679fb45c366d02a17d6c29ae4e0a42f382fbccc7a9f2f96c5cbc023494e8f7d5283f0f01736fc03ab1ababcdf431ed56dec49aa4b07531dd0f7aff90"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 15:44:08.292791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 15:44:08.293153) ========= 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.ZgmBVBvwXe/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.xbYKKhIsGH/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005289 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 15:44:08.341485) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 15:44:08.341805) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 15:44:08.342145) ========= ========= Total master_lag: 0.17 =========