builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0047 starttime: 1469613761.54 results: success (0) buildid: 20160704144857 builduid: bc664416cded4d938f8dbe94591ab4e8 revision: 0842107a80e7b434b964922bfb5b4b3f88e8eec2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.540056) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.540498) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.540771) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.565437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.566023) ========= 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.x1H2zVQdXC/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.93JSvAatgr/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-27 03:02:41-- 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% 6.90M=0.002s 2016-07-27 03:02:41 (6.90 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086090 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.679803) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.680629) ========= 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.x1H2zVQdXC/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.93JSvAatgr/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.020240 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:41.730525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-27 03:02:41.730844) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0842107a80e7b434b964922bfb5b4b3f88e8eec2 --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 0842107a80e7b434b964922bfb5b4b3f88e8eec2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x1H2zVQdXC/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.93JSvAatgr/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-27 03:02:41,805 truncating revision to first 12 chars 2016-07-27 03:02:41,805 Setting DEBUG logging. 2016-07-27 03:02:41,805 attempt 1/10 2016-07-27 03:02:41,805 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0842107a80e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 03:02:43,694 unpacking tar archive at: mozilla-central-0842107a80e7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.154528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-27 03:02:43.907200) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:43.907503) ========= script_repo_revision: 0842107a80e7b434b964922bfb5b4b3f88e8eec2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:43.907881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:43.908164) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 03:02:43.926146) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-27 03:02:43.926461) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.x1H2zVQdXC/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.93JSvAatgr/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:02:44 INFO - MultiFileLogger online at 20160727 03:02:44 in /builds/slave/test 03:02:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:02:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:02:44 INFO - {'append_to_log': False, 03:02:44 INFO - 'base_work_dir': '/builds/slave/test', 03:02:44 INFO - 'blob_upload_branch': 'Firefox', 03:02:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:02:44 INFO - 'branch': 'Firefox', 03:02:44 INFO - 'buildbot_json_path': 'buildprops.json', 03:02:44 INFO - 'config_files': ('talos/mac_config.py',), 03:02:44 INFO - 'default_actions': ('clobber', 03:02:44 INFO - 'read-buildbot-config', 03:02:44 INFO - 'download-and-extract', 03:02:44 INFO - 'populate-webroot', 03:02:44 INFO - 'create-virtualenv', 03:02:44 INFO - 'install', 03:02:44 INFO - 'run-tests'), 03:02:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:02:44 INFO - 'download_minidump_stackwalk': True, 03:02:44 INFO - 'download_symbols': 'ondemand', 03:02:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:02:44 INFO - 'tooltool.py': '/tools/tooltool.py', 03:02:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:02:44 INFO - '/tools/misc-python/virtualenv.py')}, 03:02:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:02:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:02:44 INFO - 'installer_path': 'installer.exe', 03:02:44 INFO - 'log_level': 'info', 03:02:44 INFO - 'log_name': 'talos', 03:02:44 INFO - 'log_to_console': True, 03:02:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:02:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:02:44 INFO - 'opt_config_files': (), 03:02:44 INFO - 'pip_index': False, 03:02:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:44 INFO - 'cmd': ('bash', 03:02:44 INFO - '-c', 03:02:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:44 INFO - 'enabled': True, 03:02:44 INFO - 'halt_on_failure': False, 03:02:44 INFO - 'name': 'check_screen_resolution'},), 03:02:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:44 INFO - 'cmd': ('bash', 03:02:44 INFO - '-c', 03:02:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:44 INFO - 'enabled': True, 03:02:44 INFO - 'halt_on_failure': False, 03:02:44 INFO - 'name': 'check_screen_resolution'},), 03:02:44 INFO - 'run_cmd_checks_enabled': True, 03:02:44 INFO - 'sps_profile': False, 03:02:44 INFO - 'sps_profile_interval': 0, 03:02:44 INFO - 'suite': 'dromaeojs-e10s', 03:02:44 INFO - 'system_bits': '32', 03:02:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:02:44 INFO - 'title': 't-yosemite-r7-0047', 03:02:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:02:44 INFO - 'use_talos_json': True, 03:02:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:02:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:02:44 INFO - 'work_dir': 'build'} 03:02:44 INFO - ##### 03:02:44 INFO - ##### Running clobber step. 03:02:44 INFO - ##### 03:02:44 INFO - Running pre-action listener: _resource_record_pre_action 03:02:44 INFO - Running main action method: clobber 03:02:44 INFO - rmtree: /builds/slave/test/build 03:02:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:02:45 INFO - Running post-action listener: _resource_record_post_action 03:02:45 INFO - ##### 03:02:45 INFO - ##### Finished clobber step (success) 03:02:45 INFO - ##### 03:02:45 INFO - ##### 03:02:45 INFO - ##### Running read-buildbot-config step. 03:02:45 INFO - ##### 03:02:45 INFO - Running pre-action listener: _resource_record_pre_action 03:02:45 INFO - Running main action method: read_buildbot_config 03:02:45 INFO - Using buildbot properties: 03:02:45 INFO - { 03:02:45 INFO - "project": "", 03:02:45 INFO - "product": "firefox", 03:02:45 INFO - "script_repo_revision": "production", 03:02:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:02:45 INFO - "repository": "", 03:02:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:02:45 INFO - "buildid": "20160704144857", 03:02:45 INFO - "pgo_build": "False", 03:02:45 INFO - "basedir": "/builds/slave/test", 03:02:45 INFO - "buildnumber": 25, 03:02:45 INFO - "slavename": "t-yosemite-r7-0047", 03:02:45 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:02:45 INFO - "platform": "yosemite_r7", 03:02:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:02:45 INFO - "branch": "mozilla-central", 03:02:45 INFO - "repo_path": "mozilla-central", 03:02:45 INFO - "revision": "0842107a80e7b434b964922bfb5b4b3f88e8eec2", 03:02:45 INFO - "stage_platform": "macosx64", 03:02:45 INFO - "builduid": "bc664416cded4d938f8dbe94591ab4e8", 03:02:45 INFO - "slavebuilddir": "test" 03:02:45 INFO - } 03:02:45 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg. 03:02:45 INFO - Running post-action listener: _resource_record_post_action 03:02:45 INFO - ##### 03:02:45 INFO - ##### Finished read-buildbot-config step (success) 03:02:45 INFO - ##### 03:02:45 INFO - ##### 03:02:45 INFO - ##### Running download-and-extract step. 03:02:45 INFO - ##### 03:02:45 INFO - Running pre-action listener: _resource_record_pre_action 03:02:45 INFO - Running main action method: download_and_extract 03:02:45 INFO - mkdir: /builds/slave/test/build/tests 03:02: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')]} 03:02:45 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.test_packages.json 03:02:45 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:02:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/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 03:02:46 INFO - Downloaded 1199 bytes. 03:02:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:02:46 INFO - Using the following test package requirements: 03:02:46 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:02:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:02:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:02:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'jsshell-mac.zip'], 03:02:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:02:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:02:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:02:46 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:02:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:02:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:02:46 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:02:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:02:46 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.common.tests.zip 03:02:46 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:02:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/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 03:02:46 INFO - Downloaded 18795171 bytes. 03:02:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:02:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:02:49 INFO - Return code: 0 03:02:49 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 03:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:02:49 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.common.tests.zip 03:02:49 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:02:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/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 03:02:49 INFO - Downloaded 18795171 bytes. 03:02:49 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'] 03:02:49 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 03:02:53 INFO - Return code: 0 03:02:53 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')]} 03:02:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.talos.tests.zip 03:02:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:02:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/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 03:02:53 INFO - Downloaded 14403333 bytes. 03:02:53 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'] 03:02:53 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 03:02:53 INFO - Return code: 0 03:02:53 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')]} 03:02:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg 03:02:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:02:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:02:55 INFO - Downloaded 112419469 bytes. 03:02:55 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg 03:02:55 INFO - mkdir: /builds/slave/test/properties 03:02:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:02:55 INFO - Writing to file /builds/slave/test/properties/build_url 03:02:55 INFO - Contents: 03:02:55 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg 03:02:55 INFO - Running post-action listener: _resource_record_post_action 03:02:55 INFO - Running post-action listener: set_extra_try_arguments 03:02:55 INFO - ##### 03:02:55 INFO - ##### Finished download-and-extract step (success) 03:02:55 INFO - ##### 03:02:55 INFO - ##### 03:02:55 INFO - ##### Running populate-webroot step. 03:02:55 INFO - ##### 03:02:55 INFO - Running pre-action listener: _resource_record_pre_action 03:02:55 INFO - Running main action method: populate_webroot 03:02:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:02:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:02:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:02:55 INFO - '--tppagecycles', 03:02:55 INFO - '7'], 03:02:55 INFO - 'tests': ['tsvgm']}}, 03:02:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:02:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:02:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:02:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:02:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['damp', 'tps']}, 03:02:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'tests': ['damp', 'tps']}, 03:02:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['dromaeo_dom']}, 03:02:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:02:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['basic_compositor_video']}, 03:02:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:02:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['a11yr', 03:02:55 INFO - 'ts_paint', 03:02:55 INFO - 'tpaint', 03:02:55 INFO - 'sessionrestore', 03:02:55 INFO - 'sessionrestore_no_auto_restore', 03:02:55 INFO - 'tabpaint']}, 03:02:55 INFO - 'other-e10s': {'tests': ['a11yr', 03:02:55 INFO - 'ts_paint', 03:02:55 INFO - 'tpaint', 03:02:55 INFO - 'sessionrestore', 03:02:55 INFO - 'sessionrestore_no_auto_restore', 03:02:55 INFO - 'tabpaint']}, 03:02:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:02:55 INFO - 'ts_paint', 03:02:55 INFO - 'tpaint', 03:02:55 INFO - 'sessionrestore', 03:02:55 INFO - 'sessionrestore_no_auto_restore', 03:02:55 INFO - 'tabpaint']}, 03:02:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:02:55 INFO - 'ts_paint', 03:02:55 INFO - 'tpaint', 03:02:55 INFO - 'sessionrestore', 03:02:55 INFO - 'sessionrestore_no_auto_restore', 03:02:55 INFO - 'tabpaint']}, 03:02:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['a11yr', 03:02:55 INFO - 'ts_paint', 03:02:55 INFO - 'tpaint', 03:02:55 INFO - 'sessionrestore', 03:02:55 INFO - 'sessionrestore_no_auto_restore', 03:02:55 INFO - 'tabpaint']}, 03:02:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['a11yr', 03:02:55 INFO - 'ts_paint', 03:02:55 INFO - 'tpaint', 03:02:55 INFO - 'sessionrestore', 03:02:55 INFO - 'sessionrestore_no_auto_restore', 03:02:55 INFO - 'tabpaint']}, 03:02:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['tsvgx', 03:02:55 INFO - 'tsvgr_opacity', 03:02:55 INFO - 'tart', 03:02:55 INFO - 'tscrollx', 03:02:55 INFO - 'cart']}, 03:02:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:02:55 INFO - 'tsvgr_opacity', 03:02:55 INFO - 'tart', 03:02:55 INFO - 'tscrollx', 03:02:55 INFO - 'cart']}, 03:02:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'talos_options': ['--disable-e10s'], 03:02:55 INFO - 'tests': ['tp5o']}, 03:02:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'tests': ['tp5o']}, 03:02:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'talos_options': ['--disable-e10s', 03:02:55 INFO - '--xperf_path', 03:02:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:55 INFO - 'tests': ['tp5n']}, 03:02:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:02:55 INFO - 'talos_options': ['--xperf_path', 03:02:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:55 INFO - 'tests': ['tp5n']}}, 03:02:55 INFO - 'talos.zip': {'path': '', 03:02:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:02:55 INFO - Running post-action listener: _resource_record_post_action 03:02:55 INFO - ##### 03:02:55 INFO - ##### Finished populate-webroot step (success) 03:02:55 INFO - ##### 03:02:55 INFO - ##### 03:02:55 INFO - ##### Running create-virtualenv step. 03:02:55 INFO - ##### 03:02:55 INFO - Running pre-action listener: _resource_record_pre_action 03:02:55 INFO - Running main action method: create_virtualenv 03:02:55 INFO - Creating virtualenv /builds/slave/test/build/venv 03:02:55 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 03:02:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:02:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:02:55 INFO - Using real prefix '/tools/python27' 03:02:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:02:56 INFO - Installing distribute.............................................................................................................................................................................................done. 03:02:59 INFO - Installing pip.................done. 03:02:59 INFO - Return code: 0 03:02:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:02:59 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')]} 03:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:59 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')]} 03:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:59 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:02:59 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 03:02:59 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 03:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:59 INFO - 'HOME': '/Users/cltbld', 03:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:59 INFO - 'LOGNAME': 'cltbld', 03:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:59 INFO - 'MOZ_NO_REMOTE': '1', 03:02:59 INFO - 'NO_EM_RESTART': '1', 03:02:59 INFO - 'PAGER': '/bin/cat', 03:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:59 INFO - 'PWD': '/builds/slave/test', 03:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:59 INFO - 'SHELL': '/bin/bash', 03:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:02:59 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:59 INFO - 'USER': 'cltbld', 03:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:59 INFO - 'XPC_FLAGS': '0x0', 03:02:59 INFO - 'XPC_SERVICE_NAME': '0', 03:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:59 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 03:02:59 INFO - Cleaning up... 03:02:59 INFO - Return code: 0 03:02:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:02:59 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')]} 03:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:59 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')]} 03:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:59 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:02:59 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 03:02:59 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 03:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:59 INFO - 'HOME': '/Users/cltbld', 03:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:59 INFO - 'LOGNAME': 'cltbld', 03:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:59 INFO - 'MOZ_NO_REMOTE': '1', 03:02:59 INFO - 'NO_EM_RESTART': '1', 03:02:59 INFO - 'PAGER': '/bin/cat', 03:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:59 INFO - 'PWD': '/builds/slave/test', 03:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:59 INFO - 'SHELL': '/bin/bash', 03:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:02:59 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:59 INFO - 'USER': 'cltbld', 03:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:59 INFO - 'XPC_FLAGS': '0x0', 03:02:59 INFO - 'XPC_SERVICE_NAME': '0', 03:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:59 INFO - Downloading/unpacking psutil>=3.1.1 03:02:59 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 03:02:59 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 03:02:59 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 03:02:59 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 03:02:59 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 03:02:59 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 03:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:03:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:02 INFO - Installing collected packages: psutil 03:03:02 INFO - Running setup.py install for psutil 03:03:02 INFO - building 'psutil._psutil_osx' extension 03:03:02 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 03:03:02 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 03:03:02 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 03:03:03 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 03:03:03 INFO - building 'psutil._psutil_posix' extension 03:03:03 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 03:03:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:03:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:03:03 INFO - ^ 03:03:03 INFO - 1 warning generated. 03:03:03 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 03:03:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:03 INFO - Successfully installed psutil 03:03:03 INFO - Cleaning up... 03:03:03 INFO - Return code: 0 03:03:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:03:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:03 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:03:03 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 03:03:03 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 03:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:03 INFO - 'HOME': '/Users/cltbld', 03:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:03 INFO - 'LOGNAME': 'cltbld', 03:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:03 INFO - 'MOZ_NO_REMOTE': '1', 03:03:03 INFO - 'NO_EM_RESTART': '1', 03:03:03 INFO - 'PAGER': '/bin/cat', 03:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:03 INFO - 'PWD': '/builds/slave/test', 03:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:03 INFO - 'SHELL': '/bin/bash', 03:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:03:03 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:03 INFO - 'USER': 'cltbld', 03:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:03 INFO - 'XPC_FLAGS': '0x0', 03:03:03 INFO - 'XPC_SERVICE_NAME': '0', 03:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:03:06 INFO - Installing collected packages: mozsystemmonitor 03:03:06 INFO - Running setup.py install for mozsystemmonitor 03:03:06 INFO - Successfully installed mozsystemmonitor 03:03:06 INFO - Cleaning up... 03:03:06 INFO - Return code: 0 03:03:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:03:06 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')]} 03:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:06 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')]} 03:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:06 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:03:06 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 03:03:06 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 03:03:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:03:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:06 INFO - 'HOME': '/Users/cltbld', 03:03:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:06 INFO - 'LOGNAME': 'cltbld', 03:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:06 INFO - 'MOZ_NO_REMOTE': '1', 03:03:06 INFO - 'NO_EM_RESTART': '1', 03:03:06 INFO - 'PAGER': '/bin/cat', 03:03:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:06 INFO - 'PWD': '/builds/slave/test', 03:03:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:06 INFO - 'SHELL': '/bin/bash', 03:03:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:03:06 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:03:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:06 INFO - 'USER': 'cltbld', 03:03:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:06 INFO - 'XPC_FLAGS': '0x0', 03:03:06 INFO - 'XPC_SERVICE_NAME': '0', 03:03:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:06 INFO - Downloading/unpacking jsonschema==2.5.1 03:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:03:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:09 INFO - Downloading functools32-3.2.3-2.tar.gz 03:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:03:09 INFO - warning: no files found matching '*.txt' 03:03:09 INFO - no previously-included directories found matching 'build' 03:03:09 INFO - no previously-included directories found matching 'dist' 03:03:09 INFO - no previously-included directories found matching '.git*' 03:03:09 INFO - Installing collected packages: jsonschema, functools32 03:03:09 INFO - Running setup.py install for functools32 03:03:09 INFO - warning: no files found matching '*.txt' 03:03:09 INFO - no previously-included directories found matching 'build' 03:03:09 INFO - no previously-included directories found matching 'dist' 03:03:09 INFO - no previously-included directories found matching '.git*' 03:03:09 INFO - Successfully installed jsonschema functools32 03:03:09 INFO - Cleaning up... 03:03:09 INFO - Return code: 0 03:03:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:03:09 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')]} 03:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:09 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')]} 03:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:09 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:03:09 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 03:03:09 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 03:03:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:03:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:09 INFO - 'HOME': '/Users/cltbld', 03:03:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:09 INFO - 'LOGNAME': 'cltbld', 03:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:09 INFO - 'MOZ_NO_REMOTE': '1', 03:03:09 INFO - 'NO_EM_RESTART': '1', 03:03:09 INFO - 'PAGER': '/bin/cat', 03:03:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:09 INFO - 'PWD': '/builds/slave/test', 03:03:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:09 INFO - 'SHELL': '/bin/bash', 03:03:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:03:09 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:03:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:09 INFO - 'USER': 'cltbld', 03:03:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:09 INFO - 'XPC_FLAGS': '0x0', 03:03:09 INFO - 'XPC_SERVICE_NAME': '0', 03:03:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:10 INFO - Downloading/unpacking blobuploader==1.2.4 03:03:10 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 03:03:10 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 03:03:10 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 03:03:10 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 03:03:10 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 03:03:10 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 03:03:12 INFO - Downloading blobuploader-1.2.4.tar.gz 03:03:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:03:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:03:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:03:13 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 03:03:13 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 03:03:13 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 03:03:13 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 03:03:13 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 03:03:13 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 03:03:13 INFO - Downloading docopt-0.6.1.tar.gz 03:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:03:13 INFO - Installing collected packages: blobuploader, requests, docopt 03:03:13 INFO - Running setup.py install for blobuploader 03:03:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:03:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:03:13 INFO - Running setup.py install for requests 03:03:14 INFO - Running setup.py install for docopt 03:03:14 INFO - Successfully installed blobuploader requests docopt 03:03:14 INFO - Cleaning up... 03:03:14 INFO - Return code: 0 03:03:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:14 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')]} 03:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:14 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')]} 03:03:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:14 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:03:14 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 03:03:14 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 03:03:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:03:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:14 INFO - 'HOME': '/Users/cltbld', 03:03:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:14 INFO - 'LOGNAME': 'cltbld', 03:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:14 INFO - 'MOZ_NO_REMOTE': '1', 03:03:14 INFO - 'NO_EM_RESTART': '1', 03:03:14 INFO - 'PAGER': '/bin/cat', 03:03:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:14 INFO - 'PWD': '/builds/slave/test', 03:03:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:14 INFO - 'SHELL': '/bin/bash', 03:03:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:03:14 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:03:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:14 INFO - 'USER': 'cltbld', 03:03:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:14 INFO - 'XPC_FLAGS': '0x0', 03:03:14 INFO - 'XPC_SERVICE_NAME': '0', 03:03:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-kpwA1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-pVmYxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-gYyePX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-DEdHtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-3kWh5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-n8wJFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Jb_4si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-0WcYYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-rJJ8P5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-biISjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:16 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-jxCrGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:16 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-gkQrZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:16 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-jYlL8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:16 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-oMKiKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:16 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-3vvT4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:16 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-ofZgWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:16 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-eCmyTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:03:16 INFO - Running setup.py install for manifestparser 03:03:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:03:16 INFO - Running setup.py install for mozcrash 03:03:17 INFO - Running setup.py install for mozdebug 03:03:17 INFO - Running setup.py install for mozdevice 03:03:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Running setup.py install for mozfile 03:03:17 INFO - Running setup.py install for mozhttpd 03:03:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Running setup.py install for mozinfo 03:03:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Running setup.py install for mozInstall 03:03:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:03:17 INFO - Running setup.py install for mozleak 03:03:18 INFO - Running setup.py install for mozlog 03:03:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:03:18 INFO - Running setup.py install for moznetwork 03:03:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:03:18 INFO - Running setup.py install for mozprocess 03:03:18 INFO - Running setup.py install for mozprofile 03:03:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:03:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:03:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:03:18 INFO - Running setup.py install for mozrunner 03:03:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:03:18 INFO - Running setup.py install for mozscreenshot 03:03:19 INFO - Running setup.py install for moztest 03:03:19 INFO - Running setup.py install for mozversion 03:03:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:03:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:03:19 INFO - Cleaning up... 03:03:19 INFO - Return code: 0 03:03:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:19 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:03:19 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 03:03:19 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 03:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:19 INFO - 'HOME': '/Users/cltbld', 03:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:19 INFO - 'LOGNAME': 'cltbld', 03:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:19 INFO - 'MOZ_NO_REMOTE': '1', 03:03:19 INFO - 'NO_EM_RESTART': '1', 03:03:19 INFO - 'PAGER': '/bin/cat', 03:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:19 INFO - 'PWD': '/builds/slave/test', 03:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:19 INFO - 'SHELL': '/bin/bash', 03:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:03:19 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:19 INFO - 'USER': 'cltbld', 03:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:19 INFO - 'XPC_FLAGS': '0x0', 03:03:19 INFO - 'XPC_SERVICE_NAME': '0', 03:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-P25ojq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:19 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)) 03:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-L4OPU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:19 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)) 03:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-_X4JS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:19 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)) 03:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-WBmRwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-PmRO0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-iTNtI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-4MTG6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-pXl43Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-wD3IpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-ANGoa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-aeCedn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:20 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)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Ly7Ha6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:20 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-aKnYEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:21 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)) 03:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:21 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-1g4Qe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:21 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-BZ5Wpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:21 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)) 03:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:21 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-z2qYUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:21 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)) 03:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:21 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-SLuKYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:21 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)) 03:03:21 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)) 03:03:21 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)) 03:03:21 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)) 03:03:21 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)) 03:03:21 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)) 03:03:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:03:21 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 03:03:21 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 03:03:21 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 03:03:21 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 03:03:21 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 03:03:21 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 03:03:23 INFO - Downloading blessings-1.6.tar.gz 03:03:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:03:23 INFO - Installing collected packages: blessings 03:03:23 INFO - Running setup.py install for blessings 03:03:24 INFO - Successfully installed blessings 03:03:24 INFO - Cleaning up... 03:03:24 INFO - Return code: 0 03:03:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:24 INFO - Reading from file tmpfile_stdout 03:03:24 INFO - Current package versions: 03:03:24 INFO - blessings == 1.6 03:03:24 INFO - blobuploader == 1.2.4 03:03:24 INFO - docopt == 0.6.1 03:03:24 INFO - functools32 == 3.2.3-2 03:03:24 INFO - jsonschema == 2.5.1 03:03:24 INFO - manifestparser == 1.1 03:03:24 INFO - mozInstall == 1.12 03:03:24 INFO - mozcrash == 0.17 03:03:24 INFO - mozdebug == 0.1 03:03:24 INFO - mozdevice == 0.48 03:03:24 INFO - mozfile == 1.2 03:03:24 INFO - mozhttpd == 0.7 03:03:24 INFO - mozinfo == 0.9 03:03:24 INFO - mozleak == 0.1 03:03:24 INFO - mozlog == 3.2 03:03:24 INFO - moznetwork == 0.27 03:03:24 INFO - mozprocess == 0.22 03:03:24 INFO - mozprofile == 0.28 03:03:24 INFO - mozrunner == 6.11 03:03:24 INFO - mozscreenshot == 0.1 03:03:24 INFO - mozsystemmonitor == 0.3 03:03:24 INFO - moztest == 0.7 03:03:24 INFO - mozversion == 1.4 03:03:24 INFO - psutil == 3.1.1 03:03:24 INFO - requests == 1.2.3 03:03:24 INFO - wsgiref == 0.1.2 03:03:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:24 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')]} 03:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:24 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')]} 03:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:24 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:03:24 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 03:03:24 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 03:03:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:03:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:24 INFO - 'HOME': '/Users/cltbld', 03:03:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:24 INFO - 'LOGNAME': 'cltbld', 03:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:24 INFO - 'MOZ_NO_REMOTE': '1', 03:03:24 INFO - 'NO_EM_RESTART': '1', 03:03:24 INFO - 'PAGER': '/bin/cat', 03:03:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:24 INFO - 'PWD': '/builds/slave/test', 03:03:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:24 INFO - 'SHELL': '/bin/bash', 03:03:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:03:24 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:03:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:24 INFO - 'USER': 'cltbld', 03:03:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:24 INFO - 'XPC_FLAGS': '0x0', 03:03:24 INFO - 'XPC_SERVICE_NAME': '0', 03:03:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 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)) 03:03:24 INFO - Cleaning up... 03:03:24 INFO - Return code: 0 03:03:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:03:24 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')]} 03:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:24 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')]} 03:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:24 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 0x10e221a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849f2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, '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.x1H2zVQdXC/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.93JSvAatgr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 03:03:24 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 03:03:24 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 03:03:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:03:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:24 INFO - 'HOME': '/Users/cltbld', 03:03:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:24 INFO - 'LOGNAME': 'cltbld', 03:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:24 INFO - 'MOZ_NO_REMOTE': '1', 03:03:24 INFO - 'NO_EM_RESTART': '1', 03:03:24 INFO - 'PAGER': '/bin/cat', 03:03:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:24 INFO - 'PWD': '/builds/slave/test', 03:03:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:24 INFO - 'SHELL': '/bin/bash', 03:03:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:03:24 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:03:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:24 INFO - 'USER': 'cltbld', 03:03:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:24 INFO - 'XPC_FLAGS': '0x0', 03:03:24 INFO - 'XPC_SERVICE_NAME': '0', 03:03:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:03:25 INFO - Cleaning up... 03:03:25 INFO - Return code: 0 03:03:25 INFO - Running post-action listener: _resource_record_post_action 03:03:25 INFO - Running post-action listener: _start_resource_monitoring 03:03:25 INFO - Starting resource monitoring. 03:03:25 INFO - ##### 03:03:25 INFO - ##### Finished create-virtualenv step (success) 03:03:25 INFO - ##### 03:03:25 INFO - ##### 03:03:25 INFO - ##### Running install step. 03:03:25 INFO - ##### 03:03:25 INFO - Running pre-action listener: _resource_record_pre_action 03:03:25 INFO - Running main action method: install 03:03:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:25 INFO - Reading from file tmpfile_stdout 03:03:25 INFO - Detecting whether we're running mozinstall >=1.0... 03:03:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:03:25 INFO - Reading from file tmpfile_stdout 03:03:25 INFO - Output received: 03:03:25 INFO - Usage: mozinstall [options] installer 03:03:25 INFO - Options: 03:03:25 INFO - -h, --help show this help message and exit 03:03:25 INFO - -d DEST, --destination=DEST 03:03:25 INFO - Directory to install application into. [default: 03:03:25 INFO - "/builds/slave/test"] 03:03:25 INFO - --app=APP Application being installed. [default: firefox] 03:03:25 INFO - mkdir: /builds/slave/test/build/application 03:03:25 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'] 03:03:25 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 03:03:59 INFO - Reading from file tmpfile_stdout 03:03:59 INFO - Output received: 03:03:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:03:59 INFO - Running post-action listener: _resource_record_post_action 03:03:59 INFO - ##### 03:03:59 INFO - ##### Finished install step (success) 03:03:59 INFO - ##### 03:03:59 INFO - ##### 03:03:59 INFO - ##### Running run-tests step. 03:03:59 INFO - ##### 03:03:59 INFO - Running pre-action listener: _resource_record_pre_action 03:03:59 INFO - Running main action method: run_tests 03:03:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:03:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:03:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:03:59 INFO - 2016-07-27 03:03:59.827 screenresolution[1911:13065] starting screenresolution argv=screenresolution get 03:03:59 INFO - 2016-07-27 03:03:59.830 screenresolution[1911:13065] Display 0: 1600x1200x32@60 03:03:59 INFO - 2016-07-27 03:03:59.836 screenresolution[1912:13067] starting screenresolution argv=screenresolution list 03:03:59 INFO - Available Modes on Display 0 03:03:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:03:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:03:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:03:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:04:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:04:00 INFO - Intel Iris: 03:04:00 INFO - Chipset Model: Intel Iris 03:04:00 INFO - Type: GPU 03:04:00 INFO - Bus: Built-In 03:04:00 INFO - VRAM (Dynamic, Max): 1536 MB 03:04:00 INFO - Vendor: Intel (0x8086) 03:04:00 INFO - Device ID: 0x0a2e 03:04:00 INFO - Revision ID: 0x0009 03:04:00 INFO - Displays: 03:04:00 INFO - Display: 03:04:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:04:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:04:00 INFO - Display Serial Number: 1600x1200 60 03:04:00 INFO - Main Display: Yes 03:04:00 INFO - Mirror: Off 03:04:00 INFO - Online: Yes 03:04:00 INFO - Rotation: Supported 03:04:00 INFO - Return code: 0 03:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:04:00 INFO - Python 2.7.3 03:04:00 INFO - Return code: 0 03:04:00 INFO - grabbing minidump binary from tooltool 03:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:00 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 0x10e185d30>, '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 0x10deeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:04:00 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 03:04:00 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 03:04:00 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 03:04:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:04:00 INFO - Return code: 0 03:04:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:04:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:04:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:04:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:04:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1H2zVQdXC/Render', 03:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:00 INFO - 'HOME': '/Users/cltbld', 03:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:00 INFO - 'LOGNAME': 'cltbld', 03:04:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:04:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:00 INFO - 'MOZ_NO_REMOTE': '1', 03:04:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:04:00 INFO - 'NO_EM_RESTART': '1', 03:04:00 INFO - 'PAGER': '/bin/cat', 03:04:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:00 INFO - 'PWD': '/builds/slave/test', 03:04:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:00 INFO - 'SHELL': '/bin/bash', 03:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.93JSvAatgr/Listeners', 03:04:00 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 03:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:00 INFO - 'USER': 'cltbld', 03:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:00 INFO - 'XPC_FLAGS': '0x0', 03:04:00 INFO - 'XPC_SERVICE_NAME': '0', 03:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:04:00 INFO - mozversion application_buildid: 20160704144857 03:04:00 INFO - mozversion application_changeset: 0842107a80e7b434b964922bfb5b4b3f88e8eec2 03:04:00 INFO - mozversion application_display_name: Nightly 03:04:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:04:00 INFO - mozversion application_name: Firefox 03:04:00 INFO - mozversion application_remotingname: firefox 03:04:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:04:00 INFO - mozversion application_vendor: Mozilla 03:04:00 INFO - mozversion application_version: 50.0a1 03:04:00 INFO - mozversion platform_buildid: 20160704144857 03:04:00 INFO - mozversion platform_changeset: 0842107a80e7b434b964922bfb5b4b3f88e8eec2 03:04:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:04:00 INFO - mozversion platform_version: 50.0a1 03:04:00 INFO - using testdate: 1469613840 03:04:00 INFO - actual date: 1469613840 03:04:00 INFO - starting webserver on 'localhost:51521' 03:04:00 INFO - SUITE-START | Running 2 tests 03:04:00 INFO - TEST-START | dromaeo_css 03:04:00 INFO - operating with platform_type : mac_ 03:04:00 INFO - Initialising browser for dromaeo_css test... 03:04:00 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpcfOLbJ/profile http://localhost:51521/getInfo.html) 03:04:03 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 03:04:03 INFO - PROCESS | 1922 | colorDepth:24 03:04:03 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 03:04:03 INFO - PROCESS | 1922 | __metrics 03:04:03 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 03:04:04 INFO - TEST-INFO | 1922: exit 0 03:04:04 INFO - Browser initialized. 03:04:04 INFO - Running cycle 1/1 for dromaeo_css test... 03:04:04 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpcfOLbJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:16 INFO - PROCESS | 1925 | RSS: Main: 256061440 03:04:16 INFO - PROCESS | 1925 | 03:05:06 INFO - PROCESS | 1925 | 1469613906166 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] 03:07:17 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51521/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51521/tests/dromaeo/cssquery-ext.html) 03:07:18 INFO - PROCESS | 1925 | RSS: Main: 268505088 03:07:18 INFO - PROCESS | 1925 | 03:10:21 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51521/tests/dromaeo/cssquery-ext.html (next: http://localhost:51521/tests/dromaeo/cssquery-jquery.html) 03:10:21 INFO - PROCESS | 1925 | RSS: Main: 277434368 03:10:21 INFO - PROCESS | 1925 | 03:13:23 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51521/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51521/tests/dromaeo/cssquery-mootools.html) 03:13:24 INFO - PROCESS | 1925 | RSS: Main: 282017792 03:13:24 INFO - PROCESS | 1925 | 03:16:26 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51521/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51521/tests/dromaeo/cssquery-prototype.html) 03:16:26 INFO - PROCESS | 1925 | RSS: Main: 281419776 03:16:26 INFO - PROCESS | 1925 | 03:19:29 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51521/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51521/tests/dromaeo/cssquery-yui.html) 03:19:30 INFO - PROCESS | 1925 | RSS: Main: 284790784 03:19:30 INFO - PROCESS | 1925 | 03:22:32 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51521/tests/dromaeo/cssquery-yui.html (next: http://localhost:51521/tests/dromaeo/cssquery-dojo.html) 03:22:32 INFO - PROCESS | 1925 | RSS: Main: 284884992 03:22:32 INFO - PROCESS | 1925 | 03:22:32 INFO - PROCESS | 1925 | __start_tp_report 03:22:32 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 03:22:32 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 03:22:32 INFO - PROCESS | 1925 | |i|pagename|runs| 03:22:32 INFO - PROCESS | 1925 | |0;dojo.html;3355;3408.301298233861;3424.0412684448365;3434.484827275908;3467.653234676532;5136.563392111675;5248.530411484786;5814.302283725953;5853.297604287481;5904.409559044098;4677;5757.481826635602;5759.452851979056;5926.733297001634;5950.821475355746;5307.973460132704;5352.759125839347;5365.7853685852515;5430.619856610039;5480;5386.111291636876;5452.454754524555;5513.558915286788;5515.448455154493;5557.472040156179;5255.763490642892;5345.198220266952;5367.46325367464;5406.216098665691;5420.566354691615;5393.83818485446;5399.082156033483;5428.61999660024;5433.945660543423;5435.1847222916485;5282.260483532308;5345.705986170759;5381.004514164892;5383.973080134613;5387.434319396485;5759.366469688331;5826.300843898717;5892.528597712173;5930.584859059867;6013;6223.408776166257;6255.186825712673;6266.373362663697;6271.498280137624;6296.433321001132;6183.628982261079;6191.2260967378625;6289.622622642656;6300.054991751228;6301.369863013662;5912.054071348563;5993.9700301498215;6003.429674180946;6003.8198854034445;6038.667873266969;6403.615783053032;6460.353964603596;6463.547551671432;6466.967665161644;6483.027545868157;6102.337773232528;6187.226596675416;6198.287196972406;6274.058891166362;6274.717637706314;6239.656818875005;6277.686115694197;6290.24517057956;6304.873902521924;6309.337519560472;6124.808163753463;6131.101423540838;6253.593516421509;6266.436020758154;6287.245530536365;6154.507639388928;6183.81448556544;6197.690115494207;6246.968765156145;6288.088227207121;4212.599803018708;5060.2156665716875;5079.08576456234;5104;5112.054269960069;4490.753008584559;4497.190505709003;4532.4334458192725;4552.385427967182;4581.518940511265;4762.261849413347;5179.637425380187;5617.41017193197;5957.970210148921;5985.850353741104;6238.937610623926;6272.341404152498;6285.340039295808;6299.96850015747;6371.980483122678;6009.489193418608;6054.182685337423;6070;6090.52843852597;6109.75560977556;5477.75350109246;5525.751341189656;5565.526930210896;5574.777008919679;5583.664980101125;6489.415952564386;6516.609003459807;6530.47756179495;6542.672866356568;6591.736330546821;6148.9692551536955;6329.715162817776;6329.715162817776;6351.68241587928;6356.841078973062;5949.434803693643;6444.967775161094;6503.934960650333;6538.215414150332;6544.3782840630065;6193.566450348432;6214.937850621436;6231.9688401559515;6268.435840774352;6282.680637066267;6145.647957449354;6218.62688238689;6324.6521441321165;6324.810255692337;6343.682815859281;6128.142060111677;6129.939558357133;6135.906433916272;6190.969045154746;6224.564280500322;5081.23781432788;5837.474627283565;6249.625022498664;6263.3736626337;6270.874582508233;5401.540869026177;5942.227510423617;6100.877982440237;6102.993006153935;6107.328193898757;4445.355423463644;6101.298350689727;6205.720742566505;6217.4715149211015;6262.906056409066;6143.6928153593035;6143.877122457615;6149.169862068564;6185.288691800501;6197.008478643395 03:22:32 INFO - PROCESS | 1925 | |1;ext.html;481.41027241628996;500.0424187680592;505.95772708221057;526.9855528108391;571.6998575747732;30872;38334.808325958365;39724.60275397245;39797.20405591886;40032.1993560129;41474;42134.36798448026;42252.943676408104;42255.73232803013;42267;9252.537373131334;9437.433753974783;9488.28837837165;9518.381305215173;9568.33021688482;8700.869486957701;9585.80828383432;9660.082292182231;9697.660581879636;9720.95139524305;9683.661071862487;9735.415875047483;9738.220942324633;9741.756456088584;9747.902520974805;9633.75915602112;9706.417614943126;9729.367591106591;9741.512924353754;9795.951020244853;9660.75848103803;9685.854712179324;9766.462844543546;9779.168770654504;9799.020097990215;39890;40113;40141.19717605661;40165.59834401648;40306.395404068964;2588.741125887415;3371.831408429569;3396.1679388549965;3627.075095850568;3907.882763517099;31084.533731994037;31353.37293254122;31700;31850.840745796355;32082.679173208202;3167.224030112614;3174.4762114251353;3177.729892959101;3195.6484786673445;3296.901092967191;382715.0864245689;407489;410167.9491602523;412396;413727;33945.8118965835;34385;34398.65601344004;34535.4819677705;34564;35517.46723799145;35524.46713299303;35755;36024.819875900976;36115.458268126;14919.179445130405;15151.621209469851;15218.619534511727;15219.695606087816;15220.01069930451;12582;12613.369331533386;12952.093353465356;13016.804747928789;13023.47906083746;6350.682465876687;7492.213317601682;7664.885026724603;7686.9231307686205;7742.61286935651;3217.8873739419478;3259.0385836178384;3262.6900444457738;3263.6083669959758;3275.5577996970583;976.3751199232497;987.3286165407592;1022.7647641042602;1110.134095405585;1111.8610173728284;29679.703202967696;31043;31273.530897036562;31877.840610797262;32020.35959280848;1091.8471414002047;1125.1336470917347;1137.4426530999751;1158.1892675127444;1204.2052245517916;12908.35458227085;15277.694446111016;16132.032078075119;16648.001119932844;16757.971345907063;712558;718200;719811;721580;724758;62041.37958620356;62500.06249906255;63204;63298.417539560956;63356.04965925515;1237.5915947737226;1406.6694326833185;1414.985850141506;1427.336288625794;1434.089353260687;29534.704652953194;30911.2272193197;31209.531857022615;31221;31731.84134079407;45469.54530454653;54711;55132.72433637806;55339.893202134925;55483;597271;599085;604911;605023;605063;27304;27711.861440692668;27719;27873;27984.440311193255;48511;50518;51735;51807;51884.481155189475;41326.1734765309;41781.79109104435;41993.79003104965;42085.36871946984;43115.353269701605;41372.793136034124;41565;41864.790676046425;41999;42148.57851421447;38619.613803861605;39092;40265.194696105325;40475.98810029773;40560.1887962245;34878;36167.457488137174;36831.26337473182;36867.26265474622;36959;26396.208113756617;26486.470270594866;26715.732842671325;27120.32199195036;27174.048908288114 03:22:32 INFO - PROCESS | 1925 | |2;jquery.html;373.95512538495353;386.3914334922497;391.94708714323576;394.19190659148745;414.76773007116014;18039.466645335146;18305;20402.387928362154;21637.567248655017;22777.31668049957;20873.582528349423;21360;21429;21633.675494867588;22115;5424.593155513352;5897.557683173768;6132;6134.570580059375;6152.815415537541;4875.97604503053;4975.4527002029745;6159.969200153993;6172;6256.374362563753;5059.544640982311;5802.564807639423;6160.5687601867885;6163.8150855474405;6203.2245969253845;6132.417420345082;6179.783707570258;6190.938090619081;6395.20059992501;6872;5990.5507086968655;6040.96979515104;6059.151718759377;6072.483838873701;6080.9391906081255;13709.862901371058;13878.514252001232;22762.975666095066;24723.011079556974;26356.60465093025;2239.5856766498086;2458.483718419134;2587.3143616941525;2861.298981749464;2928.569287491377;8266.875996860052;10530.736731581695;10584.682459526226;15328.923355383151;16023.759643605355;1801.4325487471535;1842.631473705251;1879.3328368429284;1918.9232430702755;1922.6731455652432;127371.36314318368;133093;134603;134880;137280;11701.707457313636;11741.178117531861;11815.350155741517;11865.406729663655;12062.215955962834;14835;14858.331375088146;14859.777103343458;14901.403943842342;14985.625359365886;8727.127287271349;8784.165504277084;8789.736307910645;8792.648294068293;8815.338849586307;7172.282771722786;8618.741437756877;8895.73312800617;8913.064128266567;8919.955400223087;4619;4636.79134438951;4690.64820138491;4702.224133018082;4747.098051370229;1953.189426388033;1992.213075835037;1994.4216177308708;1997.530580313625;2000.9499762506055;907.6550910653909;923.2613908872875;944.1361154543516;1017.1710056304237;1076.9030787229187;17390.739138912926;17673.67447441447;18152.63694726098;21381.57236855254;22721.09115635356;966.3622009473713;982.4792859784326;991.9553620087113;1027.337367398024;1059.274397038016;10968.122550195965;10970.945145274223;11247.887521124847;11691.941540292244;11696.70758231051;555377;565645;567320;595567;601039;143147.56852431552;149792.2510387463;150720.2463987695;151444;155631;916.9954150229339;1007.561710655867;1023.4882558720789;1028.9125424339015;1050.5640159333943;19720.80279197247;20111.994400279636;20174;20232.59534809325;20264.18943242313;46461.535384647075;60176.39823601708;61162.082568762395;61787.69106154441;62162;90478.54760726154;91169.54415227882;91178.17643646957;91289;91504;6979.1973922999505;7067.964660176666;7657.923420765721;7775.650095745818;8403.705870294754;11315.886841131483;11585.884141158818;13512.79730804019;13886.93056534711;13947.232902190477;11671.299722016705;11704.648860534198;11799.823002654795;11984.520619175135;12009.819852702041;11410.657680269605;11741.178117532032;11747.353895535485;11754.177207595387;11863.940680296544;11811.995980341768;11951.98043277524;11968.16222864391;11991.160618756952;12021.218620789621;6960.408365288805;7646.8088297793;7684.6926122954455;7701;7831.177726338652;9867.605295788087;11935.343556104497;11974.46114924813;11984.880151198377;12062.914337709648 03:22:32 INFO - PROCESS | 1925 | |3;mootools.html;1496.580957331947;1561.0321600607622;1762.162972588021;1817.4547635709284;1839.751633529473;8039.678412863478;8753.43102698325;8832.735017949455;9138.680146194876;9159.587818548165;8774.605142768574;8788.560571971391;8933.508657023862;8954.552272386387;8966.506842123681;2349.3774149850306;2370.0993622423457;2372.074890792592;2373.6439534069855;2378.4886249456363;2363.858168509886;2370.1704403458825;2377.9762202378015;2390.8924098415614;2412.7104747430335;2369.4194922244073;2377.41753270449;2392.3899405651537;2394.4971555973266;2400.1119585753295;2347.6713260143424;2362.70466191726;2377.0270675339425;2381.666566680666;2384.2727967969763;2342.8125749939963;2345.64815277708;2353.6234202527685;2368.6328619064075;2376.916807911715;8027.638756255982;9107.089291070906;9110.86333704995;9116.908830911738;9125.726228213165;5268.078086334876;5358.4641535846495;5400.891982160335;5418.647787893774;5448;3608.3685355062757;3641.908952276214;3705.3330400527893;3707.0917625181733;3714.3685573452576;1067.5356220044305;1084.8500589375208;1089.4988305379534;1092.7104317355906;1108.933463992163;11542.769144617061;11653.417329133594;11661.242019268806;11690.240134391322;11703.765924681458;4622.514635963243;4647.814087436464;4840.903181936342;4872.049950259665;4873.561379475864;9829.361091529028;9830.95084524587;9857.75355616101;9862.950685246671;9864.260180486492;5331;5398.676079435168;5408.4050754416985;5408.621396502286;5414.3232095988005;4482.462104547475;4821.831235906727;4937.1113199624415;4998.075356059141;5006;3287.8027318360973;3325.3515564464697;4091.57038510958;4105.527864295585;4164.542410156421;1298.1626850681257;1301.7917133258634;1311.2132720367704;1320.973580528384;1322.7883538633773;802.0736049862302;827.3215962910476;827.995860020708;948.1087777489138;977.7897751983357;5941.287045554474;6090.177825993434;6176.672015516686;6202.81391558254;6346;955.2358113509165;977.3012296208245;986.4574484033752;1005.7737009172878;1007.9114556279201;3638.1268495560876;3977.582353852804;4205.936910946339;4218.367244913287;4226.76752778594;10701.89298107009;10808.919108089285;10847.620333288298;11095.334279943228;11102.167337449724;11015.559377624806;11045.889541104647;11054.336739795477;11054.668359949215;11076.169287303484;7946.801329966682;8210.794730131674;8468.44955077918;8528.01927778301;8569.95715021421;5943.673097979653;6219.284782250099;6231.22109736283;6302.873942521215;6425.325340839145;10032.197424206191;10157.492125393554;10350.120239779402;10412.531436085546;10440.060394564525;10215.131713804112;10461.163106951577;10464.581416743245;10537.683869483928;10571.04860562553;7721.266479684421;7809.804754881174;7839.216078392115;7869.213078692085;7976.6410511525955;8457.281131103678;8515.233628973423;8590.570471476276;8593.226609605166;8608.311335093304;7487.513311634726;7699.114601820638;7734.110577283459;7767.3786097113225;7774.805629859299;7486.213947535431;7565.130010048695;7573.507721998052;7580.810479738051;7725.304722574995;7192.31672991065;7234.891476627749;7243.348098671145;7266.346028857428;7269.200387957426;5037.546620804145;5176.689398636094;5186.58507319406;5218.817341393033;5247.317848679647;4612.953870461252;4841.927371089366;5181.715005674573;5235.528802407802;5335.573154147582 03:22:32 INFO - PROCESS | 1925 | |4;prototype.html;270.28913956294946;319.19244311890907;320.54642680606935;321.9420504309225;325.93644239373333;5456.781728730851;5603.299587551561;5791.333996590388;5814.796482123126;5831.504322132613;5263.526282634562;5716.514096301819;5760.855978600537;5774.942250577493;5839.299284085916;4197.244495990721;4251.702380833343;4364.607185353318;4376.7811609419405;4398.252297109483;4194.328907374821;4325.178216138939;4355.237833379146;4390.714603550759;4417.27115026022;3714.591394946554;4346.891542656618;4364.258076127066;4376.715513491628;4409.625181859546;3486.825658717054;3839.86560470385;4227.787716159806;4346.391505189276;4370.868873933787;2803.5934789455414;3210.3739770744714;3398.898033059012;3485.302939412102;3543.9114022149392;5630.296212973379;5659.236003139606;5693.174489699012;5725.341585717612;5757.309122905236;4603.447586289667;4983.053219888209;5005.874853128701;5063.87340316495;5112.309838171874;5002.9499705002845;5920.97039514804;6067.271927368744;6093.542984276197;6106.084087386883;4066.369712694537;4083.4283200351974;4088.2436749201197;4097.774622395767;4124.9587504124875;5594.58040646949;6090.390960903918;6366.554341196072;6446.387593178641;6476.060971159202;4110.116324990082;4135.731177473454;4136.503619565671;4155.148194620096;4171.666266698657;3206.7915585487335;4220.873373798791;4235.597618226325;4244.87265382039;4283.614474697292;4131.698514967836;4736.668433209642;4976.850694479172;4984.934265426737;5015.699058056528;5378.327709036371;6085.208922840003;6119.44924956756;6146.200993870768;6146.631202127886;1349.7637913365122;1418.5318844781198;1433.5699290212895;1437.8705916467568;1441.243347242706;862.5687156421789;873.7116751471999;876.5748611923212;878.3280790195505;929.6002718830832;5025.221090730943;5361.115415956401;5555.555555555546;5561.360443548962;5723.742431590588;5442.945570544244;6146.938530614726;6237.157983672237;6249.375062493714;6275.454368281906;4323.589259020388;4860.125177468019;5606.831795046155;5644.407337229614;5681.9431805681415;2679.825811322258;2755.5728862026417;3075.861931085513;3233.4826427771445;3255.9023229303157;5846.736896839652;5961.967619057132;6100.450959413674;6424.486041116793;6519.739210431624;5722;6547.705353259114;6614.239362473281;6616.603003819786;6632.1046658701425;6604.735810567524;6662.6668666566475;6685.866282674319;6692.564983275974;6708.597484150967;6113.908291375544;6381.202349706286;6382.744690212339;6421;6454.322296158835;4881.974785295128;5237.973810130925;5820.534357251325;6374.362563743589;6378.298387177314;5314.388845282842;6340.619562826245;6526.2800203883335;6558.376954189344;6589.275179730322;4133.181400183909;4134.421181034598;4143.295639741312;4157.355609880473;4177.373393990925;4720.433547974265;5688.54491640676;5696.971515142398;6376.39424254695;6468.3855033771715;4549.225801937181;5087;5553.888922221452;6147.84630384244;6151.876962460637;6109.266887973472;6124.897518446723;6165.815025549241;6226.516907826358;6230.719617617127;5788.305403351625;5820.330661973927;5998.790042348497;6049.485793707584;6090.878182436415;5404.973055119514;5522.6134170607675;5867.618604790675;6077.453029227391;6213.440790328892;4936.543719602798;5269.841904742864;5506.421825708243;5679.602427830012;5856.94143058564 03:22:32 INFO - PROCESS | 1925 | |5;yui.html;734.2473964186711;761.2387612387612;779.5244900610627;806.1495122645606;889.3196704521041;15257.771133432994;15422.460213892517;16054.277557509911;16094.517164485054;16144.677106457864;14502.92748536256;14614;14660.193689347081;14715.337809798559;14746.041507302018;711.9715211391538;886.5478605910968;901.6123067081153;912.6988093928987;935.2097477631424;916.9770755731126;920.1994264989469;920.3557509743173;921.345844450441;921.5300196899595;981.7300242433316;984.906433888783;987.733312005758;989.515137582583;989.6387818446251;939.8449255872773;941.2893265584474;942.3215284994793;982.0326977926736;982.2485798364221;722.4040166862308;726.731109489487;726.901868247785;730.7978375572208;731.3710209220067;15177.317020734094;15182.164980926045;15386.692266154614;15477.148756818371;15556.377744890191;14973.775393369107;15039.473618423412;15066;15138.470153544684;15326.616834579114;11967.40162991847;12169.634910952596;12405.379731013414;12408.875911240862;12479.563215287511;3792.3363411402893;3880.6507414332564;4012.7391719538136;4109.732867363612;4165.271077561415;116393.4999475479;116847.83152168455;118362;118475.4076229622;118492;10315.710536182976;10410.063094321547;11288.379139147262;11298;11654.417279136009;16436.1781910904;22860.199893003664;22904.198353057564;23141.652875206884;23390.883045585004;6879.759208427681;7551.244875512405;8482.194191551793;8528.189821966904;8545.444546104483;4505.301678239878;4691.014886873795;4797.472278049412;4830.396200474941;4830.9275360869615;3053.4198502284494;3664.7801131931637;3713.3872910969926;3724.2923844469465;3734.775913445202;683.4771399879096;688.8071340024801;699.5903254941297;705.2312978853075;710.1797423975316;671.771597656799;676.8341756269746;682.2938258902012;720.3013077314996;721.8267615772177;9232.584533696;9574;9597.760055998517;9657.275704322063;9705.320627556004;577.2236342119842;634.4004915355038;634.5938599296454;683.1938312790855;701.7508784381471;4497.752623605733;4703.670743048022;4710.646701497333;4714.622830173578;4727.503612120678;158549;162743.5588466174;163817;166202;166562;138009.3099534516;139843.60156398508;141527;141836.2908185473;142454.86317705244;103755;104230.47884760528;121844;122742.386288068;124855;9694.127528522467;9975.251856110675;10024.849627255742;10127.291089623655;10221.182305415698;65521.68956620936;67294.66352668205;67930;68726.96909546261;70564.64717676378;90099;92002;92992;96018.5199074028;96258;11354.489047992694;13456.394462249027;13532.458701651824;13943.372548235557;13975.440982360999;14102.083364581269;14983.850161498682;15091.245437728288;15294.923525382303;15506.612334691723;13934.163950163023;14082.929585352007;14084.52110033131;14098.859011409755;14117;13755.656108597364;13803.447862085806;13846.792298115335;14177.645558861112;14364.209968451836;13792.862071379559;14012.859871401562;14058.929705351407;14260.928695356457;14466.349014294172;11354;11594;11707;11841.111916606116;11924.701882453008;9081.636734530544;10588.152947764007;11571.197952849474;11740.178187526792;11744.29534227949 03:22:32 INFO - PROCESS | 1925 | __end_tp_report 03:22:32 INFO - PROCESS | 1925 | __start_cc_report 03:22:32 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,440 03:22:32 INFO - PROCESS | 1925 | __end_cc_report 03:22:32 INFO - PROCESS | 1925 | __startTimestamp1469614952982__endTimestamp 03:22:32 INFO - PROCESS | 1925 | 03:22:32 INFO - PROCESS | 1925 | ------- Summary: start ------- 03:22:32 INFO - PROCESS | 1925 | Number of tests: 6 03:22:32 INFO - PROCESS | 1925 | 03:22:32 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:5819.16 Median:6102.34 stddev:664.49 (10.9%) stddev-sans-first:637.79 03:22:32 INFO - PROCESS | 1925 | Values: 3355.0 3408.3 3424.0 3434.5 3467.7 5136.6 5248.5 5814.3 5853.3 5904.4 4677.0 5757.5 5759.5 5926.7 5950.8 5308.0 5352.8 5365.8 5430.6 5480.0 5386.1 5452.5 5513.6 5515.4 5557.5 5255.8 5345.2 5367.5 5406.2 5420.6 5393.8 5399.1 5428.6 5433.9 5435.2 5282.3 5345.7 5381.0 5384.0 5387.4 5759.4 5826.3 5892.5 5930.6 6013.0 6223.4 6255.2 6266.4 6271.5 6296.4 6183.6 6191.2 6289.6 6300.1 6301.4 5912.1 5994.0 6003.4 6003.8 6038.7 6403.6 6460.4 6463.5 6467.0 6483.0 6102.3 6187.2 6198.3 6274.1 6274.7 6239.7 6277.7 6290.2 6304.9 6309.3 6124.8 6131.1 6253.6 6266.4 6287.2 6154.5 6183.8 6197.7 6247.0 6288.1 4212.6 5060.2 5079.1 5104.0 5112.1 4490.8 4497.2 4532.4 4552.4 4581.5 4762.3 5179.6 5617.4 5958.0 5985.9 6238.9 6272.3 6285.3 6300.0 6372.0 6009.5 6054.2 6070.0 6090.5 6109.8 5477.8 5525.8 5565.5 5574.8 5583.7 6489.4 6516.6 6530.5 6542.7 6591.7 6149.0 6329.7 6329.7 6351.7 6356.8 5949.4 6445.0 6503.9 6538.2 6544.4 6193.6 6214.9 6232.0 6268.4 6282.7 6145.6 6218.6 6324.7 6324.8 6343.7 6128.1 6129.9 6135.9 6191.0 6224.6 5081.2 5837.5 6249.6 6263.4 6270.9 5401.5 5942.2 6100.9 6103.0 6107.3 4445.4 6101.3 6205.7 6217.5 6262.9 6143.7 6143.9 6149.2 6185.3 6197.0 03:22:33 INFO - PROCESS | 1925 | 03:22:33 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:69589.28 Median:39961.10 stddev:158469.82 (396.6%) stddev-sans-first:158829.42 03:22:33 INFO - PROCESS | 1925 | Values: 481.4 500.0 506.0 527.0 571.7 30872.0 38334.8 39724.6 39797.2 40032.2 41474.0 42134.4 42252.9 42255.7 42267.0 9252.5 9437.4 9488.3 9518.4 9568.3 8700.9 9585.8 9660.1 9697.7 9721.0 9683.7 9735.4 9738.2 9741.8 9747.9 9633.8 9706.4 9729.4 9741.5 9796.0 9660.8 9685.9 9766.5 9779.2 9799.0 39890.0 40113.0 40141.2 40165.6 40306.4 2588.7 3371.8 3396.2 3627.1 3907.9 31084.5 31353.4 31700.0 31850.8 32082.7 3167.2 3174.5 3177.7 3195.6 3296.9 382715.1 407489.0 410167.9 412396.0 413727.0 33945.8 34385.0 34398.7 34535.5 34564.0 35517.5 35524.5 35755.0 36024.8 36115.5 14919.2 15151.6 15218.6 15219.7 15220.0 12582.0 12613.4 12952.1 13016.8 13023.5 6350.7 7492.2 7664.9 7686.9 7742.6 3217.9 3259.0 3262.7 3263.6 3275.6 976.4 987.3 1022.8 1110.1 1111.9 29679.7 31043.0 31273.5 31877.8 32020.4 1091.8 1125.1 1137.4 1158.2 1204.2 12908.4 15277.7 16132.0 16648.0 16758.0 712558.0 718200.0 719811.0 721580.0 724758.0 62041.4 62500.1 63204.0 63298.4 63356.0 1237.6 1406.7 1415.0 1427.3 1434.1 29534.7 30911.2 31209.5 31221.0 31731.8 45469.5 54711.0 55132.7 55339.9 55483.0 597271.0 599085.0 604911.0 605023.0 605063.0 27304.0 27711.9 27719.0 27873.0 27984.4 48511.0 50518.0 51735.0 51807.0 51884.5 41326.2 41781.8 41993.8 42085.4 43115.4 41372.8 41565.0 41864.8 41999.0 42148.6 38619.6 39092.0 40265.2 40476.0 40560.2 34878.0 36167.5 36831.3 36867.3 36959.0 26396.2 26486.5 26715.7 27120.3 27174.0 03:22:33 INFO - PROCESS | 1925 | 03:22:33 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:36071.84 Median:12177.29 stddev:97807.66 (803.2%) stddev-sans-first:98045.31 03:22:33 INFO - PROCESS | 1925 | Values: 374.0 386.4 391.9 394.2 414.8 18039.5 18305.0 20402.4 21637.6 22777.3 20873.6 21360.0 21429.0 21633.7 22115.0 5424.6 5897.6 6132.0 6134.6 6152.8 4876.0 4975.5 6160.0 6172.0 6256.4 5059.5 5802.6 6160.6 6163.8 6203.2 6132.4 6179.8 6190.9 6395.2 6872.0 5990.6 6041.0 6059.2 6072.5 6080.9 13709.9 13878.5 22763.0 24723.0 26356.6 2239.6 2458.5 2587.3 2861.3 2928.6 8266.9 10530.7 10584.7 15328.9 16023.8 1801.4 1842.6 1879.3 1918.9 1922.7 127371.4 133093.0 134603.0 134880.0 137280.0 11701.7 11741.2 11815.4 11865.4 12062.2 14835.0 14858.3 14859.8 14901.4 14985.6 8727.1 8784.2 8789.7 8792.6 8815.3 7172.3 8618.7 8895.7 8913.1 8920.0 4619.0 4636.8 4690.6 4702.2 4747.1 1953.2 1992.2 1994.4 1997.5 2000.9 907.7 923.3 944.1 1017.2 1076.9 17390.7 17673.7 18152.6 21381.6 22721.1 966.4 982.5 992.0 1027.3 1059.3 10968.1 10970.9 11247.9 11691.9 11696.7 555377.0 565645.0 567320.0 595567.0 601039.0 143147.6 149792.3 150720.2 151444.0 155631.0 917.0 1007.6 1023.5 1028.9 1050.6 19720.8 20112.0 20174.0 20232.6 20264.2 46461.5 60176.4 61162.1 61787.7 62162.0 90478.5 91169.5 91178.2 91289.0 91504.0 6979.2 7068.0 7657.9 7775.7 8403.7 11315.9 11585.9 13512.8 13886.9 13947.2 11671.3 11704.6 11799.8 11984.5 12009.8 11410.7 11741.2 11747.4 11754.2 11863.9 11812.0 11952.0 11968.2 11991.2 12021.2 6960.4 7646.8 7684.7 7701.0 7831.2 9867.6 11935.3 11974.5 11984.9 12062.9 03:22:33 INFO - PROCESS | 1925 | 03:22:33 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:5842.95 Median:4905.34 stddev:3268.32 (66.6%) stddev-sans-first:3261.16 03:22:33 INFO - PROCESS | 1925 | Values: 1496.6 1561.0 1762.2 1817.5 1839.8 8039.7 8753.4 8832.7 9138.7 9159.6 8774.6 8788.6 8933.5 8954.6 8966.5 2349.4 2370.1 2372.1 2373.6 2378.5 2363.9 2370.2 2378.0 2390.9 2412.7 2369.4 2377.4 2392.4 2394.5 2400.1 2347.7 2362.7 2377.0 2381.7 2384.3 2342.8 2345.6 2353.6 2368.6 2376.9 8027.6 9107.1 9110.9 9116.9 9125.7 5268.1 5358.5 5400.9 5418.6 5448.0 3608.4 3641.9 3705.3 3707.1 3714.4 1067.5 1084.9 1089.5 1092.7 1108.9 11542.8 11653.4 11661.2 11690.2 11703.8 4622.5 4647.8 4840.9 4872.0 4873.6 9829.4 9831.0 9857.8 9863.0 9864.3 5331.0 5398.7 5408.4 5408.6 5414.3 4482.5 4821.8 4937.1 4998.1 5006.0 3287.8 3325.4 4091.6 4105.5 4164.5 1298.2 1301.8 1311.2 1321.0 1322.8 802.1 827.3 828.0 948.1 977.8 5941.3 6090.2 6176.7 6202.8 6346.0 955.2 977.3 986.5 1005.8 1007.9 3638.1 3977.6 4205.9 4218.4 4226.8 10701.9 10808.9 10847.6 11095.3 11102.2 11015.6 11045.9 11054.3 11054.7 11076.2 7946.8 8210.8 8468.4 8528.0 8570.0 5943.7 6219.3 6231.2 6302.9 6425.3 10032.2 10157.5 10350.1 10412.5 10440.1 10215.1 10461.2 10464.6 10537.7 10571.0 7721.3 7809.8 7839.2 7869.2 7976.6 8457.3 8515.2 8590.6 8593.2 8608.3 7487.5 7699.1 7734.1 7767.4 7774.8 7486.2 7565.1 7573.5 7580.8 7725.3 7192.3 7234.9 7243.3 7266.3 7269.2 5037.5 5176.7 5186.6 5218.8 5247.3 4613.0 4841.9 5181.7 5235.5 5335.6 03:22:33 INFO - PROCESS | 1925 | 03:22:33 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4891.84 Median:5554.72 stddev:1561.92 (28.1%) stddev-sans-first:1527.29 03:22:33 INFO - PROCESS | 1925 | Values: 270.3 319.2 320.5 321.9 325.9 5456.8 5603.3 5791.3 5814.8 5831.5 5263.5 5716.5 5760.9 5774.9 5839.3 4197.2 4251.7 4364.6 4376.8 4398.3 4194.3 4325.2 4355.2 4390.7 4417.3 3714.6 4346.9 4364.3 4376.7 4409.6 3486.8 3839.9 4227.8 4346.4 4370.9 2803.6 3210.4 3398.9 3485.3 3543.9 5630.3 5659.2 5693.2 5725.3 5757.3 4603.4 4983.1 5005.9 5063.9 5112.3 5002.9 5921.0 6067.3 6093.5 6106.1 4066.4 4083.4 4088.2 4097.8 4125.0 5594.6 6090.4 6366.6 6446.4 6476.1 4110.1 4135.7 4136.5 4155.1 4171.7 3206.8 4220.9 4235.6 4244.9 4283.6 4131.7 4736.7 4976.9 4984.9 5015.7 5378.3 6085.2 6119.4 6146.2 6146.6 1349.8 1418.5 1433.6 1437.9 1441.2 862.6 873.7 876.6 878.3 929.6 5025.2 5361.1 5555.6 5561.4 5723.7 5442.9 6146.9 6237.2 6249.4 6275.5 4323.6 4860.1 5606.8 5644.4 5681.9 2679.8 2755.6 3075.9 3233.5 3255.9 5846.7 5962.0 6100.5 6424.5 6519.7 5722.0 6547.7 6614.2 6616.6 6632.1 6604.7 6662.7 6685.9 6692.6 6708.6 6113.9 6381.2 6382.7 6421.0 6454.3 4882.0 5238.0 5820.5 6374.4 6378.3 5314.4 6340.6 6526.3 6558.4 6589.3 4133.2 4134.4 4143.3 4157.4 4177.4 4720.4 5688.5 5697.0 6376.4 6468.4 4549.2 5087.0 5553.9 6147.8 6151.9 6109.3 6124.9 6165.8 6226.5 6230.7 5788.3 5820.3 5998.8 6049.5 6090.9 5405.0 5522.6 5867.6 6077.5 6213.4 4936.5 5269.8 5506.4 5679.6 5856.9 03:22:33 INFO - PROCESS | 1925 | 03:22:33 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:26383.27 Median:23266.27 stddev:42753.21 (183.8%) stddev-sans-first:42829.77 03:22:33 INFO - PROCESS | 1925 | Values: 734.2 761.2 779.5 806.1 889.3 15257.8 15422.5 16054.3 16094.5 16144.7 14502.9 14614.0 14660.2 14715.3 14746.0 712.0 886.5 901.6 912.7 935.2 917.0 920.2 920.4 921.3 921.5 981.7 984.9 987.7 989.5 989.6 939.8 941.3 942.3 982.0 982.2 722.4 726.7 726.9 730.8 731.4 15177.3 15182.2 15386.7 15477.1 15556.4 14973.8 15039.5 15066.0 15138.5 15326.6 11967.4 12169.6 12405.4 12408.9 12479.6 3792.3 3880.7 4012.7 4109.7 4165.3 116393.5 116847.8 118362.0 118475.4 118492.0 10315.7 10410.1 11288.4 11298.0 11654.4 16436.2 22860.2 22904.2 23141.7 23390.9 6879.8 7551.2 8482.2 8528.2 8545.4 4505.3 4691.0 4797.5 4830.4 4830.9 3053.4 3664.8 3713.4 3724.3 3734.8 683.5 688.8 699.6 705.2 710.2 671.8 676.8 682.3 720.3 721.8 9232.6 9574.0 9597.8 9657.3 9705.3 577.2 634.4 634.6 683.2 701.8 4497.8 4703.7 4710.6 4714.6 4727.5 158549.0 162743.6 163817.0 166202.0 166562.0 138009.3 139843.6 141527.0 141836.3 142454.9 103755.0 104230.5 121844.0 122742.4 124855.0 9694.1 9975.3 10024.8 10127.3 10221.2 65521.7 67294.7 67930.0 68727.0 70564.6 90099.0 92002.0 92992.0 96018.5 96258.0 11354.5 13456.4 13532.5 13943.4 13975.4 14102.1 14983.9 15091.2 15294.9 15506.6 13934.2 14082.9 14084.5 14098.9 14117.0 13755.7 13803.4 13846.8 14177.6 14364.2 13792.9 14012.9 14058.9 14260.9 14466.3 11354.0 11594.0 11707.0 11841.1 11924.7 9081.6 10588.2 11571.2 11740.2 11744.3 03:22:33 INFO - PROCESS | 1925 | -------- Summary: end -------- 03:22:33 INFO - PROCESS | 1925 | 03:22:33 INFO - TEST-INFO | 1925: exit 0 03:22:33 INFO - TEST-OK | dromaeo_css | took 1113162ms 03:22:33 INFO - TEST-START | kraken 03:22:33 INFO - operating with platform_type : mac_ 03:22:33 INFO - Initialising browser for kraken test... 03:22:33 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpzh3dG5/profile http://localhost:51521/getInfo.html) 03:22:36 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 03:22:36 INFO - PROCESS | 1931 | colorDepth:24 03:22:36 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 03:22:36 INFO - PROCESS | 1931 | __metrics 03:22:36 ERROR - PROCESS | 1931 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 03:22:37 INFO - TEST-INFO | 1931: exit 0 03:22:37 INFO - Browser initialized. 03:22:37 INFO - Running cycle 1/1 for kraken test... 03:22:37 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpzh3dG5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:49 INFO - PROCESS | 1933 | RSS: Main: 255533056 03:22:49 INFO - PROCESS | 1933 | 03:22:51 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51521/tests/kraken/driver.html?testName=audio-beat-detection) 03:22:52 INFO - PROCESS | 1933 | RSS: Main: 268259328 03:22:52 INFO - PROCESS | 1933 | 03:22:55 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51521/tests/kraken/driver.html?testName=audio-dft) 03:22:56 INFO - PROCESS | 1933 | RSS: Main: 272445440 03:22:56 INFO - PROCESS | 1933 | 03:22:59 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51521/tests/kraken/driver.html?testName=audio-fft) 03:22:59 INFO - PROCESS | 1933 | RSS: Main: 272629760 03:22:59 INFO - PROCESS | 1933 | 03:23:01 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51521/tests/kraken/driver.html?testName=audio-oscillator) 03:23:02 INFO - PROCESS | 1933 | RSS: Main: 273612800 03:23:02 INFO - PROCESS | 1933 | 03:23:04 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51521/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:23:05 INFO - PROCESS | 1933 | RSS: Main: 274173952 03:23:05 INFO - PROCESS | 1933 | 03:23:08 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51521/tests/kraken/driver.html?testName=imaging-darkroom) 03:23:09 INFO - PROCESS | 1933 | RSS: Main: 274792448 03:23:09 INFO - PROCESS | 1933 | 03:23:12 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51521/tests/kraken/driver.html?testName=imaging-desaturate) 03:23:13 INFO - PROCESS | 1933 | RSS: Main: 272404480 03:23:13 INFO - PROCESS | 1933 | 03:23:16 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51521/tests/kraken/driver.html?testName=json-parse-financial) 03:23:16 INFO - PROCESS | 1933 | RSS: Main: 274018304 03:23:16 INFO - PROCESS | 1933 | 03:23:18 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51521/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:23:18 INFO - PROCESS | 1933 | RSS: Main: 274247680 03:23:18 INFO - PROCESS | 1933 | 03:23:20 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:23:21 INFO - PROCESS | 1933 | RSS: Main: 274034688 03:23:21 INFO - PROCESS | 1933 | 03:23:23 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:23:24 INFO - PROCESS | 1933 | RSS: Main: 274432000 03:23:24 INFO - PROCESS | 1933 | 03:23:27 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:23:27 INFO - PROCESS | 1933 | RSS: Main: 274681856 03:23:27 INFO - PROCESS | 1933 | 03:23:30 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:23:31 INFO - PROCESS | 1933 | RSS: Main: 274665472 03:23:31 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:51521/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51521/tests/kraken/driver.html?testName=ai-astar) 03:23:33 INFO - PROCESS | 1933 | RSS: Main: 275795968 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | __start_tp_report 03:23:33 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 03:23:33 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 03:23:33 INFO - PROCESS | 1933 | |i|pagename|runs| 03:23:33 INFO - PROCESS | 1933 | |0;ai-astar;102;123;106;115;115;116;114;114;117;118 03:23:33 INFO - PROCESS | 1933 | |1;audio-beat-detection;189;135;135;156;106;134;166;142;175;108 03:23:33 INFO - PROCESS | 1933 | |2;audio-dft;188;202;199;209;245;200;196;200;196;200 03:23:33 INFO - PROCESS | 1933 | |3;audio-fft;64;74;69;110;60;60;55;60;131;58 03:23:33 INFO - PROCESS | 1933 | |4;audio-oscillator;74;96;140;69;71;93;142;81;72;115 03:23:33 INFO - PROCESS | 1933 | |5;imaging-gaussian-blur;77;123;135;86;89;89;77;114;104;104 03:23:33 INFO - PROCESS | 1933 | |6;imaging-darkroom;98;132;151;156;109;156;154;160;91;155 03:23:33 INFO - PROCESS | 1933 | |7;imaging-desaturate;90;91;89;92;78;145;145;93;92;92 03:23:33 INFO - PROCESS | 1933 | |8;json-parse-financial;58;59;58;56;58;59;57;57;58;58 03:23:33 INFO - PROCESS | 1933 | |9;json-stringify-tinderbox;69;70;72;71;80;76;71;72;70;67 03:23:33 INFO - PROCESS | 1933 | |10;stanford-crypto-aes;88;100;111;83;85;85;87;83;85;88 03:23:33 INFO - PROCESS | 1933 | |11;stanford-crypto-ccm;134;139;136;140;144;180;131;138;197;167 03:23:33 INFO - PROCESS | 1933 | |12;stanford-crypto-pbkdf2;214;236;170;165;164;165;161;164;165;167 03:23:33 INFO - PROCESS | 1933 | |13;stanford-crypto-sha256-iterative;68;66;67;66;70;65;67;68;77;87 03:23:33 INFO - PROCESS | 1933 | __end_tp_report 03:23:33 INFO - PROCESS | 1933 | __start_cc_report 03:23:33 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,1119 03:23:33 INFO - PROCESS | 1933 | __end_cc_report 03:23:33 INFO - PROCESS | 1933 | __startTimestamp1469615013394__endTimestamp 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | ------- Summary: start ------- 03:23:33 INFO - PROCESS | 1933 | Number of tests: 14 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.50 stddev:5.96 (5.2%) stddev-sans-first:4.47 03:23:33 INFO - PROCESS | 1933 | Values: 102.0 123.0 106.0 115.0 115.0 116.0 114.0 114.0 117.0 118.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:149.00 stddev:27.11 (18.2%) stddev-sans-first:23.52 03:23:33 INFO - PROCESS | 1933 | Values: 189.0 135.0 135.0 156.0 106.0 134.0 166.0 142.0 175.0 108.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#2] audio-dft Cycles:10 Average:203.50 Median:200.00 stddev:15.51 (7.8%) stddev-sans-first:15.40 03:23:33 INFO - PROCESS | 1933 | Values: 188.0 202.0 199.0 209.0 245.0 200.0 196.0 200.0 196.0 200.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#3] audio-fft Cycles:10 Average:74.10 Median:60.00 stddev:25.55 (42.6%) stddev-sans-first:26.84 03:23:33 INFO - PROCESS | 1933 | Values: 64.0 74.0 69.0 110.0 60.0 60.0 55.0 60.0 131.0 58.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:73.00 stddev:28.00 (38.4%) stddev-sans-first:28.62 03:23:33 INFO - PROCESS | 1933 | Values: 74.0 96.0 140.0 69.0 71.0 93.0 142.0 81.0 72.0 115.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:77.00 stddev:19.66 (25.5%) stddev-sans-first:19.04 03:23:33 INFO - PROCESS | 1933 | Values: 77.0 123.0 135.0 86.0 89.0 89.0 77.0 114.0 104.0 104.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#6] imaging-darkroom Cycles:10 Average:136.20 Median:156.00 stddev:26.87 (17.2%) stddev-sans-first:24.69 03:23:33 INFO - PROCESS | 1933 | Values: 98.0 132.0 151.0 156.0 109.0 156.0 154.0 160.0 91.0 155.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:91.50 stddev:23.74 (25.9%) stddev-sans-first:24.86 03:23:33 INFO - PROCESS | 1933 | Values: 90.0 91.0 89.0 92.0 78.0 145.0 145.0 93.0 92.0 92.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.00 stddev:0.92 (1.6%) stddev-sans-first:0.97 03:23:33 INFO - PROCESS | 1933 | Values: 58.0 59.0 58.0 56.0 58.0 59.0 57.0 57.0 58.0 58.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:3.71 (5.2%) stddev-sans-first:3.79 03:23:33 INFO - PROCESS | 1933 | Values: 69.0 70.0 72.0 71.0 80.0 76.0 71.0 72.0 70.0 67.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:85.00 stddev:9.00 (10.6%) stddev-sans-first:9.53 03:23:33 INFO - PROCESS | 1933 | Values: 88.0 100.0 111.0 83.0 85.0 85.0 87.0 83.0 85.0 88.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:142.00 stddev:22.63 (15.9%) stddev-sans-first:23.19 03:23:33 INFO - PROCESS | 1933 | Values: 134.0 139.0 136.0 140.0 144.0 180.0 131.0 138.0 197.0 167.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:166.00 stddev:25.87 (15.6%) stddev-sans-first:23.75 03:23:33 INFO - PROCESS | 1933 | Values: 214.0 236.0 170.0 165.0 164.0 165.0 161.0 164.0 165.0 167.0 03:23:33 INFO - PROCESS | 1933 | 03:23:33 INFO - PROCESS | 1933 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:68.00 stddev:6.84 (10.1%) stddev-sans-first:7.21 03:23:33 INFO - PROCESS | 1933 | Values: 68.0 66.0 67.0 66.0 70.0 65.0 67.0 68.0 77.0 87.0 03:23:33 INFO - PROCESS | 1933 | -------- Summary: end -------- 03:23:33 INFO - PROCESS | 1933 | 03:23:34 INFO - TEST-INFO | 1933: exit 0 03:23:34 INFO - TEST-OK | kraken | took 60586ms 03:23:34 INFO - SUITE-END | took 1173s 03:23:34 INFO - Completed test suite (00:19:34) 03:23:34 INFO - javascript benchmark 03:23:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7523.57777718571, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3355.0, 3408.301298233861, 3424.0412684448365, 3434.484827275908, 3467.653234676532, 5136.563392111675, 5248.530411484786, 5814.302283725953, 5853.297604287481, 5904.409559044098, 4677.0, 5757.481826635602, 5759.452851979056, 5926.733297001634, 5950.821475355746, 5307.973460132704, 5352.759125839347, 5365.7853685852515, 5430.619856610039, 5480.0, 5386.111291636876, 5452.454754524555, 5513.558915286788, 5515.448455154493, 5557.472040156179, 5255.763490642892, 5345.198220266952, 5367.46325367464, 5406.216098665691, 5420.566354691615, 5393.83818485446, 5399.082156033483, 5428.61999660024, 5433.945660543423, 5435.1847222916485, 5282.260483532308, 5345.705986170759, 5381.004514164892, 5383.973080134613, 5387.434319396485, 5759.366469688331, 5826.300843898717, 5892.528597712173, 5930.584859059867, 6013.0, 6223.408776166257, 6255.186825712673, 6266.373362663697, 6271.498280137624, 6296.433321001132, 6183.628982261079, 6191.2260967378625, 6289.622622642656, 6300.054991751228, 6301.369863013662, 5912.054071348563, 5993.9700301498215, 6003.429674180946, 6003.8198854034445, 6038.667873266969, 6403.615783053032, 6460.353964603596, 6463.547551671432, 6466.967665161644, 6483.027545868157, 6102.337773232528, 6187.226596675416, 6198.287196972406, 6274.058891166362, 6274.717637706314, 6239.656818875005, 6277.686115694197, 6290.24517057956, 6304.873902521924, 6309.337519560472, 6124.808163753463, 6131.101423540838, 6253.593516421509, 6266.436020758154, 6287.245530536365, 6154.507639388928, 6183.81448556544, 6197.690115494207, 6246.968765156145, 6288.088227207121, 4212.599803018708, 5060.2156665716875, 5079.08576456234, 5104.0, 5112.054269960069, 4490.753008584559, 4497.190505709003, 4532.4334458192725, 4552.385427967182, 4581.518940511265, 4762.261849413347, 5179.637425380187, 5617.41017193197, 5957.970210148921, 5985.850353741104, 6238.937610623926, 6272.341404152498, 6285.340039295808, 6299.96850015747, 6371.980483122678, 6009.489193418608, 6054.182685337423, 6070.0, 6090.52843852597, 6109.75560977556, 5477.75350109246, 5525.751341189656, 5565.526930210896, 5574.777008919679, 5583.664980101125, 6489.415952564386, 6516.609003459807, 6530.47756179495, 6542.672866356568, 6591.736330546821, 6148.9692551536955, 6329.715162817776, 6329.715162817776, 6351.68241587928, 6356.841078973062, 5949.434803693643, 6444.967775161094, 6503.934960650333, 6538.215414150332, 6544.3782840630065, 6193.566450348432, 6214.937850621436, 6231.9688401559515, 6268.435840774352, 6282.680637066267, 6145.647957449354, 6218.62688238689, 6324.6521441321165, 6324.810255692337, 6343.682815859281, 6128.142060111677, 6129.939558357133, 6135.906433916272, 6190.969045154746, 6224.564280500322, 5081.23781432788, 5837.474627283565, 6249.625022498664, 6263.3736626337, 6270.874582508233, 5401.540869026177, 5942.227510423617, 6100.877982440237, 6102.993006153935, 6107.328193898757, 4445.355423463644, 6101.298350689727, 6205.720742566505, 6217.4715149211015, 6262.906056409066, 6143.6928153593035, 6143.877122457615, 6149.169862068564, 6185.288691800501, 6197.008478643395], "value": 5778.961386675005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.41027241628996, 500.0424187680592, 505.95772708221057, 526.9855528108391, 571.6998575747732, 30872.0, 38334.808325958365, 39724.60275397245, 39797.20405591886, 40032.1993560129, 41474.0, 42134.36798448026, 42252.943676408104, 42255.73232803013, 42267.0, 9252.537373131334, 9437.433753974783, 9488.28837837165, 9518.381305215173, 9568.33021688482, 8700.869486957701, 9585.80828383432, 9660.082292182231, 9697.660581879636, 9720.95139524305, 9683.661071862487, 9735.415875047483, 9738.220942324633, 9741.756456088584, 9747.902520974805, 9633.75915602112, 9706.417614943126, 9729.367591106591, 9741.512924353754, 9795.951020244853, 9660.75848103803, 9685.854712179324, 9766.462844543546, 9779.168770654504, 9799.020097990215, 39890.0, 40113.0, 40141.19717605661, 40165.59834401648, 40306.395404068964, 2588.741125887415, 3371.831408429569, 3396.1679388549965, 3627.075095850568, 3907.882763517099, 31084.533731994037, 31353.37293254122, 31700.0, 31850.840745796355, 32082.679173208202, 3167.224030112614, 3174.4762114251353, 3177.729892959101, 3195.6484786673445, 3296.901092967191, 382715.0864245689, 407489.0, 410167.9491602523, 412396.0, 413727.0, 33945.8118965835, 34385.0, 34398.65601344004, 34535.4819677705, 34564.0, 35517.46723799145, 35524.46713299303, 35755.0, 36024.819875900976, 36115.458268126, 14919.179445130405, 15151.621209469851, 15218.619534511727, 15219.695606087816, 15220.01069930451, 12582.0, 12613.369331533386, 12952.093353465356, 13016.804747928789, 13023.47906083746, 6350.682465876687, 7492.213317601682, 7664.885026724603, 7686.9231307686205, 7742.61286935651, 3217.8873739419478, 3259.0385836178384, 3262.6900444457738, 3263.6083669959758, 3275.5577996970583, 976.3751199232497, 987.3286165407592, 1022.7647641042602, 1110.134095405585, 1111.8610173728284, 29679.703202967696, 31043.0, 31273.530897036562, 31877.840610797262, 32020.35959280848, 1091.8471414002047, 1125.1336470917347, 1137.4426530999751, 1158.1892675127444, 1204.2052245517916, 12908.35458227085, 15277.694446111016, 16132.032078075119, 16648.001119932844, 16757.971345907063, 712558.0, 718200.0, 719811.0, 721580.0, 724758.0, 62041.37958620356, 62500.06249906255, 63204.0, 63298.417539560956, 63356.04965925515, 1237.5915947737226, 1406.6694326833185, 1414.985850141506, 1427.336288625794, 1434.089353260687, 29534.704652953194, 30911.2272193197, 31209.531857022615, 31221.0, 31731.84134079407, 45469.54530454653, 54711.0, 55132.72433637806, 55339.893202134925, 55483.0, 597271.0, 599085.0, 604911.0, 605023.0, 605063.0, 27304.0, 27711.861440692668, 27719.0, 27873.0, 27984.440311193255, 48511.0, 50518.0, 51735.0, 51807.0, 51884.481155189475, 41326.1734765309, 41781.79109104435, 41993.79003104965, 42085.36871946984, 43115.353269701605, 41372.793136034124, 41565.0, 41864.790676046425, 41999.0, 42148.57851421447, 38619.613803861605, 39092.0, 40265.194696105325, 40475.98810029773, 40560.1887962245, 34878.0, 36167.457488137174, 36831.26337473182, 36867.26265474622, 36959.0, 26396.208113756617, 26486.470270594866, 26715.732842671325, 27120.32199195036, 27174.048908288114], "value": 18806.12471187151, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.95512538495353, 386.3914334922497, 391.94708714323576, 394.19190659148745, 414.76773007116014, 18039.466645335146, 18305.0, 20402.387928362154, 21637.567248655017, 22777.31668049957, 20873.582528349423, 21360.0, 21429.0, 21633.675494867588, 22115.0, 5424.593155513352, 5897.557683173768, 6132.0, 6134.570580059375, 6152.815415537541, 4875.97604503053, 4975.4527002029745, 6159.969200153993, 6172.0, 6256.374362563753, 5059.544640982311, 5802.564807639423, 6160.5687601867885, 6163.8150855474405, 6203.2245969253845, 6132.417420345082, 6179.783707570258, 6190.938090619081, 6395.20059992501, 6872.0, 5990.5507086968655, 6040.96979515104, 6059.151718759377, 6072.483838873701, 6080.9391906081255, 13709.862901371058, 13878.514252001232, 22762.975666095066, 24723.011079556974, 26356.60465093025, 2239.5856766498086, 2458.483718419134, 2587.3143616941525, 2861.298981749464, 2928.569287491377, 8266.875996860052, 10530.736731581695, 10584.682459526226, 15328.923355383151, 16023.759643605355, 1801.4325487471535, 1842.631473705251, 1879.3328368429284, 1918.9232430702755, 1922.6731455652432, 127371.36314318368, 133093.0, 134603.0, 134880.0, 137280.0, 11701.707457313636, 11741.178117531861, 11815.350155741517, 11865.406729663655, 12062.215955962834, 14835.0, 14858.331375088146, 14859.777103343458, 14901.403943842342, 14985.625359365886, 8727.127287271349, 8784.165504277084, 8789.736307910645, 8792.648294068293, 8815.338849586307, 7172.282771722786, 8618.741437756877, 8895.73312800617, 8913.064128266567, 8919.955400223087, 4619.0, 4636.79134438951, 4690.64820138491, 4702.224133018082, 4747.098051370229, 1953.189426388033, 1992.213075835037, 1994.4216177308708, 1997.530580313625, 2000.9499762506055, 907.6550910653909, 923.2613908872875, 944.1361154543516, 1017.1710056304237, 1076.9030787229187, 17390.739138912926, 17673.67447441447, 18152.63694726098, 21381.57236855254, 22721.09115635356, 966.3622009473713, 982.4792859784326, 991.9553620087113, 1027.337367398024, 1059.274397038016, 10968.122550195965, 10970.945145274223, 11247.887521124847, 11691.941540292244, 11696.70758231051, 555377.0, 565645.0, 567320.0, 595567.0, 601039.0, 143147.56852431552, 149792.2510387463, 150720.2463987695, 151444.0, 155631.0, 916.9954150229339, 1007.561710655867, 1023.4882558720789, 1028.9125424339015, 1050.5640159333943, 19720.80279197247, 20111.994400279636, 20174.0, 20232.59534809325, 20264.18943242313, 46461.535384647075, 60176.39823601708, 61162.082568762395, 61787.69106154441, 62162.0, 90478.54760726154, 91169.54415227882, 91178.17643646957, 91289.0, 91504.0, 6979.1973922999505, 7067.964660176666, 7657.923420765721, 7775.650095745818, 8403.705870294754, 11315.886841131483, 11585.884141158818, 13512.79730804019, 13886.93056534711, 13947.232902190477, 11671.299722016705, 11704.648860534198, 11799.823002654795, 11984.520619175135, 12009.819852702041, 11410.657680269605, 11741.178117532032, 11747.353895535485, 11754.177207595387, 11863.940680296544, 11811.995980341768, 11951.98043277524, 11968.16222864391, 11991.160618756952, 12021.218620789621, 6960.408365288805, 7646.8088297793, 7684.6926122954455, 7701.0, 7831.177726338652, 9867.605295788087, 11935.343556104497, 11974.46114924813, 11984.880151198377, 12062.914337709648], "value": 9913.488484561714, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.580957331947, 1561.0321600607622, 1762.162972588021, 1817.4547635709284, 1839.751633529473, 8039.678412863478, 8753.43102698325, 8832.735017949455, 9138.680146194876, 9159.587818548165, 8774.605142768574, 8788.560571971391, 8933.508657023862, 8954.552272386387, 8966.506842123681, 2349.3774149850306, 2370.0993622423457, 2372.074890792592, 2373.6439534069855, 2378.4886249456363, 2363.858168509886, 2370.1704403458825, 2377.9762202378015, 2390.8924098415614, 2412.7104747430335, 2369.4194922244073, 2377.41753270449, 2392.3899405651537, 2394.4971555973266, 2400.1119585753295, 2347.6713260143424, 2362.70466191726, 2377.0270675339425, 2381.666566680666, 2384.2727967969763, 2342.8125749939963, 2345.64815277708, 2353.6234202527685, 2368.6328619064075, 2376.916807911715, 8027.638756255982, 9107.089291070906, 9110.86333704995, 9116.908830911738, 9125.726228213165, 5268.078086334876, 5358.4641535846495, 5400.891982160335, 5418.647787893774, 5448.0, 3608.3685355062757, 3641.908952276214, 3705.3330400527893, 3707.0917625181733, 3714.3685573452576, 1067.5356220044305, 1084.8500589375208, 1089.4988305379534, 1092.7104317355906, 1108.933463992163, 11542.769144617061, 11653.417329133594, 11661.242019268806, 11690.240134391322, 11703.765924681458, 4622.514635963243, 4647.814087436464, 4840.903181936342, 4872.049950259665, 4873.561379475864, 9829.361091529028, 9830.95084524587, 9857.75355616101, 9862.950685246671, 9864.260180486492, 5331.0, 5398.676079435168, 5408.4050754416985, 5408.621396502286, 5414.3232095988005, 4482.462104547475, 4821.831235906727, 4937.1113199624415, 4998.075356059141, 5006.0, 3287.8027318360973, 3325.3515564464697, 4091.57038510958, 4105.527864295585, 4164.542410156421, 1298.1626850681257, 1301.7917133258634, 1311.2132720367704, 1320.973580528384, 1322.7883538633773, 802.0736049862302, 827.3215962910476, 827.995860020708, 948.1087777489138, 977.7897751983357, 5941.287045554474, 6090.177825993434, 6176.672015516686, 6202.81391558254, 6346.0, 955.2358113509165, 977.3012296208245, 986.4574484033752, 1005.7737009172878, 1007.9114556279201, 3638.1268495560876, 3977.582353852804, 4205.936910946339, 4218.367244913287, 4226.76752778594, 10701.89298107009, 10808.919108089285, 10847.620333288298, 11095.334279943228, 11102.167337449724, 11015.559377624806, 11045.889541104647, 11054.336739795477, 11054.668359949215, 11076.169287303484, 7946.801329966682, 8210.794730131674, 8468.44955077918, 8528.01927778301, 8569.95715021421, 5943.673097979653, 6219.284782250099, 6231.22109736283, 6302.873942521215, 6425.325340839145, 10032.197424206191, 10157.492125393554, 10350.120239779402, 10412.531436085546, 10440.060394564525, 10215.131713804112, 10461.163106951577, 10464.581416743245, 10537.683869483928, 10571.04860562553, 7721.266479684421, 7809.804754881174, 7839.216078392115, 7869.213078692085, 7976.6410511525955, 8457.281131103678, 8515.233628973423, 8590.570471476276, 8593.226609605166, 8608.311335093304, 7487.513311634726, 7699.114601820638, 7734.110577283459, 7767.3786097113225, 7774.805629859299, 7486.213947535431, 7565.130010048695, 7573.507721998052, 7580.810479738051, 7725.304722574995, 7192.31672991065, 7234.891476627749, 7243.348098671145, 7266.346028857428, 7269.200387957426, 5037.546620804145, 5176.689398636094, 5186.58507319406, 5218.817341393033, 5247.317848679647, 4612.953870461252, 4841.927371089366, 5181.715005674573, 5235.528802407802, 5335.573154147582], "value": 4690.758385572957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.28913956294946, 319.19244311890907, 320.54642680606935, 321.9420504309225, 325.93644239373333, 5456.781728730851, 5603.299587551561, 5791.333996590388, 5814.796482123126, 5831.504322132613, 5263.526282634562, 5716.514096301819, 5760.855978600537, 5774.942250577493, 5839.299284085916, 4197.244495990721, 4251.702380833343, 4364.607185353318, 4376.7811609419405, 4398.252297109483, 4194.328907374821, 4325.178216138939, 4355.237833379146, 4390.714603550759, 4417.27115026022, 3714.591394946554, 4346.891542656618, 4364.258076127066, 4376.715513491628, 4409.625181859546, 3486.825658717054, 3839.86560470385, 4227.787716159806, 4346.391505189276, 4370.868873933787, 2803.5934789455414, 3210.3739770744714, 3398.898033059012, 3485.302939412102, 3543.9114022149392, 5630.296212973379, 5659.236003139606, 5693.174489699012, 5725.341585717612, 5757.309122905236, 4603.447586289667, 4983.053219888209, 5005.874853128701, 5063.87340316495, 5112.309838171874, 5002.9499705002845, 5920.97039514804, 6067.271927368744, 6093.542984276197, 6106.084087386883, 4066.369712694537, 4083.4283200351974, 4088.2436749201197, 4097.774622395767, 4124.9587504124875, 5594.58040646949, 6090.390960903918, 6366.554341196072, 6446.387593178641, 6476.060971159202, 4110.116324990082, 4135.731177473454, 4136.503619565671, 4155.148194620096, 4171.666266698657, 3206.7915585487335, 4220.873373798791, 4235.597618226325, 4244.87265382039, 4283.614474697292, 4131.698514967836, 4736.668433209642, 4976.850694479172, 4984.934265426737, 5015.699058056528, 5378.327709036371, 6085.208922840003, 6119.44924956756, 6146.200993870768, 6146.631202127886, 1349.7637913365122, 1418.5318844781198, 1433.5699290212895, 1437.8705916467568, 1441.243347242706, 862.5687156421789, 873.7116751471999, 876.5748611923212, 878.3280790195505, 929.6002718830832, 5025.221090730943, 5361.115415956401, 5555.555555555546, 5561.360443548962, 5723.742431590588, 5442.945570544244, 6146.938530614726, 6237.157983672237, 6249.375062493714, 6275.454368281906, 4323.589259020388, 4860.125177468019, 5606.831795046155, 5644.407337229614, 5681.9431805681415, 2679.825811322258, 2755.5728862026417, 3075.861931085513, 3233.4826427771445, 3255.9023229303157, 5846.736896839652, 5961.967619057132, 6100.450959413674, 6424.486041116793, 6519.739210431624, 5722.0, 6547.705353259114, 6614.239362473281, 6616.603003819786, 6632.1046658701425, 6604.735810567524, 6662.6668666566475, 6685.866282674319, 6692.564983275974, 6708.597484150967, 6113.908291375544, 6381.202349706286, 6382.744690212339, 6421.0, 6454.322296158835, 4881.974785295128, 5237.973810130925, 5820.534357251325, 6374.362563743589, 6378.298387177314, 5314.388845282842, 6340.619562826245, 6526.2800203883335, 6558.376954189344, 6589.275179730322, 4133.181400183909, 4134.421181034598, 4143.295639741312, 4157.355609880473, 4177.373393990925, 4720.433547974265, 5688.54491640676, 5696.971515142398, 6376.39424254695, 6468.3855033771715, 4549.225801937181, 5087.0, 5553.888922221452, 6147.84630384244, 6151.876962460637, 6109.266887973472, 6124.897518446723, 6165.815025549241, 6226.516907826358, 6230.719617617127, 5788.305403351625, 5820.330661973927, 5998.790042348497, 6049.485793707584, 6090.878182436415, 5404.973055119514, 5522.6134170607675, 5867.618604790675, 6077.453029227391, 6213.440790328892, 4936.543719602798, 5269.841904742864, 5506.421825708243, 5679.602427830012, 5856.94143058564], "value": 4384.20750609776, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.2473964186711, 761.2387612387612, 779.5244900610627, 806.1495122645606, 889.3196704521041, 15257.771133432994, 15422.460213892517, 16054.277557509911, 16094.517164485054, 16144.677106457864, 14502.92748536256, 14614.0, 14660.193689347081, 14715.337809798559, 14746.041507302018, 711.9715211391538, 886.5478605910968, 901.6123067081153, 912.6988093928987, 935.2097477631424, 916.9770755731126, 920.1994264989469, 920.3557509743173, 921.345844450441, 921.5300196899595, 981.7300242433316, 984.906433888783, 987.733312005758, 989.515137582583, 989.6387818446251, 939.8449255872773, 941.2893265584474, 942.3215284994793, 982.0326977926736, 982.2485798364221, 722.4040166862308, 726.731109489487, 726.901868247785, 730.7978375572208, 731.3710209220067, 15177.317020734094, 15182.164980926045, 15386.692266154614, 15477.148756818371, 15556.377744890191, 14973.775393369107, 15039.473618423412, 15066.0, 15138.470153544684, 15326.616834579114, 11967.40162991847, 12169.634910952596, 12405.379731013414, 12408.875911240862, 12479.563215287511, 3792.3363411402893, 3880.6507414332564, 4012.7391719538136, 4109.732867363612, 4165.271077561415, 116393.4999475479, 116847.83152168455, 118362.0, 118475.4076229622, 118492.0, 10315.710536182976, 10410.063094321547, 11288.379139147262, 11298.0, 11654.417279136009, 16436.1781910904, 22860.199893003664, 22904.198353057564, 23141.652875206884, 23390.883045585004, 6879.759208427681, 7551.244875512405, 8482.194191551793, 8528.189821966904, 8545.444546104483, 4505.301678239878, 4691.014886873795, 4797.472278049412, 4830.396200474941, 4830.9275360869615, 3053.4198502284494, 3664.7801131931637, 3713.3872910969926, 3724.2923844469465, 3734.775913445202, 683.4771399879096, 688.8071340024801, 699.5903254941297, 705.2312978853075, 710.1797423975316, 671.771597656799, 676.8341756269746, 682.2938258902012, 720.3013077314996, 721.8267615772177, 9232.584533696, 9574.0, 9597.760055998517, 9657.275704322063, 9705.320627556004, 577.2236342119842, 634.4004915355038, 634.5938599296454, 683.1938312790855, 701.7508784381471, 4497.752623605733, 4703.670743048022, 4710.646701497333, 4714.622830173578, 4727.503612120678, 158549.0, 162743.5588466174, 163817.0, 166202.0, 166562.0, 138009.3099534516, 139843.60156398508, 141527.0, 141836.2908185473, 142454.86317705244, 103755.0, 104230.47884760528, 121844.0, 122742.386288068, 124855.0, 9694.127528522467, 9975.251856110675, 10024.849627255742, 10127.291089623655, 10221.182305415698, 65521.68956620936, 67294.66352668205, 67930.0, 68726.96909546261, 70564.64717676378, 90099.0, 92002.0, 92992.0, 96018.5199074028, 96258.0, 11354.489047992694, 13456.394462249027, 13532.458701651824, 13943.372548235557, 13975.440982360999, 14102.083364581269, 14983.850161498682, 15091.245437728288, 15294.923525382303, 15506.612334691723, 13934.163950163023, 14082.929585352007, 14084.52110033131, 14098.859011409755, 14117.0, 13755.656108597364, 13803.447862085806, 13846.792298115335, 14177.645558861112, 14364.209968451836, 13792.862071379559, 14012.859871401562, 14058.929705351407, 14260.928695356457, 14466.349014294172, 11354.0, 11594.0, 11707.0, 11841.111916606116, 11924.701882453008, 9081.636734530544, 10588.152947764007, 11571.197952849474, 11740.178187526792, 11744.29534227949], "value": 8184.578386164723, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 123.0, 106.0, 115.0, 115.0, 116.0, 114.0, 114.0, 117.0, 118.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 135.0, 135.0, 156.0, 106.0, 134.0, 166.0, 142.0, 175.0, 108.0], "value": 144.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 202.0, 199.0, 209.0, 245.0, 200.0, 196.0, 200.0, 196.0, 200.0], "value": 203.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 69.0, 110.0, 60.0, 60.0, 55.0, 60.0, 131.0, 58.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 96.0, 140.0, 69.0, 71.0, 93.0, 142.0, 81.0, 72.0, 115.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 123.0, 135.0, 86.0, 89.0, 89.0, 77.0, 114.0, 104.0, 104.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 132.0, 151.0, 156.0, 109.0, 156.0, 154.0, 160.0, 91.0, 155.0], "value": 136.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 92.0, 78.0, 145.0, 145.0, 93.0, 92.0, 92.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 56.0, 58.0, 59.0, 57.0, 57.0, 58.0, 58.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 72.0, 71.0, 80.0, 76.0, 71.0, 72.0, 70.0, 67.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 100.0, 111.0, 83.0, 85.0, 85.0, 87.0, 83.0, 85.0, 88.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 139.0, 136.0, 140.0, 144.0, 180.0, 131.0, 138.0, 197.0, 167.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 236.0, 170.0, 165.0, 164.0, 165.0, 161.0, 164.0, 165.0, 167.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 70.0, 65.0, 67.0, 68.0, 77.0, 87.0], "value": 70.1, "unit": "score"}]}]} 03:23:34 INFO - Return code: 0 03:23:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:23:34 ERROR - # TBPL SUCCESS # 03:23:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:23:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:23:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:23:34 INFO - 2016-07-27 03:23:34.701 screenresolution[1938:15591] starting screenresolution argv=screenresolution get 03:23:34 INFO - 2016-07-27 03:23:34.703 screenresolution[1938:15591] Display 0: 1600x1200x32@60 03:23:34 INFO - 2016-07-27 03:23:34.709 screenresolution[1939:15594] starting screenresolution argv=screenresolution list 03:23:34 INFO - Available Modes on Display 0 03:23:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:23:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:23:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:23:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:23:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:23:34 INFO - Intel Iris: 03:23:34 INFO - Chipset Model: Intel Iris 03:23:34 INFO - Type: GPU 03:23:34 INFO - Bus: Built-In 03:23:34 INFO - VRAM (Dynamic, Max): 1536 MB 03:23:34 INFO - Vendor: Intel (0x8086) 03:23:34 INFO - Device ID: 0x0a2e 03:23:34 INFO - Revision ID: 0x0009 03:23:34 INFO - Displays: 03:23:34 INFO - Display: 03:23:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:23:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:23:34 INFO - Display Serial Number: 1600x1200 60 03:23:34 INFO - Main Display: Yes 03:23:34 INFO - Mirror: Off 03:23:34 INFO - Online: Yes 03:23:34 INFO - Rotation: Supported 03:23:34 INFO - Return code: 0 03:23:34 INFO - Running post-action listener: _resource_record_post_action 03:23:34 INFO - ##### 03:23:34 INFO - ##### Finished run-tests step (success) 03:23:34 INFO - ##### 03:23:34 INFO - Running post-run listener: _resource_record_post_run 03:23:34 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 1073664; Write bytes: 705794048; Read time: 13; Write time: 1978 03:23:34 INFO - TinderboxPrint: CPU usage
54.2% 03:23:34 INFO - TinderboxPrint: I/O read bytes / time
1,073,664 / 13 03:23:34 INFO - TinderboxPrint: I/O write bytes / time
705,794,048 / 1,978 03:23:35 INFO - TinderboxPrint: CPU idle
2,214.1 (45.8%) 03:23:35 INFO - TinderboxPrint: CPU system
978.9 (20.2%) 03:23:35 INFO - TinderboxPrint: CPU user
1,644.6 (34.0%) 03:23:35 INFO - TinderboxPrint: Swap in / out
408,514,560 / 0 03:23:35 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392049152; Write bytes: 386180608; Read time: 29131; Write time: 800 03:23:35 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 1073664; Write bytes: 296389120; Read time: 13; Write time: 1133 03:23:35 INFO - Running post-run listener: _upload_blobber_files 03:23:35 INFO - Blob upload gear active. 03:23:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:23:35 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 03:23:35 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'] 03:23:35 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 03:23:35 INFO - (blobuploader) - INFO - Open directory for files ... 03:23:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:23:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:37 INFO - (blobuploader) - INFO - Done attempting. 03:23:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:23:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:23:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:38 INFO - (blobuploader) - INFO - Done attempting. 03:23:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:23:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:23:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:40 INFO - (blobuploader) - INFO - Done attempting. 03:23:40 INFO - (blobuploader) - INFO - Iteration through files over. 03:23:40 INFO - Return code: 0 03:23:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:23:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:23:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9097ce69e326e093b5058114114000ba5a439f9b1ebbdcf32a03316ab2bf71ec0f13200ea7be3bffb69d27e9d799db461f2dc0d954bc192640dbdc6e2f9e30c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ebd94fc44ba3116d460fc2d6118bc8dc1e5ccd0dcf98164b6580b1409cde607fe716e2b412e20b75ddba2c853f4821241a28501885bd844c7e27e2b6d20997e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82868c52b5440d7bc4968a689ff22713be2674d911725b3963dc076d127d9de8bece2a8df18605f6bdb2cb27346db3152475e38442568b663acf68a88da7b924"} 03:23:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:23:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:23:40 INFO - Contents: 03:23:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9097ce69e326e093b5058114114000ba5a439f9b1ebbdcf32a03316ab2bf71ec0f13200ea7be3bffb69d27e9d799db461f2dc0d954bc192640dbdc6e2f9e30c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ebd94fc44ba3116d460fc2d6118bc8dc1e5ccd0dcf98164b6580b1409cde607fe716e2b412e20b75ddba2c853f4821241a28501885bd844c7e27e2b6d20997e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82868c52b5440d7bc4968a689ff22713be2674d911725b3963dc076d127d9de8bece2a8df18605f6bdb2cb27346db3152475e38442568b663acf68a88da7b924"} 03:23:40 INFO - Running post-run listener: copy_logs_to_upload_dir 03:23:40 INFO - Copying logs to upload dir... 03:23:40 INFO - mkdir: /builds/slave/test/build/upload/logs 03:23:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.629504 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-27 03:23:40.783188) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 03:23:40.790481) ========= 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.x1H2zVQdXC/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.93JSvAatgr/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9097ce69e326e093b5058114114000ba5a439f9b1ebbdcf32a03316ab2bf71ec0f13200ea7be3bffb69d27e9d799db461f2dc0d954bc192640dbdc6e2f9e30c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ebd94fc44ba3116d460fc2d6118bc8dc1e5ccd0dcf98164b6580b1409cde607fe716e2b412e20b75ddba2c853f4821241a28501885bd844c7e27e2b6d20997e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82868c52b5440d7bc4968a689ff22713be2674d911725b3963dc076d127d9de8bece2a8df18605f6bdb2cb27346db3152475e38442568b663acf68a88da7b924"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010858 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467668937/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9097ce69e326e093b5058114114000ba5a439f9b1ebbdcf32a03316ab2bf71ec0f13200ea7be3bffb69d27e9d799db461f2dc0d954bc192640dbdc6e2f9e30c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ebd94fc44ba3116d460fc2d6118bc8dc1e5ccd0dcf98164b6580b1409cde607fe716e2b412e20b75ddba2c853f4821241a28501885bd844c7e27e2b6d20997e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82868c52b5440d7bc4968a689ff22713be2674d911725b3963dc076d127d9de8bece2a8df18605f6bdb2cb27346db3152475e38442568b663acf68a88da7b924"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 03:23:40.829840) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:23:40.830199) ========= 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.x1H2zVQdXC/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.93JSvAatgr/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.004990 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:23:40.878798) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 03:23:40.882179) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 03:23:40.882556) ========= ========= Total master_lag: 0.38 =========