builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0386 starttime: 1467634860.59 results: success (0) buildid: 20160630025457 builduid: a2370907a98d4fac9592ebd1dbb1dd73 revision: d700dc054751333e0735f975fce3d3adf153c62a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:00.585525) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:00.591458) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:00.591751) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:00.720844) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:00.721260) ========= 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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-04 05:21:00-- 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% 19.1M=0.001s 2016-07-04 05:21:00 (19.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.192782 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:00.967171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:00.967503) ========= 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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.025934 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:01.110970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:01.114331) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d700dc054751333e0735f975fce3d3adf153c62a --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 d700dc054751333e0735f975fce3d3adf153c62a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-04 05:21:01,203 truncating revision to first 12 chars 2016-07-04 05:21:01,203 Setting DEBUG logging. 2016-07-04 05:21:01,203 attempt 1/10 2016-07-04 05:21:01,203 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d700dc054751?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 05:21:01,629 unpacking tar archive at: mozilla-central-d700dc054751/testing/mozharness/ program finished with exit code 0 elapsedTime=0.756609 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:01.902933) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:01.903245) ========= script_repo_revision: d700dc054751333e0735f975fce3d3adf153c62a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:01.906396) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:01.906678) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 05:21:01.954341) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-04 05:21:01.954751) ========= /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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:21:02 INFO - MultiFileLogger online at 20160704 05:21:02 in /builds/slave/test 05:21:02 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 05:21:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:02 INFO - {'append_to_log': False, 05:21:02 INFO - 'base_work_dir': '/builds/slave/test', 05:21:02 INFO - 'blob_upload_branch': 'Firefox', 05:21:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:02 INFO - 'branch': 'Firefox', 05:21:02 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:02 INFO - 'config_files': ('talos/mac_config.py',), 05:21:02 INFO - 'default_actions': ('clobber', 05:21:02 INFO - 'read-buildbot-config', 05:21:02 INFO - 'download-and-extract', 05:21:02 INFO - 'populate-webroot', 05:21:02 INFO - 'create-virtualenv', 05:21:02 INFO - 'install', 05:21:02 INFO - 'run-tests'), 05:21:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:02 INFO - 'download_minidump_stackwalk': True, 05:21:02 INFO - 'download_symbols': 'ondemand', 05:21:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:02 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:02 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:02 INFO - 'installer_path': 'installer.exe', 05:21:02 INFO - 'log_level': 'info', 05:21:02 INFO - 'log_name': 'talos', 05:21:02 INFO - 'log_to_console': True, 05:21:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:21:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:21:02 INFO - 'opt_config_files': (), 05:21:02 INFO - 'pip_index': False, 05:21:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:02 INFO - 'cmd': ('bash', 05:21:02 INFO - '-c', 05:21:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:02 INFO - 'enabled': True, 05:21:02 INFO - 'halt_on_failure': False, 05:21:02 INFO - 'name': 'check_screen_resolution'},), 05:21:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:02 INFO - 'cmd': ('bash', 05:21:02 INFO - '-c', 05:21:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:02 INFO - 'enabled': True, 05:21:02 INFO - 'halt_on_failure': False, 05:21:02 INFO - 'name': 'check_screen_resolution'},), 05:21:02 INFO - 'run_cmd_checks_enabled': True, 05:21:02 INFO - 'sps_profile': False, 05:21:02 INFO - 'sps_profile_interval': 0, 05:21:02 INFO - 'suite': 'dromaeojs-e10s', 05:21:02 INFO - 'system_bits': '32', 05:21:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:02 INFO - 'title': 't-yosemite-r7-0386', 05:21:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:02 INFO - 'use_talos_json': True, 05:21:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:02 INFO - 'work_dir': 'build'} 05:21:02 INFO - ##### 05:21:02 INFO - ##### Running clobber step. 05:21:02 INFO - ##### 05:21:02 INFO - Running pre-action listener: _resource_record_pre_action 05:21:02 INFO - Running main action method: clobber 05:21:02 INFO - rmtree: /builds/slave/test/build 05:21:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:04 INFO - Running post-action listener: _resource_record_post_action 05:21:04 INFO - ##### 05:21:04 INFO - ##### Finished clobber step (success) 05:21:04 INFO - ##### 05:21:04 INFO - ##### 05:21:04 INFO - ##### Running read-buildbot-config step. 05:21:04 INFO - ##### 05:21:04 INFO - Running pre-action listener: _resource_record_pre_action 05:21:04 INFO - Running main action method: read_buildbot_config 05:21:04 INFO - Using buildbot properties: 05:21:04 INFO - { 05:21:04 INFO - "project": "", 05:21:04 INFO - "product": "firefox", 05:21:04 INFO - "script_repo_revision": "production", 05:21:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:21:04 INFO - "repository": "", 05:21:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:21:04 INFO - "buildid": "20160630025457", 05:21:04 INFO - "pgo_build": "False", 05:21:04 INFO - "basedir": "/builds/slave/test", 05:21:04 INFO - "buildnumber": 1, 05:21:04 INFO - "slavename": "t-yosemite-r7-0386", 05:21:04 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:21:04 INFO - "platform": "yosemite_r7", 05:21:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:21:04 INFO - "branch": "mozilla-central", 05:21:04 INFO - "repo_path": "mozilla-central", 05:21:04 INFO - "revision": "d700dc054751333e0735f975fce3d3adf153c62a", 05:21:04 INFO - "stage_platform": "macosx64", 05:21:04 INFO - "builduid": "a2370907a98d4fac9592ebd1dbb1dd73", 05:21:04 INFO - "slavebuilddir": "test" 05:21:04 INFO - } 05:21:04 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg. 05:21:04 INFO - Running post-action listener: _resource_record_post_action 05:21:04 INFO - ##### 05:21:04 INFO - ##### Finished read-buildbot-config step (success) 05:21:04 INFO - ##### 05:21:04 INFO - ##### 05:21:04 INFO - ##### Running download-and-extract step. 05:21:04 INFO - ##### 05:21:04 INFO - Running pre-action listener: _resource_record_pre_action 05:21:04 INFO - Running main action method: download_and_extract 05:21:04 INFO - mkdir: /builds/slave/test/build/tests 05:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.test_packages.json 05:21:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 05:21:04 INFO - Downloaded 1199 bytes. 05:21:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:21:04 INFO - Using the following test package requirements: 05:21:04 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:21:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:21:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:21:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'jsshell-mac.zip'], 05:21:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:21:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:21:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:21:04 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:21:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:21:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:21:04 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:21:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.common.tests.zip 05:21:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:21:04 INFO - Downloaded 18788471 bytes. 05:21:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:21:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:21:08 INFO - Return code: 0 05:21:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.common.tests.zip 05:21:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:21:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:21:08 INFO - Downloaded 18788471 bytes. 05:21:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:21:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:21:11 INFO - Return code: 0 05:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:11 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.talos.tests.zip 05:21:11 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:21:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:21:16 INFO - Downloaded 14405245 bytes. 05:21:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:21:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:21:16 INFO - Return code: 0 05:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg 05:21:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:21:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 05:21:24 INFO - Downloaded 112479543 bytes. 05:21:24 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg 05:21:24 INFO - mkdir: /builds/slave/test/properties 05:21:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:21:24 INFO - Writing to file /builds/slave/test/properties/build_url 05:21:24 INFO - Contents: 05:21:24 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg 05:21:24 INFO - Running post-action listener: _resource_record_post_action 05:21:24 INFO - Running post-action listener: set_extra_try_arguments 05:21:24 INFO - ##### 05:21:24 INFO - ##### Finished download-and-extract step (success) 05:21:24 INFO - ##### 05:21:24 INFO - ##### 05:21:24 INFO - ##### Running populate-webroot step. 05:21:24 INFO - ##### 05:21:24 INFO - Running pre-action listener: _resource_record_pre_action 05:21:24 INFO - Running main action method: populate_webroot 05:21:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:21:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:21:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:21:24 INFO - '--tppagecycles', 05:21:24 INFO - '7'], 05:21:24 INFO - 'tests': ['tsvgm']}}, 05:21:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:21:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:21:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:21:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:21:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['damp', 'tps']}, 05:21:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'tests': ['damp', 'tps']}, 05:21:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['dromaeo_dom']}, 05:21:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:21:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['basic_compositor_video']}, 05:21:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:21:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['a11yr', 05:21:24 INFO - 'ts_paint', 05:21:24 INFO - 'tpaint', 05:21:24 INFO - 'sessionrestore', 05:21:24 INFO - 'sessionrestore_no_auto_restore', 05:21:24 INFO - 'tabpaint']}, 05:21:24 INFO - 'other-e10s': {'tests': ['a11yr', 05:21:24 INFO - 'ts_paint', 05:21:24 INFO - 'tpaint', 05:21:24 INFO - 'sessionrestore', 05:21:24 INFO - 'sessionrestore_no_auto_restore', 05:21:24 INFO - 'tabpaint']}, 05:21:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:21:24 INFO - 'ts_paint', 05:21:24 INFO - 'tpaint', 05:21:24 INFO - 'sessionrestore', 05:21:24 INFO - 'sessionrestore_no_auto_restore', 05:21:24 INFO - 'tabpaint']}, 05:21:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:21:24 INFO - 'ts_paint', 05:21:24 INFO - 'tpaint', 05:21:24 INFO - 'sessionrestore', 05:21:24 INFO - 'sessionrestore_no_auto_restore', 05:21:24 INFO - 'tabpaint']}, 05:21:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['a11yr', 05:21:24 INFO - 'ts_paint', 05:21:24 INFO - 'tpaint', 05:21:24 INFO - 'sessionrestore', 05:21:24 INFO - 'sessionrestore_no_auto_restore', 05:21:24 INFO - 'tabpaint']}, 05:21:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['a11yr', 05:21:24 INFO - 'ts_paint', 05:21:24 INFO - 'tpaint', 05:21:24 INFO - 'sessionrestore', 05:21:24 INFO - 'sessionrestore_no_auto_restore', 05:21:24 INFO - 'tabpaint']}, 05:21:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['tsvgx', 05:21:24 INFO - 'tsvgr_opacity', 05:21:24 INFO - 'tart', 05:21:24 INFO - 'tscrollx', 05:21:24 INFO - 'cart']}, 05:21:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:21:24 INFO - 'tsvgr_opacity', 05:21:24 INFO - 'tart', 05:21:24 INFO - 'tscrollx', 05:21:24 INFO - 'cart']}, 05:21:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'talos_options': ['--disable-e10s'], 05:21:24 INFO - 'tests': ['tp5o']}, 05:21:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'tests': ['tp5o']}, 05:21:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'talos_options': ['--disable-e10s', 05:21:24 INFO - '--xperf_path', 05:21:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:24 INFO - 'tests': ['tp5n']}, 05:21:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:24 INFO - 'talos_options': ['--xperf_path', 05:21:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:24 INFO - 'tests': ['tp5n']}}, 05:21:24 INFO - 'talos.zip': {'path': '', 05:21:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:21:24 INFO - Running post-action listener: _resource_record_post_action 05:21:24 INFO - ##### 05:21:24 INFO - ##### Finished populate-webroot step (success) 05:21:24 INFO - ##### 05:21:24 INFO - ##### 05:21:24 INFO - ##### Running create-virtualenv step. 05:21:24 INFO - ##### 05:21:24 INFO - Running pre-action listener: _resource_record_pre_action 05:21:24 INFO - Running main action method: create_virtualenv 05:21:24 INFO - Creating virtualenv /builds/slave/test/build/venv 05:21:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:21:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:21:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:21:24 INFO - Using real prefix '/tools/python27' 05:21:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:21:25 INFO - Installing distribute.............................................................................................................................................................................................done. 05:21:28 INFO - Installing pip.................done. 05:21:28 INFO - Return code: 0 05:21:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:28 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:28 INFO - 'HOME': '/Users/cltbld', 05:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:28 INFO - 'LOGNAME': 'cltbld', 05:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:28 INFO - 'MOZ_NO_REMOTE': '1', 05:21:28 INFO - 'NO_EM_RESTART': '1', 05:21:28 INFO - 'PAGER': '/bin/cat', 05:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:28 INFO - 'PWD': '/builds/slave/test', 05:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:28 INFO - 'SHELL': '/bin/bash', 05:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:28 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:28 INFO - 'USER': 'cltbld', 05:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:28 INFO - 'XPC_FLAGS': '0x0', 05:21:28 INFO - 'XPC_SERVICE_NAME': '0', 05:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:21:28 INFO - Cleaning up... 05:21:28 INFO - Return code: 0 05:21:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:28 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:28 INFO - 'HOME': '/Users/cltbld', 05:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:28 INFO - 'LOGNAME': 'cltbld', 05:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:28 INFO - 'MOZ_NO_REMOTE': '1', 05:21:28 INFO - 'NO_EM_RESTART': '1', 05:21:28 INFO - 'PAGER': '/bin/cat', 05:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:28 INFO - 'PWD': '/builds/slave/test', 05:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:28 INFO - 'SHELL': '/bin/bash', 05:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:28 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:28 INFO - 'USER': 'cltbld', 05:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:28 INFO - 'XPC_FLAGS': '0x0', 05:21:28 INFO - 'XPC_SERVICE_NAME': '0', 05:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:28 INFO - Downloading/unpacking psutil>=3.1.1 05:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:21:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:32 INFO - Installing collected packages: psutil 05:21:32 INFO - Running setup.py install for psutil 05:21:32 INFO - building 'psutil._psutil_osx' extension 05:21:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:21:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:21:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:21:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:21:33 INFO - building 'psutil._psutil_posix' extension 05:21:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:21:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:21:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:21:33 INFO - ^ 05:21:33 INFO - 1 warning generated. 05:21:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:21:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:33 INFO - Successfully installed psutil 05:21:33 INFO - Cleaning up... 05:21:33 INFO - Return code: 0 05:21:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:33 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:33 INFO - 'HOME': '/Users/cltbld', 05:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:33 INFO - 'LOGNAME': 'cltbld', 05:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:33 INFO - 'MOZ_NO_REMOTE': '1', 05:21:33 INFO - 'NO_EM_RESTART': '1', 05:21:33 INFO - 'PAGER': '/bin/cat', 05:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:33 INFO - 'PWD': '/builds/slave/test', 05:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:33 INFO - 'SHELL': '/bin/bash', 05:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:33 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:33 INFO - 'USER': 'cltbld', 05:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:33 INFO - 'XPC_FLAGS': '0x0', 05:21:33 INFO - 'XPC_SERVICE_NAME': '0', 05:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:21:36 INFO - Installing collected packages: mozsystemmonitor 05:21:36 INFO - Running setup.py install for mozsystemmonitor 05:21:36 INFO - Successfully installed mozsystemmonitor 05:21:36 INFO - Cleaning up... 05:21:36 INFO - Return code: 0 05:21:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:36 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:21:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:36 INFO - 'HOME': '/Users/cltbld', 05:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:36 INFO - 'LOGNAME': 'cltbld', 05:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:36 INFO - 'MOZ_NO_REMOTE': '1', 05:21:36 INFO - 'NO_EM_RESTART': '1', 05:21:36 INFO - 'PAGER': '/bin/cat', 05:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:36 INFO - 'PWD': '/builds/slave/test', 05:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:36 INFO - 'SHELL': '/bin/bash', 05:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:36 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:36 INFO - 'USER': 'cltbld', 05:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:36 INFO - 'XPC_FLAGS': '0x0', 05:21:36 INFO - 'XPC_SERVICE_NAME': '0', 05:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:21:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:39 INFO - Downloading functools32-3.2.3-2.tar.gz 05:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:21:39 INFO - warning: no files found matching '*.txt' 05:21:39 INFO - no previously-included directories found matching 'build' 05:21:39 INFO - no previously-included directories found matching 'dist' 05:21:39 INFO - no previously-included directories found matching '.git*' 05:21:39 INFO - Installing collected packages: jsonschema, functools32 05:21:39 INFO - Running setup.py install for functools32 05:21:40 INFO - warning: no files found matching '*.txt' 05:21:40 INFO - no previously-included directories found matching 'build' 05:21:40 INFO - no previously-included directories found matching 'dist' 05:21:40 INFO - no previously-included directories found matching '.git*' 05:21:40 INFO - Successfully installed jsonschema functools32 05:21:40 INFO - Cleaning up... 05:21:40 INFO - Return code: 0 05:21:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:40 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:40 INFO - 'HOME': '/Users/cltbld', 05:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:40 INFO - 'LOGNAME': 'cltbld', 05:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:40 INFO - 'MOZ_NO_REMOTE': '1', 05:21:40 INFO - 'NO_EM_RESTART': '1', 05:21:40 INFO - 'PAGER': '/bin/cat', 05:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:40 INFO - 'PWD': '/builds/slave/test', 05:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:40 INFO - 'SHELL': '/bin/bash', 05:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:40 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:40 INFO - 'USER': 'cltbld', 05:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:40 INFO - 'XPC_FLAGS': '0x0', 05:21:40 INFO - 'XPC_SERVICE_NAME': '0', 05:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:42 INFO - Downloading blobuploader-1.2.4.tar.gz 05:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:21:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:21:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:44 INFO - Downloading docopt-0.6.1.tar.gz 05:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:21:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:21:44 INFO - Running setup.py install for blobuploader 05:21:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:21:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:21:44 INFO - Running setup.py install for requests 05:21:44 INFO - Running setup.py install for docopt 05:21:44 INFO - Successfully installed blobuploader requests docopt 05:21:44 INFO - Cleaning up... 05:21:44 INFO - Return code: 0 05:21:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:44 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:44 INFO - 'HOME': '/Users/cltbld', 05:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:44 INFO - 'LOGNAME': 'cltbld', 05:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:44 INFO - 'MOZ_NO_REMOTE': '1', 05:21:44 INFO - 'NO_EM_RESTART': '1', 05:21:44 INFO - 'PAGER': '/bin/cat', 05:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:44 INFO - 'PWD': '/builds/slave/test', 05:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:44 INFO - 'SHELL': '/bin/bash', 05:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:44 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:44 INFO - 'USER': 'cltbld', 05:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:44 INFO - 'XPC_FLAGS': '0x0', 05:21:44 INFO - 'XPC_SERVICE_NAME': '0', 05:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-3_KqmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Z73vrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-SIpV1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-xF9CHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-MqSPEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-KwvMFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-iQqTaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:21:45 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-AcmR6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-UmDcDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ehSXX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-rXsaQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-_P0MsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-wtWM29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-x_yP7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-gOvbyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-O8ZLEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:21:46 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-M83bi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:21:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:21:47 INFO - Running setup.py install for manifestparser 05:21:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:21:47 INFO - Running setup.py install for mozcrash 05:21:47 INFO - Running setup.py install for mozdebug 05:21:47 INFO - Running setup.py install for mozdevice 05:21:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:21:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:21:47 INFO - Running setup.py install for mozfile 05:21:47 INFO - Running setup.py install for mozhttpd 05:21:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:21:47 INFO - Running setup.py install for mozinfo 05:21:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Running setup.py install for mozInstall 05:21:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Running setup.py install for mozleak 05:21:48 INFO - Running setup.py install for mozlog 05:21:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Running setup.py install for moznetwork 05:21:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Running setup.py install for mozprocess 05:21:48 INFO - Running setup.py install for mozprofile 05:21:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:21:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:21:49 INFO - Running setup.py install for mozrunner 05:21:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:21:49 INFO - Running setup.py install for mozscreenshot 05:21:49 INFO - Running setup.py install for moztest 05:21:49 INFO - Running setup.py install for mozversion 05:21:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:21:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:21:49 INFO - Cleaning up... 05:21:49 INFO - Return code: 0 05:21:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21: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')]} 05:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21: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')]} 05:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:49 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:49 INFO - 'HOME': '/Users/cltbld', 05:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:49 INFO - 'LOGNAME': 'cltbld', 05:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:49 INFO - 'MOZ_NO_REMOTE': '1', 05:21:49 INFO - 'NO_EM_RESTART': '1', 05:21:49 INFO - 'PAGER': '/bin/cat', 05:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:49 INFO - 'PWD': '/builds/slave/test', 05:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:49 INFO - 'SHELL': '/bin/bash', 05:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:49 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:49 INFO - 'USER': 'cltbld', 05:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:49 INFO - 'XPC_FLAGS': '0x0', 05:21:49 INFO - 'XPC_SERVICE_NAME': '0', 05:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:49 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Wpcx4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-8PFFS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-gcvY5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-mNMGaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-pIdrfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-gTNr0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-X2EVad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-sfLDin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-5hFV7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-XZed2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:21:50 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-tEMsK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:21:51 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-YGGhDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:21:51 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)) 05:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:21:51 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7sdHos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:21:51 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-vXVgBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:21:51 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)) 05:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:51 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-EAxBlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:21:51 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-OR03ZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:21:51 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-gpIHyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:21:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:54 INFO - Downloading blessings-1.6.tar.gz 05:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:21:54 INFO - Installing collected packages: blessings 05:21:54 INFO - Running setup.py install for blessings 05:21:54 INFO - Successfully installed blessings 05:21:54 INFO - Cleaning up... 05:21:54 INFO - Return code: 0 05:21:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:21:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:54 INFO - Reading from file tmpfile_stdout 05:21:54 INFO - Current package versions: 05:21:54 INFO - blessings == 1.6 05:21:54 INFO - blobuploader == 1.2.4 05:21:54 INFO - docopt == 0.6.1 05:21:54 INFO - functools32 == 3.2.3-2 05:21:54 INFO - jsonschema == 2.5.1 05:21:54 INFO - manifestparser == 1.1 05:21:54 INFO - mozInstall == 1.12 05:21:54 INFO - mozcrash == 0.17 05:21:54 INFO - mozdebug == 0.1 05:21:54 INFO - mozdevice == 0.48 05:21:54 INFO - mozfile == 1.2 05:21:54 INFO - mozhttpd == 0.7 05:21:54 INFO - mozinfo == 0.9 05:21:54 INFO - mozleak == 0.1 05:21:54 INFO - mozlog == 3.2 05:21:54 INFO - moznetwork == 0.27 05:21:54 INFO - mozprocess == 0.22 05:21:54 INFO - mozprofile == 0.28 05:21:54 INFO - mozrunner == 6.11 05:21:54 INFO - mozscreenshot == 0.1 05:21:54 INFO - mozsystemmonitor == 0.3 05:21:54 INFO - moztest == 0.7 05:21:54 INFO - mozversion == 1.4 05:21:54 INFO - psutil == 3.1.1 05:21:54 INFO - requests == 1.2.3 05:21:54 INFO - wsgiref == 0.1.2 05:21:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:54 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:54 INFO - 'HOME': '/Users/cltbld', 05:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:54 INFO - 'LOGNAME': 'cltbld', 05:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:54 INFO - 'MOZ_NO_REMOTE': '1', 05:21:54 INFO - 'NO_EM_RESTART': '1', 05:21:54 INFO - 'PAGER': '/bin/cat', 05:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:54 INFO - 'PWD': '/builds/slave/test', 05:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:54 INFO - 'SHELL': '/bin/bash', 05:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:54 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:54 INFO - 'USER': 'cltbld', 05:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:54 INFO - 'XPC_FLAGS': '0x0', 05:21:54 INFO - 'XPC_SERVICE_NAME': '0', 05:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:21:55 INFO - Cleaning up... 05:21:55 INFO - Return code: 0 05:21:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:55 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 0x10d6faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d78f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe439e949a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, '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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:55 INFO - 'HOME': '/Users/cltbld', 05:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:55 INFO - 'LOGNAME': 'cltbld', 05:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:55 INFO - 'MOZ_NO_REMOTE': '1', 05:21:55 INFO - 'NO_EM_RESTART': '1', 05:21:55 INFO - 'PAGER': '/bin/cat', 05:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:55 INFO - 'PWD': '/builds/slave/test', 05:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:55 INFO - 'SHELL': '/bin/bash', 05:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:21:55 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:55 INFO - 'USER': 'cltbld', 05:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:55 INFO - 'XPC_FLAGS': '0x0', 05:21:55 INFO - 'XPC_SERVICE_NAME': '0', 05:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:21:55 INFO - Cleaning up... 05:21:55 INFO - Return code: 0 05:21:55 INFO - Running post-action listener: _resource_record_post_action 05:21:55 INFO - Running post-action listener: _start_resource_monitoring 05:21:55 INFO - Starting resource monitoring. 05:21:55 INFO - ##### 05:21:55 INFO - ##### Finished create-virtualenv step (success) 05:21:55 INFO - ##### 05:21:55 INFO - ##### 05:21:55 INFO - ##### Running install step. 05:21:55 INFO - ##### 05:21:55 INFO - Running pre-action listener: _resource_record_pre_action 05:21:55 INFO - Running main action method: install 05:21:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:55 INFO - Reading from file tmpfile_stdout 05:21:55 INFO - Detecting whether we're running mozinstall >=1.0... 05:21:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:21:55 INFO - Reading from file tmpfile_stdout 05:21:55 INFO - Output received: 05:21:55 INFO - Usage: mozinstall [options] installer 05:21:55 INFO - Options: 05:21:55 INFO - -h, --help show this help message and exit 05:21:55 INFO - -d DEST, --destination=DEST 05:21:55 INFO - Directory to install application into. [default: 05:21:55 INFO - "/builds/slave/test"] 05:21:55 INFO - --app=APP Application being installed. [default: firefox] 05:21:55 INFO - mkdir: /builds/slave/test/build/application 05:21:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:22:29 INFO - Reading from file tmpfile_stdout 05:22:29 INFO - Output received: 05:22:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:22:29 INFO - Running post-action listener: _resource_record_post_action 05:22:29 INFO - ##### 05:22:29 INFO - ##### Finished install step (success) 05:22:29 INFO - ##### 05:22:29 INFO - ##### 05:22:29 INFO - ##### Running run-tests step. 05:22:29 INFO - ##### 05:22:29 INFO - Running pre-action listener: _resource_record_pre_action 05:22:29 INFO - Running main action method: run_tests 05:22:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:22:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:22:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:22:29 INFO - 2016-07-04 05:22:29.988 screenresolution[1655:10178] starting screenresolution argv=screenresolution get 05:22:29 INFO - 2016-07-04 05:22:29.990 screenresolution[1655:10178] Display 0: 1600x1200x32@60 05:22:29 INFO - 2016-07-04 05:22:29.996 screenresolution[1656:10180] starting screenresolution argv=screenresolution list 05:22:29 INFO - Available Modes on Display 0 05:22:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:22:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:22:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:22:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:22:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:22:30 INFO - Intel Iris: 05:22:30 INFO - Chipset Model: Intel Iris 05:22:30 INFO - Type: GPU 05:22:30 INFO - Bus: Built-In 05:22:30 INFO - VRAM (Dynamic, Max): 1536 MB 05:22:30 INFO - Vendor: Intel (0x8086) 05:22:30 INFO - Device ID: 0x0a2e 05:22:30 INFO - Revision ID: 0x0009 05:22:30 INFO - Displays: 05:22:30 INFO - Display: 05:22:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:22:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:22:30 INFO - Display Serial Number: 1600x1200 60 05:22:30 INFO - Main Display: Yes 05:22:30 INFO - Mirror: Off 05:22:30 INFO - Online: Yes 05:22:30 INFO - Rotation: Supported 05:22:30 INFO - Return code: 0 05:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:22:30 INFO - Python 2.7.3 05:22:30 INFO - Return code: 0 05:22:30 INFO - grabbing minidump binary from tooltool 05:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:30 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 0x10d65ed30>, '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 0x10d3c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d656b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:22:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:22:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:22:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:22:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:22:30 INFO - Return code: 0 05:22:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:22:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:22:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:22:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:22:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:22:30 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/1467280497/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--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 05:22:30 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/1467280497/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0386 --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 05:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HDeRJt0Kv/Render', 05:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:30 INFO - 'HOME': '/Users/cltbld', 05:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:30 INFO - 'LOGNAME': 'cltbld', 05:22:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:22:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:30 INFO - 'MOZ_NO_REMOTE': '1', 05:22:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:22:30 INFO - 'NO_EM_RESTART': '1', 05:22:30 INFO - 'PAGER': '/bin/cat', 05:22:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:30 INFO - 'PWD': '/builds/slave/test', 05:22:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:30 INFO - 'SHELL': '/bin/bash', 05:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EDNCcHyZpq/Listeners', 05:22:30 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 05:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:30 INFO - 'USER': 'cltbld', 05:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:30 INFO - 'XPC_FLAGS': '0x0', 05:22:30 INFO - 'XPC_SERVICE_NAME': '0', 05:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:30 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/1467280497/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--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 05:22:30 INFO - mozversion application_buildid: 20160630025457 05:22:30 INFO - mozversion application_changeset: d700dc054751333e0735f975fce3d3adf153c62a 05:22:30 INFO - mozversion application_display_name: Nightly 05:22:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:22:30 INFO - mozversion application_name: Firefox 05:22:30 INFO - mozversion application_remotingname: firefox 05:22:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:22:30 INFO - mozversion application_vendor: Mozilla 05:22:30 INFO - mozversion application_version: 50.0a1 05:22:30 INFO - mozversion platform_buildid: 20160630025457 05:22:30 INFO - mozversion platform_changeset: d700dc054751333e0735f975fce3d3adf153c62a 05:22:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:22:30 INFO - mozversion platform_version: 50.0a1 05:22:30 INFO - using testdate: 1467634950 05:22:30 INFO - actual date: 1467634950 05:22:30 INFO - starting webserver on 'localhost:49265' 05:22:30 INFO - SUITE-START | Running 2 tests 05:22:30 INFO - TEST-START | dromaeo_css 05:22:30 INFO - operating with platform_type : mac_ 05:22:30 INFO - Initialising browser for dromaeo_css test... 05:22:30 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp3cMi94/profile http://localhost:49265/getInfo.html) 05:22:33 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 05:22:33 INFO - PROCESS | 1666 | colorDepth:24 05:22:33 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 05:22:33 INFO - PROCESS | 1666 | __metrics 05:22:33 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:22:34 INFO - TEST-INFO | 1666: exit 0 05:22:34 INFO - Browser initialized. 05:22:34 INFO - Running cycle 1/1 for dromaeo_css test... 05:22:34 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp3cMi94/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:46 INFO - PROCESS | 1670 | RSS: Main: 255873024 05:22:46 INFO - PROCESS | 1670 | 05:23:36 INFO - PROCESS | 1670 | 1467635016038 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:25:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 05:25:48 INFO - PROCESS | 1670 | RSS: Main: 269549568 05:25:48 INFO - PROCESS | 1670 | 05:28:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 05:28:51 INFO - PROCESS | 1670 | RSS: Main: 276840448 05:28:51 INFO - PROCESS | 1670 | 05:31:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 05:31:53 INFO - PROCESS | 1670 | RSS: Main: 281464832 05:31:53 INFO - PROCESS | 1670 | 05:34:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 05:34:56 INFO - PROCESS | 1670 | RSS: Main: 280162304 05:34:56 INFO - PROCESS | 1670 | 05:37:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 05:37:59 INFO - PROCESS | 1670 | RSS: Main: 284790784 05:37:59 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 05:41:02 INFO - PROCESS | 1670 | RSS: Main: 285114368 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | __start_tp_report 05:41:02 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 05:41:02 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 05:41:02 INFO - PROCESS | 1670 | |i|pagename|runs| 05:41:02 INFO - PROCESS | 1670 | |0;dojo.html;3392.7116195123403;3413;3426.79439233646;3428.708559772419;3499.8600055997745;5070.264811602325;5710.657995371084;5947;6045.607035542692;6049.758009679618;5798.82603521894;5809.419058094199;5837;5839.5620328475425;5842.737076831542;5206.8698282542955;5252.474752524736;5352;5360.3299587551555;5394.136938089887;215.05982337592064;5355.7857685692525;5364.409914909357;5372.435894231108;5418.810341638044;5361.919571206435;5363.463653634644;5393.5954803389905;5420.972895135499;5430.104032834597;5297.735113244323;5442.319710036246;5449.56403487724;5461.42655021225;5476.3702174250075;5260.552853007499;5306.97346513265;5336.199570064483;5347.1177255752755;5393.7303134843105;5785.30576330843;5847.210626565445;5991.4907232884825;6034.6982650867285;6048.637081775064;5344.599155063386;5868.383819698955;6182.84542886427;6235.844103897393;6334.619922804646;6234.812955611339;6365.522585806083;6390.840228994266;6394.296627370985;6413.615183089029;6082.635041897501;6102.908456373158;6106.603070800473;6114.694265286718;6146.231721034871;5490.890182196334;6294.653794041372;6397.6481293529305;6514.967425162844;6516.446102081281;6175.876482470326;6225.346338634378;6243;6249.937500624936;6253.093301471261;6375.362463753588;6397.424231819065;6397.61614303134;6411.839704007437;6430.742770289229;6111.743676692077;6206.937930620727;6214.223222097238;6236.6258024518675;6263.592866463757;4620.052889157714;5489.819688766935;6132.967444181115;6174.38256174388;6177.2587289524645;4652.1626107300335;5081.2632168335385;5095.745212739348;5100.081985242692;5149.665271757323;3792.810359482015;4481.126180394857;4512.435945506812;4528.705634133837;4569.702969306984;5479.506844384025;5968.104784282305;6027.60820546663;6055.333913269536;6059.848503787441;5331.733413329318;6311.211098612674;6335.239771227482;6340.175777149033;6357.1735674362035;5919.704014799243;6019.60872543292;6023.849403764853;6085.57400981927;6098.69506524672;5642.548596112301;5644.125160600113;5652.3217213934595;5666.546676265889;5676.4323567642905;6348.269948955929;6592.373724496165;6607.537472376984;6620.271770105188;6625.734970601218;6295.842603934755;6358.872822543616;6381.8085457436355;6443.484521238196;6444.420002199825;6565.835854103686;6605.669716514251;6640.369164929324;6642.368974947372;6642.368974947372;6112.929053818996;6213.409726076015;6302.369763023661;6338.841528961629;6358.2687990879795;4967;5686.4029276926985;6424.164858568356;6432.13166222554;6460.483161346987;6195.10586636552;6209.534284928739;6222.595531290452;6233.470155036873;6251.312355640966;5406.918896216638;5819.883602327845;6125.969370153299;6247.781327653511;6255.0617407387435;5057.620678449204;5983.850403739942;6031.728572214349;6122.2959359674205;6141.700809433504;6160.630362178284;6223.408776166257;6225.937740622536;6250.093736408105;6259.78090766821;6113;6145.692715364304;6169.043798211284;6248.562600617913;6250.4687101595055 05:41:02 INFO - PROCESS | 1670 | |1;ext.html;494.8268106162843;528.1628618639456;529.6054439442613;533.458539582324;571.517068077475;39296;41793.58206417935;42219;42247;42364.940876478104;42482;42946;43007.49473768419;43040.139197216035;43096.3535546967;9521.714348569554;9550.90449095507;9652.565634546463;9687.806243875119;9688.806223875517;9610.855837162448;9620.470874101922;9697.75755606112;9705.854412183822;9709.320347575673;9657.613695452208;9701.029897010278;9703.466309352983;9717.659881904136;9754.122129008389;9603.519824008772;9634.084761947604;9685.079917407835;9691.224702023821;9717.514124293828;8732.781680457976;9378.624855005859;9758.12176904075;9761.316707830454;9776.022397760238;40061.99845003869;40471.7976410119;40655.39016914749;40713.778586642446;40738;2948.778841586868;3014.351914338428;3345.749068819824;3425.72594192464;3449.758516903818;31978;32120.518192227137;32140.678593214;32372.352552949043;32508.837455812805;3028.394321135782;3268.6731326867125;3309.6690330966953;3312.3540909522653;3400.6939375456327;512640;516059;519787;525345;525352;54615;64135;64248.67875660592;64678.353216467236;64838;35162.12094697653;35476.46785298223;35671.4649280261;35750.463743043874;68851.65574172097;16153.192340382933;16320.102394368423;16405.93361431527;16420.589485262964;16538.83461165397;12401.503939842305;12460.12779105472;12462.065345099154;12480.81278780819;12489.437975291134;17057.658846822993;19479.90260048691;19782.604347912962;19809.603807923762;20103.798962010485;7635.809104772314;7953.2842044216295;7996.561478564273;8021.358291336679;8031.678732850737;835.4569529805553;982.0817535604233;1001.1190152665607;1010.8433192855119;1030.3972176276961;30571.847140764155;31383.686163138533;31400.900968465994;31729.84135079356;32741.672583273863;1010.161565900301;1047.539082803564;1065.8827528971806;1127.199688221372;1153.3656488931053;15772.605684857741;15862.92068539673;15927.522174334788;16064.79514036452;16193.271302791167;639785;643807;646005;647727;657701;49496.75251623791;58521.70739146277;58913;59016.704916475144;59083.227503174974;1364.2769332253922;1370.7258548290183;1388.8541703121027;1405.4237762517523;1408.9084209526347;26171.73828261693;30119;30121.84939075378;30193.590856048453;30469;52599.73700131475;53350.4664953361;53375.46624533705;53410.198847016545;53560.46439535711;622016;625862;641428;642534;645761;27184.456310874066;27277.59083613787;27536.76084576159;27540.44919101567;27591.724082758916;48785.756071220836;48921.510784891696;49446.752766237376;49924;50068.74828129326;39489.21021579495;39798.801005994785;40146.39780403238;41163.98486859395;41412.378814317206;36603.816980914926;39844.40233396443;40084.398734019596;40268.59731402648;41047.38428923509;33195;38385.42421863618;39097.6090239094;39125.82622521329;39199.82400527989;31102.844485777427;33299.33401332008;33568;35343.46984795179;35629.28741425209;25867.353316167246;26022.86988565045;26109.08618198354;26162.86918565395;26365.077222297128 05:41:02 INFO - PROCESS | 1670 | |2;jquery.html;358.85107680312655;371.5021870693272;374.3860069486043;378.29259285136783;387.264198023755;19957.301494447696;20366.59266814663;20823.479413014684;21398.679019814677;21408.14367425301;11574.768504629903;11981.460834262458;12038.819417708719;12061.035117190626;15346.462873799419;5365.543928766059;5522.337319521643;5556.083246264382;5561.388247292814;5564.1931919871595;3874.5544262409903;5742;6054.909176362358;6081.908771368411;6103.23709536308;5097.949020509803;5908.67502287374;5972.970135149319;6098.298695650013;6128.509719222451;5418.4039755626845;5825.446582574648;5928.88142237155;6047.637141771508;6066.090086487018;5718.914216286759;5898.292204935392;5914.112883067532;6032.638041717511;6050.334463209044;23626.64560031601;24043.759562404328;24859.62710559343;25640.48719025609;26002;2104.810567048973;2281.258590957945;2326.336993956714;2674.3314171457137;2750.2849259192553;10048.849267260997;10239.180865530814;10334.7416314592;10412.219083568687;10620.362778233331;1805.6403915200433;1887.4243355776482;1896.6680830854546;1911.5125642961095;1976.3675623800389;95906.643267486;113891;118345;119413.61172776496;126903;10330.431826249629;10596.993285637853;10846.806851246356;11737.589184378507;11741.060715142768;14694.779578306334;14800.925995369955;14863.479778207711;14868.925655371653;14897.627559311104;8157.225063619066;8690.522021288889;8773.38586298953;8828.470291782514;8835.867461988075;8613.181747733955;8630.741077767678;8637.611307491177;8737.30101591871;8748.912510874938;3758.3047136279874;4560.315952607136;4561.840335588238;4645.605123564467;4687.695299805502;1819.7907240667228;1858.2566973210824;1983.3157560641555;2037.7758446570865;2039.4493486758493;877.4954401219325;948.6300342866288;953.1945506047379;956.2636769687302;996.3324572536418;20424.183032678822;21316.934153292274;21507.247246346302;21521.246756363453;21597.46006349854;908.9954550227338;939.6711151097061;969.6751588217885;1026.1175389165312;1032.9018743219383;11256.549738010388;11300.830487542693;11337.773244535063;11349.659510214708;11419.885801142049;568127;573471;585259;588700;595948;144536.27731861483;147756.26121869322;148858.7671184933;148863.25568372305;149045;951.2532661860406;1009.1926458833051;1022.728976821143;1023.9078482936571;1040.9115225205942;15757.527274181793;18298.261665141792;18876.716849246997;19551.70672439943;19656.01719914027;50777.23834142565;51863.74068129635;53938.73030634822;54646.18030729463;54810;72406;74259.25740742523;75001.87497187438;75007;75426.24573754192;6208.2860471044605;8115.715949941724;8222.931018967496;8320.625571849403;8401.11788262248;9194.908050919405;12408.75182496363;12892.355382230664;12977.610671679864;13228.867711323148;9461;10240;11000.614978475714;11016.669499915015;11343.75218725956;10886.782264354826;11100.555977761122;11111;11135.053520450852;11287.604933827277;9704.611815527582;11221.438928053401;11237.213395062594;11314.321140731583;11382.772344552897;8124.471909325875;8174.550399728072;8199.0980992092;8306.377021723274;8389.580520973805;10089.646862359783;11218.887811122111;11284.661460156209;11326.603568875049;11346.489407976826 05:41:02 INFO - PROCESS | 1670 | |3;mootools.html;1355.6746380868592;1530.4566878758042;1564.7731078993554;1719.6818588561116;1885.4155211884308;7937.08723496798;8464.66141354345;8479.788005299863;8926.107389261071;8945.016048234696;8579.871301930476;8602.526861022641;8726.91273087269;8741.388102832805;8758.08040155784;2360.2683168217823;2382.3924899150693;2383.9761602384015;2387.1048356866177;2390.988045059772;2007.3275452723356;2355.128602417108;2374.0622454130607;2374.7387787343378;2408.987955060231;2348.635961425973;2366.3137690069857;2375.2992867104244;2381.809455243585;2386.3795413192606;1983.9504012399805;2322.175627652176;2352.0709319818748;2373.32360277321;2375.5605213035556;2348.6007378745485;2352.482453860148;2352.764723527651;2354.199572145462;2356.1871154451687;8658.826823463496;8722.956385218033;8779.429337093068;8786.121387861163;8823.338249631302;5145.845624631267;5208.374995000586;5238.554722848525;5250.159974404077;5305.124654432014;3007.6090108286003;3540.8760693375866;3667.7799332040163;3670.210904655485;3674.3386190485703;1080.9891901080966;1095.2497539185683;1103.2773533335678;1119.7648493816228;1121.0863146535548;11514.136439767051;11685.94157029218;11698.23961442512;11698.707532311675;11749.118816088741;4312.956870431318;4571.977140114278;4595.1728688835665;4614.446266447981;4774.809007639656;9648.421094734338;9838.212942964547;9851.50742462874;9861.260405469618;9872.012798720214;5284.603654725911;5301;5310.070737620901;5311.814086506954;5324.760385782649;3993.381025940984;4367.410399596078;4778.522147785194;4834.903301933941;4836.830710925101;3505.9824700876334;3593.8320045984638;3943.783091929914;3976.264391087658;4057.472528571267;1264.3109505319624;1278.5844600504872;1278.9424475898606;1297.6431481342518;1301.1022394547922;920.1028996728137;923.8429466990627;929.5259417697023;946.3580687507169;982.9705108846603;5059.241113832954;6041.124037014608;6078.817635470943;6177.567570270038;6241.407066328691;958.4488918871676;974.0940924939732;986.4525188520297;997.5211898288862;1072.9034386905216;3273.4107860585323;4003.499562554681;4105.261053010427;4152.584741525884;4164.167166566699;10668.573257069786;10757.731056723487;10779.299345542515;10870;10871.782564348669;10644.574217031386;10683.73290667724;10809.189310801565;10821.83767243492;10828.296160749525;8019.759407217793;8155.388345874083;8288.668453261922;8367.372447066495;8378.53918034514;5824.097264923944;5917.5857689961285;6015.548833837567;6045.395460453919;6118;9842.901570984484;10124.493775311057;10167.339122956984;10221.846672299773;10271.178305735373;8370.037445693579;10247.077763001365;10322.277440579088;10349.430781307101;10369.585216591251;6534.019897015486;6905.413039891465;7032.578045317297;7159.463040271896;7161.140663120459;8501.914980850112;8540.615672294885;8559.144085591392;8607.225349718305;8660.17728315809;7432.554046757212;7551.773446796605;7583.431242656933;7645.541267523744;7645.5794931279315;6307.779227727007;7472.850542989218;7484.363829074372;7518.210587888412;7520.210377910241;6586.242582103119;7067.328603782633;7076.32774886385;7081.504294699322;7089.184743754534;4296.570342965803;4899.240617704261;5062.797488100435;5088.0587091388215;5145.970805838922;5026.321446604661;5047.29337892688;5064.620153488577;5246.737663116753;5333.813316533902 05:41:02 INFO - PROCESS | 1670 | |4;prototype.html;300.43218317380143;301.6772053902324;308.2017574482091;315.6701247196679;317.2702783597725;5463.672179669223;5574.470425309594;5593.979251204345;5704.63088858674;5719.370869204389;4926.507349265072;5597.748101335439;5648.209250704895;5675.772969081242;5703.914441283384;3836.2135762168828;4115.938260926088;4275.444192255002;4284.33592793116;4323.913521729563;4117.979410102946;4137.8965525861795;4292.935605965898;4298.624440179144;4308.956910430903;3634.56385233771;4198.769067701275;4231.830726770925;4274.97862510687;4276.315789473685;3824.2733880562605;4283.614474697292;4311.762853043066;4314.331278651814;4318.568143185687;2862.3845873137166;3553.3299349305785;3709.182500574726;3720.2001569662384;3770.6417890300377;4749.95250047502;5395.325584301962;5455.918161227585;5489.478499542537;5517.779288828442;4857.975710121462;4917.582005529534;4933.876653083666;4947.342640215532;4975.104481193383;4811;5878.941210587882;6022.3074346450285;6025.794841031811;6047.308947518356;3786.715996300261;4058.79706014701;4062.0860306431114;4074.7147699661036;4074.7962601870086;6306.747730090791;6329.240491141092;6393.296737358887;6398.680065996728;6412.679366031726;3499.3001399720156;3968.7420317679257;4106.07613287014;4117.21772863161;4152.501699796044;3550.9112272192883;3704.8144593730285;3892.82482288292;4177.43346245158;4178.1643671265865;4920.827771027997;4924.876878078005;4937.407511098691;4948.727819969864;4999.150144475448;4217.746935183837;4886.926696099561;6091.177691011745;6109.75560977556;6134.9079763803575;1221.328269451798;1424;1438.2592964623227;1441.344188394278;1442.3365251983996;838.8675288360786;860.350435421253;860.5009094725044;878.2798105553385;887.2325438495654;4035.4665227213472;4107.342825147962;4586.036932244266;4695.906081878344;4753.5008824072975;4806.759662016885;4895.4370247422;4929.827456039022;5118.948810511922;5261.421243663194;3935.543848775971;4252.936205956913;4633.745144017044;4656.278276867091;4740.620750339965;2905.941881162365;3260.2990357072867;3266.5590145330552;3271.8364081795817;3287.2110693433883;5547.889042219133;6362.6500542469685;6380.521460890359;6385.425311721968;6423.646699431576;5335.493128152819;5968.985272503684;6008.639481631116;6361.872762544724;6387.872242555123;5639.689817060019;6363.840903977438;6408.967955160288;6428.614283143026;6439.259485159173;5695.715214239189;5826.242588463642;5852.887951289242;6067.484263837623;6244.718987645658;5085.46602606721;6073.694155756357;6100.206973093469;6121.969390153199;6130.2030736004035;6370.426661600478;6385.329540398191;6406.942854428967;6410.839729006812;6418.775342862978;3828.674562662205;3982.9203415932097;3986.9800650997718;3998.7600743955454;4029.3553031514816;5033.615755667221;5235.3717553893775;5945.524358051259;6098.481629061579;6124.908126378197;5859.179714840011;5966.075258334965;5967.194428752063;5968.8806223876145;5998.88002239944;5272.578193744568;5494.972525137348;5527.5577953764405;6047.455728984412;6053.488976393769;5331.680099194061;5412.78348866041;5820.621659592113;5847.356790753099;5917.112433135064;5103.948960510347;5113.258577506166;5800.1439539347;5996.820095397145;6002.459778619946;5474.39781624029;5580.2745643067765;5676.545876329967;5867.6772777497645;5869.882602348014 05:41:02 INFO - PROCESS | 1670 | |5;yui.html;641.8062403928692;699.1295836683329;725.8729722298597;727.0294157300006;741.5180132913603;15011;15152.84847151528;15555.766663500057;15606.609834754137;15642.983206091596;12784.93607531961;12844.743105137892;14274.78587821181;14391.424343026267;14400.783988240184;687.4740823270203;833.1668331668332;833.6707000734706;864.6757465950269;864.9697260595881;874.1913729799942;876.3164731509432;877.4428238068808;882.3073886998708;884.0717246890739;879.5734068976541;883.7216276872797;924.2236521322088;925.7269105613826;928.5821380378823;890.6214858685098;914.9725508234764;918.5040078357679;918.8070505193917;918.8529835226365;682.2256738601723;682.3483573187594;684.4387602166225;684.7295318349273;686.3685409423342;12589.496420143183;14680.55958321252;15001.174935378549;15024.023438476574;15129.546113616609;14484.203368814711;14544.854551454457;14621.122732636077;14666.926665366605;14844.851551484562;11817.881821181763;12102.818457723053;12233;12345;12407.255564666058;3817.2556351511466;3850.788206648633;3951.7826519541413;4064.268431682296;4087.693422993287;77011;108236.45881770541;109275.36086958622;109326;109760.4511977435;10652.62715804943;10852.348859068481;10936.125109991182;10958.616448424267;10964.396958167217;22920.426989325068;23332.7666723334;23453.88273058624;23526.294211173692;23858.880705596708;7242.49302548827;7647.732329368446;7663.348615367756;7865.331446826969;8256.793580160424;4466.129104824593;4581.312803079565;4588.839390621312;4590.449146102423;4598.195315819718;3502.7898326100517;3600.351936651428;3631.763935344247;3640.7815531068222;3655.067957670777;675.2470994840717;677.1501765284536;679.9184097908184;687.9140107486564;692.8579641173548;530.7425898439254;555.361334465368;601.2934801608093;608.6973875905566;639.2648454277618;8075.475094118863;8779.375815474157;9035.683751068682;9102.044285350075;9291.07089291079;534.0973754355127;541.8645338665334;554.9028919938994;562.2241306996319;584.7602482981957;4399.956000440018;4442.267025940748;4458.598726114666;4508.41390619224;4519.887002824956;145381.27309363597;151889;157047;164202;165928;83833.58083209545;84504.15495844962;102410.46384304241;119546.20680689797;131418;72025.19937001617;72482.27517724861;75142.62428687821;104460.47769761103;105952.47023764832;9592.520373981413;9684.757881052748;9974.850377244493;9983.051610097029;10054.497275136067;58967;64846;67500.6624966872;67677.3232267671;67795.3220467789;64291;66076.33923660702;89825.55087224522;101699;105014;12510.061745369316;12992.8051079232;13661.79507307411;13673.042886998213;14273.857261427253;14953.893901262634;15000.699986000001;15055.849441505883;15235.619109522351;15806.9209653951;13557.322133893464;13931.860681393462;13940.163590184622;13959.511417100353;14178.183674177095;11451.541938322374;13568.38942247621;14049.157050576998;14088.506902258372;14576.562703118923;13592.388342524808;13635.863641363856;13739.656508587364;13807.17156970585;14079.872363763194;11543.19197656156;11644.301341919512;11651.358725526368;11681.415929203677;11808.76382472363;11086.22396432242;11116.666500005012;11368.94315528417;11373.488193031499;11447.141464390037 05:41:02 INFO - PROCESS | 1670 | __end_tp_report 05:41:02 INFO - PROCESS | 1670 | __start_cc_report 05:41:02 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,439 05:41:02 INFO - PROCESS | 1670 | __end_cc_report 05:41:02 INFO - PROCESS | 1670 | __startTimestamp1467636062383__endTimestamp 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | ------- Summary: start ------- 05:41:02 INFO - PROCESS | 1670 | Number of tests: 6 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5801.80 Median:6085.57 stddev:803.19 (13.2%) stddev-sans-first:783.10 05:41:02 INFO - PROCESS | 1670 | Values: 3392.7 3413.0 3426.8 3428.7 3499.9 5070.3 5710.7 5947.0 6045.6 6049.8 5798.8 5809.4 5837.0 5839.6 5842.7 5206.9 5252.5 5352.0 5360.3 5394.1 215.1 5355.8 5364.4 5372.4 5418.8 5361.9 5363.5 5393.6 5421.0 5430.1 5297.7 5442.3 5449.6 5461.4 5476.4 5260.6 5307.0 5336.2 5347.1 5393.7 5785.3 5847.2 5991.5 6034.7 6048.6 5344.6 5868.4 6182.8 6235.8 6334.6 6234.8 6365.5 6390.8 6394.3 6413.6 6082.6 6102.9 6106.6 6114.7 6146.2 5490.9 6294.7 6397.6 6515.0 6516.4 6175.9 6225.3 6243.0 6249.9 6253.1 6375.4 6397.4 6397.6 6411.8 6430.7 6111.7 6206.9 6214.2 6236.6 6263.6 4620.1 5489.8 6133.0 6174.4 6177.3 4652.2 5081.3 5095.7 5100.1 5149.7 3792.8 4481.1 4512.4 4528.7 4569.7 5479.5 5968.1 6027.6 6055.3 6059.8 5331.7 6311.2 6335.2 6340.2 6357.2 5919.7 6019.6 6023.8 6085.6 6098.7 5642.5 5644.1 5652.3 5666.5 5676.4 6348.3 6592.4 6607.5 6620.3 6625.7 6295.8 6358.9 6381.8 6443.5 6444.4 6565.8 6605.7 6640.4 6642.4 6642.4 6112.9 6213.4 6302.4 6338.8 6358.3 4967.0 5686.4 6424.2 6432.1 6460.5 6195.1 6209.5 6222.6 6233.5 6251.3 5406.9 5819.9 6126.0 6247.8 6255.1 5057.6 5983.9 6031.7 6122.3 6141.7 6160.6 6223.4 6225.9 6250.1 6259.8 6113.0 6145.7 6169.0 6248.6 6250.5 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:72795.78 Median:40370.20 stddev:161476.27 (400.0%) stddev-sans-first:161838.00 05:41:02 INFO - PROCESS | 1670 | Values: 494.8 528.2 529.6 533.5 571.5 39296.0 41793.6 42219.0 42247.0 42364.9 42482.0 42946.0 43007.5 43040.1 43096.4 9521.7 9550.9 9652.6 9687.8 9688.8 9610.9 9620.5 9697.8 9705.9 9709.3 9657.6 9701.0 9703.5 9717.7 9754.1 9603.5 9634.1 9685.1 9691.2 9717.5 8732.8 9378.6 9758.1 9761.3 9776.0 40062.0 40471.8 40655.4 40713.8 40738.0 2948.8 3014.4 3345.7 3425.7 3449.8 31978.0 32120.5 32140.7 32372.4 32508.8 3028.4 3268.7 3309.7 3312.4 3400.7 512640.0 516059.0 519787.0 525345.0 525352.0 54615.0 64135.0 64248.7 64678.4 64838.0 35162.1 35476.5 35671.5 35750.5 68851.7 16153.2 16320.1 16405.9 16420.6 16538.8 12401.5 12460.1 12462.1 12480.8 12489.4 17057.7 19479.9 19782.6 19809.6 20103.8 7635.8 7953.3 7996.6 8021.4 8031.7 835.5 982.1 1001.1 1010.8 1030.4 30571.8 31383.7 31400.9 31729.8 32741.7 1010.2 1047.5 1065.9 1127.2 1153.4 15772.6 15862.9 15927.5 16064.8 16193.3 639785.0 643807.0 646005.0 647727.0 657701.0 49496.8 58521.7 58913.0 59016.7 59083.2 1364.3 1370.7 1388.9 1405.4 1408.9 26171.7 30119.0 30121.8 30193.6 30469.0 52599.7 53350.5 53375.5 53410.2 53560.5 622016.0 625862.0 641428.0 642534.0 645761.0 27184.5 27277.6 27536.8 27540.4 27591.7 48785.8 48921.5 49446.8 49924.0 50068.7 39489.2 39798.8 40146.4 41164.0 41412.4 36603.8 39844.4 40084.4 40268.6 41047.4 33195.0 38385.4 39097.6 39125.8 39199.8 31102.8 33299.3 33568.0 35343.5 35629.3 25867.4 26022.9 26109.1 26162.9 26365.1 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34725.84 Median:23835.20 stddev:97881.50 (410.7%) stddev-sans-first:98122.07 05:41:02 INFO - PROCESS | 1670 | Values: 358.9 371.5 374.4 378.3 387.3 19957.3 20366.6 20823.5 21398.7 21408.1 11574.8 11981.5 12038.8 12061.0 15346.5 5365.5 5522.3 5556.1 5561.4 5564.2 3874.6 5742.0 6054.9 6081.9 6103.2 5097.9 5908.7 5973.0 6098.3 6128.5 5418.4 5825.4 5928.9 6047.6 6066.1 5718.9 5898.3 5914.1 6032.6 6050.3 23626.6 24043.8 24859.6 25640.5 26002.0 2104.8 2281.3 2326.3 2674.3 2750.3 10048.8 10239.2 10334.7 10412.2 10620.4 1805.6 1887.4 1896.7 1911.5 1976.4 95906.6 113891.0 118345.0 119413.6 126903.0 10330.4 10597.0 10846.8 11737.6 11741.1 14694.8 14800.9 14863.5 14868.9 14897.6 8157.2 8690.5 8773.4 8828.5 8835.9 8613.2 8630.7 8637.6 8737.3 8748.9 3758.3 4560.3 4561.8 4645.6 4687.7 1819.8 1858.3 1983.3 2037.8 2039.4 877.5 948.6 953.2 956.3 996.3 20424.2 21316.9 21507.2 21521.2 21597.5 909.0 939.7 969.7 1026.1 1032.9 11256.5 11300.8 11337.8 11349.7 11419.9 568127.0 573471.0 585259.0 588700.0 595948.0 144536.3 147756.3 148858.8 148863.3 149045.0 951.3 1009.2 1022.7 1023.9 1040.9 15757.5 18298.3 18876.7 19551.7 19656.0 50777.2 51863.7 53938.7 54646.2 54810.0 72406.0 74259.3 75001.9 75007.0 75426.2 6208.3 8115.7 8222.9 8320.6 8401.1 9194.9 12408.8 12892.4 12977.6 13228.9 9461.0 10240.0 11000.6 11016.7 11343.8 10886.8 11100.6 11111.0 11135.1 11287.6 9704.6 11221.4 11237.2 11314.3 11382.8 8124.5 8174.6 8199.1 8306.4 8389.6 10089.6 11218.9 11284.7 11326.6 11346.5 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5699.32 Median:4962.78 stddev:3205.04 (64.6%) stddev-sans-first:3197.40 05:41:02 INFO - PROCESS | 1670 | Values: 1355.7 1530.5 1564.8 1719.7 1885.4 7937.1 8464.7 8479.8 8926.1 8945.0 8579.9 8602.5 8726.9 8741.4 8758.1 2360.3 2382.4 2384.0 2387.1 2391.0 2007.3 2355.1 2374.1 2374.7 2409.0 2348.6 2366.3 2375.3 2381.8 2386.4 1984.0 2322.2 2352.1 2373.3 2375.6 2348.6 2352.5 2352.8 2354.2 2356.2 8658.8 8723.0 8779.4 8786.1 8823.3 5145.8 5208.4 5238.6 5250.2 5305.1 3007.6 3540.9 3667.8 3670.2 3674.3 1081.0 1095.2 1103.3 1119.8 1121.1 11514.1 11685.9 11698.2 11698.7 11749.1 4313.0 4572.0 4595.2 4614.4 4774.8 9648.4 9838.2 9851.5 9861.3 9872.0 5284.6 5301.0 5310.1 5311.8 5324.8 3993.4 4367.4 4778.5 4834.9 4836.8 3506.0 3593.8 3943.8 3976.3 4057.5 1264.3 1278.6 1278.9 1297.6 1301.1 920.1 923.8 929.5 946.4 983.0 5059.2 6041.1 6078.8 6177.6 6241.4 958.4 974.1 986.5 997.5 1072.9 3273.4 4003.5 4105.3 4152.6 4164.2 10668.6 10757.7 10779.3 10870.0 10871.8 10644.6 10683.7 10809.2 10821.8 10828.3 8019.8 8155.4 8288.7 8367.4 8378.5 5824.1 5917.6 6015.5 6045.4 6118.0 9842.9 10124.5 10167.3 10221.8 10271.2 8370.0 10247.1 10322.3 10349.4 10369.6 6534.0 6905.4 7032.6 7159.5 7161.1 8501.9 8540.6 8559.1 8607.2 8660.2 7432.6 7551.8 7583.4 7645.5 7645.6 6307.8 7472.9 7484.4 7518.2 7520.2 6586.2 7067.3 7076.3 7081.5 7089.2 4296.6 4899.2 5062.8 5088.1 5146.0 5026.3 5047.3 5064.6 5246.7 5333.8 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4741.31 Median:5108.60 stddev:1493.47 (29.2%) stddev-sans-first:1459.99 05:41:02 INFO - PROCESS | 1670 | Values: 300.4 301.7 308.2 315.7 317.3 5463.7 5574.5 5594.0 5704.6 5719.4 4926.5 5597.7 5648.2 5675.8 5703.9 3836.2 4115.9 4275.4 4284.3 4323.9 4118.0 4137.9 4292.9 4298.6 4309.0 3634.6 4198.8 4231.8 4275.0 4276.3 3824.3 4283.6 4311.8 4314.3 4318.6 2862.4 3553.3 3709.2 3720.2 3770.6 4750.0 5395.3 5455.9 5489.5 5517.8 4858.0 4917.6 4933.9 4947.3 4975.1 4811.0 5878.9 6022.3 6025.8 6047.3 3786.7 4058.8 4062.1 4074.7 4074.8 6306.7 6329.2 6393.3 6398.7 6412.7 3499.3 3968.7 4106.1 4117.2 4152.5 3550.9 3704.8 3892.8 4177.4 4178.2 4920.8 4924.9 4937.4 4948.7 4999.2 4217.7 4886.9 6091.2 6109.8 6134.9 1221.3 1424.0 1438.3 1441.3 1442.3 838.9 860.4 860.5 878.3 887.2 4035.5 4107.3 4586.0 4695.9 4753.5 4806.8 4895.4 4929.8 5118.9 5261.4 3935.5 4252.9 4633.7 4656.3 4740.6 2905.9 3260.3 3266.6 3271.8 3287.2 5547.9 6362.7 6380.5 6385.4 6423.6 5335.5 5969.0 6008.6 6361.9 6387.9 5639.7 6363.8 6409.0 6428.6 6439.3 5695.7 5826.2 5852.9 6067.5 6244.7 5085.5 6073.7 6100.2 6122.0 6130.2 6370.4 6385.3 6406.9 6410.8 6418.8 3828.7 3982.9 3987.0 3998.8 4029.4 5033.6 5235.4 5945.5 6098.5 6124.9 5859.2 5966.1 5967.2 5968.9 5998.9 5272.6 5495.0 5527.6 6047.5 6053.5 5331.7 5412.8 5820.6 5847.4 5917.1 5103.9 5113.3 5800.1 5996.8 6002.5 5474.4 5580.3 5676.5 5867.7 5869.9 05:41:02 INFO - PROCESS | 1670 | 05:41:02 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:23555.84 Median:3647.92 stddev:37144.70 (1018.2%) stddev-sans-first:37209.06 05:41:02 INFO - PROCESS | 1670 | Values: 641.8 699.1 725.9 727.0 741.5 15011.0 15152.8 15555.8 15606.6 15643.0 12784.9 12844.7 14274.8 14391.4 14400.8 687.5 833.2 833.7 864.7 865.0 874.2 876.3 877.4 882.3 884.1 879.6 883.7 924.2 925.7 928.6 890.6 915.0 918.5 918.8 918.9 682.2 682.3 684.4 684.7 686.4 12589.5 14680.6 15001.2 15024.0 15129.5 14484.2 14544.9 14621.1 14666.9 14844.9 11817.9 12102.8 12233.0 12345.0 12407.3 3817.3 3850.8 3951.8 4064.3 4087.7 77011.0 108236.5 109275.4 109326.0 109760.5 10652.6 10852.3 10936.1 10958.6 10964.4 22920.4 23332.8 23453.9 23526.3 23858.9 7242.5 7647.7 7663.3 7865.3 8256.8 4466.1 4581.3 4588.8 4590.4 4598.2 3502.8 3600.4 3631.8 3640.8 3655.1 675.2 677.2 679.9 687.9 692.9 530.7 555.4 601.3 608.7 639.3 8075.5 8779.4 9035.7 9102.0 9291.1 534.1 541.9 554.9 562.2 584.8 4400.0 4442.3 4458.6 4508.4 4519.9 145381.3 151889.0 157047.0 164202.0 165928.0 83833.6 84504.2 102410.5 119546.2 131418.0 72025.2 72482.3 75142.6 104460.5 105952.5 9592.5 9684.8 9974.9 9983.1 10054.5 58967.0 64846.0 67500.7 67677.3 67795.3 64291.0 66076.3 89825.6 101699.0 105014.0 12510.1 12992.8 13661.8 13673.0 14273.9 14953.9 15000.7 15055.8 15235.6 15806.9 13557.3 13931.9 13940.2 13959.5 14178.2 11451.5 13568.4 14049.2 14088.5 14576.6 13592.4 13635.9 13739.7 13807.2 14079.9 11543.2 11644.3 11651.4 11681.4 11808.8 11086.2 11116.7 11368.9 11373.5 11447.1 05:41:02 INFO - PROCESS | 1670 | -------- Summary: end -------- 05:41:02 INFO - PROCESS | 1670 | 05:41:03 INFO - TEST-INFO | 1670: exit 0 05:41:03 INFO - TEST-OK | dromaeo_css | took 1112491ms 05:41:03 INFO - TEST-START | kraken 05:41:03 INFO - operating with platform_type : mac_ 05:41:03 INFO - Initialising browser for kraken test... 05:41:03 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp8JcLGU/profile http://localhost:49265/getInfo.html) 05:41:06 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 05:41:06 INFO - PROCESS | 1679 | colorDepth:24 05:41:06 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 05:41:06 INFO - PROCESS | 1679 | __metrics 05:41:06 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:41:06 INFO - TEST-INFO | 1679: exit 0 05:41:06 INFO - Browser initialized. 05:41:06 INFO - Running cycle 1/1 for kraken test... 05:41:06 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp8JcLGU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:18 INFO - PROCESS | 1681 | RSS: Main: 255737856 05:41:18 INFO - PROCESS | 1681 | 05:41:21 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 05:41:22 INFO - PROCESS | 1681 | RSS: Main: 268361728 05:41:22 INFO - PROCESS | 1681 | 05:41:25 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 05:41:25 INFO - PROCESS | 1681 | RSS: Main: 272568320 05:41:25 INFO - PROCESS | 1681 | 05:41:29 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 05:41:29 INFO - PROCESS | 1681 | RSS: Main: 273076224 05:41:29 INFO - PROCESS | 1681 | 05:41:31 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 05:41:32 INFO - PROCESS | 1681 | RSS: Main: 273997824 05:41:32 INFO - PROCESS | 1681 | 05:41:34 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:41:35 INFO - PROCESS | 1681 | RSS: Main: 274698240 05:41:35 INFO - PROCESS | 1681 | 05:41:37 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 05:41:38 INFO - PROCESS | 1681 | RSS: Main: 275693568 05:41:38 INFO - PROCESS | 1681 | 05:41:41 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 05:41:42 INFO - PROCESS | 1681 | RSS: Main: 275582976 05:41:42 INFO - PROCESS | 1681 | 05:41:44 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 05:41:45 INFO - PROCESS | 1681 | RSS: Main: 275677184 05:41:45 INFO - PROCESS | 1681 | 05:41:47 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:41:47 INFO - PROCESS | 1681 | RSS: Main: 275644416 05:41:47 INFO - PROCESS | 1681 | 05:41:49 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:41:50 INFO - PROCESS | 1681 | RSS: Main: 275263488 05:41:50 INFO - PROCESS | 1681 | 05:41:52 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:41:53 INFO - PROCESS | 1681 | RSS: Main: 275496960 05:41:53 INFO - PROCESS | 1681 | 05:41:56 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:41:56 INFO - PROCESS | 1681 | RSS: Main: 275546112 05:41:56 INFO - PROCESS | 1681 | 05:41:59 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:00 INFO - PROCESS | 1681 | RSS: Main: 275451904 05:42:00 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 05:42:02 INFO - PROCESS | 1681 | RSS: Main: 276418560 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | __start_tp_report 05:42:02 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 05:42:02 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 05:42:02 INFO - PROCESS | 1681 | |i|pagename|runs| 05:42:02 INFO - PROCESS | 1681 | |0;ai-astar;104;117;108;117;117;118;114;117;118;116 05:42:02 INFO - PROCESS | 1681 | |1;audio-beat-detection;142;114;115;149;144;167;122;141;185;140 05:42:02 INFO - PROCESS | 1681 | |2;audio-dft;199;199;197;216;260;248;198;197;200;198 05:42:02 INFO - PROCESS | 1681 | |3;audio-fft;69;81;75;156;83;83;60;58;134;79 05:42:02 INFO - PROCESS | 1681 | |4;audio-oscillator;70;72;118;69;74;92;113;70;76;116 05:42:02 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;129;87;90;86;90;85;89;86;88;85 05:42:02 INFO - PROCESS | 1681 | |6;imaging-darkroom;105;151;133;95;94;97;96;96;95;96 05:42:02 INFO - PROCESS | 1681 | |7;imaging-desaturate;89;101;90;92;94;91;92;93;98;92 05:42:02 INFO - PROCESS | 1681 | |8;json-parse-financial;57;55;55;55;57;58;56;58;59;63 05:42:02 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;68;70;69;78;73;76;74;69;72;70 05:42:02 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;85;87;89;83;89;92;87;84;86;88 05:42:02 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;146;162;154;152;139;128;154;155;212;165 05:42:02 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;174;190;238;177;176;201;242;194;176;170 05:42:02 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;71;67;72;84;96;86;90;84;68;67 05:42:02 INFO - PROCESS | 1681 | __end_tp_report 05:42:02 INFO - PROCESS | 1681 | __start_cc_report 05:42:02 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1124 05:42:02 INFO - PROCESS | 1681 | __end_cc_report 05:42:02 INFO - PROCESS | 1681 | __startTimestamp1467636122452__endTimestamp 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | ------- Summary: start ------- 05:42:02 INFO - PROCESS | 1681 | Number of tests: 14 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:114.60 Median:117.00 stddev:4.77 (4.1%) stddev-sans-first:3.15 05:42:02 INFO - PROCESS | 1681 | Values: 104.0 117.0 108.0 117.0 117.0 118.0 114.0 117.0 118.0 116.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:143.00 stddev:22.22 (15.5%) stddev-sans-first:23.57 05:42:02 INFO - PROCESS | 1681 | Values: 142.0 114.0 115.0 149.0 144.0 167.0 122.0 141.0 185.0 140.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:211.20 Median:199.50 stddev:23.41 (11.7%) stddev-sans-first:24.41 05:42:02 INFO - PROCESS | 1681 | Values: 199.0 199.0 197.0 216.0 260.0 248.0 198.0 197.0 200.0 198.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:87.80 Median:77.00 stddev:31.86 (41.4%) stddev-sans-first:33.05 05:42:02 INFO - PROCESS | 1681 | Values: 69.0 81.0 75.0 156.0 83.0 83.0 60.0 58.0 134.0 79.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:71.00 stddev:20.87 (29.4%) stddev-sans-first:21.21 05:42:02 INFO - PROCESS | 1681 | Values: 70.0 72.0 118.0 69.0 74.0 92.0 113.0 70.0 76.0 116.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:87.50 stddev:13.31 (15.2%) stddev-sans-first:2.00 05:42:02 INFO - PROCESS | 1681 | Values: 129.0 87.0 90.0 86.0 90.0 85.0 89.0 86.0 88.0 85.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:105.80 Median:95.50 stddev:19.78 (20.7%) stddev-sans-first:20.98 05:42:02 INFO - PROCESS | 1681 | Values: 105.0 151.0 133.0 95.0 94.0 97.0 96.0 96.0 95.0 96.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:93.20 Median:92.00 stddev:3.68 (4.0%) stddev-sans-first:3.57 05:42:02 INFO - PROCESS | 1681 | Values: 89.0 101.0 90.0 92.0 94.0 91.0 92.0 93.0 98.0 92.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.50 stddev:2.45 (4.3%) stddev-sans-first:2.60 05:42:02 INFO - PROCESS | 1681 | Values: 57.0 55.0 55.0 55.0 57.0 58.0 56.0 58.0 59.0 63.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.50 stddev:3.31 (4.6%) stddev-sans-first:3.20 05:42:02 INFO - PROCESS | 1681 | Values: 68.0 70.0 69.0 78.0 73.0 76.0 74.0 69.0 72.0 70.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.50 stddev:2.67 (3.0%) stddev-sans-first:2.73 05:42:02 INFO - PROCESS | 1681 | Values: 85.0 87.0 89.0 83.0 89.0 92.0 87.0 84.0 86.0 88.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:156.70 Median:154.50 stddev:22.23 (14.4%) stddev-sans-first:23.23 05:42:02 INFO - PROCESS | 1681 | Values: 146.0 162.0 154.0 152.0 139.0 128.0 154.0 155.0 212.0 165.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.80 Median:192.00 stddev:26.28 (13.7%) stddev-sans-first:26.88 05:42:02 INFO - PROCESS | 1681 | Values: 174.0 190.0 238.0 177.0 176.0 201.0 242.0 194.0 176.0 170.0 05:42:02 INFO - PROCESS | 1681 | 05:42:02 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:84.00 stddev:10.69 (12.7%) stddev-sans-first:10.99 05:42:02 INFO - PROCESS | 1681 | Values: 71.0 67.0 72.0 84.0 96.0 86.0 90.0 84.0 68.0 67.0 05:42:02 INFO - PROCESS | 1681 | -------- Summary: end -------- 05:42:02 INFO - PROCESS | 1681 | 05:42:03 INFO - TEST-INFO | 1681: exit 0 05:42:03 INFO - TEST-OK | kraken | took 60170ms 05:42:03 INFO - SUITE-END | took 1172s 05:42:03 INFO - Completed test suite (00:19:33) 05:42:03 INFO - javascript benchmark 05:42:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7415.781923795511, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3392.7116195123403, 3413.0, 3426.79439233646, 3428.708559772419, 3499.8600055997745, 5070.264811602325, 5710.657995371084, 5947.0, 6045.607035542692, 6049.758009679618, 5798.82603521894, 5809.419058094199, 5837.0, 5839.5620328475425, 5842.737076831542, 5206.8698282542955, 5252.474752524736, 5352.0, 5360.3299587551555, 5394.136938089887, 215.05982337592064, 5355.7857685692525, 5364.409914909357, 5372.435894231108, 5418.810341638044, 5361.919571206435, 5363.463653634644, 5393.5954803389905, 5420.972895135499, 5430.104032834597, 5297.735113244323, 5442.319710036246, 5449.56403487724, 5461.42655021225, 5476.3702174250075, 5260.552853007499, 5306.97346513265, 5336.199570064483, 5347.1177255752755, 5393.7303134843105, 5785.30576330843, 5847.210626565445, 5991.4907232884825, 6034.6982650867285, 6048.637081775064, 5344.599155063386, 5868.383819698955, 6182.84542886427, 6235.844103897393, 6334.619922804646, 6234.812955611339, 6365.522585806083, 6390.840228994266, 6394.296627370985, 6413.615183089029, 6082.635041897501, 6102.908456373158, 6106.603070800473, 6114.694265286718, 6146.231721034871, 5490.890182196334, 6294.653794041372, 6397.6481293529305, 6514.967425162844, 6516.446102081281, 6175.876482470326, 6225.346338634378, 6243.0, 6249.937500624936, 6253.093301471261, 6375.362463753588, 6397.424231819065, 6397.61614303134, 6411.839704007437, 6430.742770289229, 6111.743676692077, 6206.937930620727, 6214.223222097238, 6236.6258024518675, 6263.592866463757, 4620.052889157714, 5489.819688766935, 6132.967444181115, 6174.38256174388, 6177.2587289524645, 4652.1626107300335, 5081.2632168335385, 5095.745212739348, 5100.081985242692, 5149.665271757323, 3792.810359482015, 4481.126180394857, 4512.435945506812, 4528.705634133837, 4569.702969306984, 5479.506844384025, 5968.104784282305, 6027.60820546663, 6055.333913269536, 6059.848503787441, 5331.733413329318, 6311.211098612674, 6335.239771227482, 6340.175777149033, 6357.1735674362035, 5919.704014799243, 6019.60872543292, 6023.849403764853, 6085.57400981927, 6098.69506524672, 5642.548596112301, 5644.125160600113, 5652.3217213934595, 5666.546676265889, 5676.4323567642905, 6348.269948955929, 6592.373724496165, 6607.537472376984, 6620.271770105188, 6625.734970601218, 6295.842603934755, 6358.872822543616, 6381.8085457436355, 6443.484521238196, 6444.420002199825, 6565.835854103686, 6605.669716514251, 6640.369164929324, 6642.368974947372, 6642.368974947372, 6112.929053818996, 6213.409726076015, 6302.369763023661, 6338.841528961629, 6358.2687990879795, 4967.0, 5686.4029276926985, 6424.164858568356, 6432.13166222554, 6460.483161346987, 6195.10586636552, 6209.534284928739, 6222.595531290452, 6233.470155036873, 6251.312355640966, 5406.918896216638, 5819.883602327845, 6125.969370153299, 6247.781327653511, 6255.0617407387435, 5057.620678449204, 5983.850403739942, 6031.728572214349, 6122.2959359674205, 6141.700809433504, 6160.630362178284, 6223.408776166257, 6225.937740622536, 6250.093736408105, 6259.78090766821, 6113.0, 6145.692715364304, 6169.043798211284, 6248.562600617913, 6250.4687101595055], "value": 5754.877395756339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.8268106162843, 528.1628618639456, 529.6054439442613, 533.458539582324, 571.517068077475, 39296.0, 41793.58206417935, 42219.0, 42247.0, 42364.940876478104, 42482.0, 42946.0, 43007.49473768419, 43040.139197216035, 43096.3535546967, 9521.714348569554, 9550.90449095507, 9652.565634546463, 9687.806243875119, 9688.806223875517, 9610.855837162448, 9620.470874101922, 9697.75755606112, 9705.854412183822, 9709.320347575673, 9657.613695452208, 9701.029897010278, 9703.466309352983, 9717.659881904136, 9754.122129008389, 9603.519824008772, 9634.084761947604, 9685.079917407835, 9691.224702023821, 9717.514124293828, 8732.781680457976, 9378.624855005859, 9758.12176904075, 9761.316707830454, 9776.022397760238, 40061.99845003869, 40471.7976410119, 40655.39016914749, 40713.778586642446, 40738.0, 2948.778841586868, 3014.351914338428, 3345.749068819824, 3425.72594192464, 3449.758516903818, 31978.0, 32120.518192227137, 32140.678593214, 32372.352552949043, 32508.837455812805, 3028.394321135782, 3268.6731326867125, 3309.6690330966953, 3312.3540909522653, 3400.6939375456327, 512640.0, 516059.0, 519787.0, 525345.0, 525352.0, 54615.0, 64135.0, 64248.67875660592, 64678.353216467236, 64838.0, 35162.12094697653, 35476.46785298223, 35671.4649280261, 35750.463743043874, 68851.65574172097, 16153.192340382933, 16320.102394368423, 16405.93361431527, 16420.589485262964, 16538.83461165397, 12401.503939842305, 12460.12779105472, 12462.065345099154, 12480.81278780819, 12489.437975291134, 17057.658846822993, 19479.90260048691, 19782.604347912962, 19809.603807923762, 20103.798962010485, 7635.809104772314, 7953.2842044216295, 7996.561478564273, 8021.358291336679, 8031.678732850737, 835.4569529805553, 982.0817535604233, 1001.1190152665607, 1010.8433192855119, 1030.3972176276961, 30571.847140764155, 31383.686163138533, 31400.900968465994, 31729.84135079356, 32741.672583273863, 1010.161565900301, 1047.539082803564, 1065.8827528971806, 1127.199688221372, 1153.3656488931053, 15772.605684857741, 15862.92068539673, 15927.522174334788, 16064.79514036452, 16193.271302791167, 639785.0, 643807.0, 646005.0, 647727.0, 657701.0, 49496.75251623791, 58521.70739146277, 58913.0, 59016.704916475144, 59083.227503174974, 1364.2769332253922, 1370.7258548290183, 1388.8541703121027, 1405.4237762517523, 1408.9084209526347, 26171.73828261693, 30119.0, 30121.84939075378, 30193.590856048453, 30469.0, 52599.73700131475, 53350.4664953361, 53375.46624533705, 53410.198847016545, 53560.46439535711, 622016.0, 625862.0, 641428.0, 642534.0, 645761.0, 27184.456310874066, 27277.59083613787, 27536.76084576159, 27540.44919101567, 27591.724082758916, 48785.756071220836, 48921.510784891696, 49446.752766237376, 49924.0, 50068.74828129326, 39489.21021579495, 39798.801005994785, 40146.39780403238, 41163.98486859395, 41412.378814317206, 36603.816980914926, 39844.40233396443, 40084.398734019596, 40268.59731402648, 41047.38428923509, 33195.0, 38385.42421863618, 39097.6090239094, 39125.82622521329, 39199.82400527989, 31102.844485777427, 33299.33401332008, 33568.0, 35343.46984795179, 35629.28741425209, 25867.353316167246, 26022.86988565045, 26109.08618198354, 26162.86918565395, 26365.077222297128], "value": 20153.28037062209, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.85107680312655, 371.5021870693272, 374.3860069486043, 378.29259285136783, 387.264198023755, 19957.301494447696, 20366.59266814663, 20823.479413014684, 21398.679019814677, 21408.14367425301, 11574.768504629903, 11981.460834262458, 12038.819417708719, 12061.035117190626, 15346.462873799419, 5365.543928766059, 5522.337319521643, 5556.083246264382, 5561.388247292814, 5564.1931919871595, 3874.5544262409903, 5742.0, 6054.909176362358, 6081.908771368411, 6103.23709536308, 5097.949020509803, 5908.67502287374, 5972.970135149319, 6098.298695650013, 6128.509719222451, 5418.4039755626845, 5825.446582574648, 5928.88142237155, 6047.637141771508, 6066.090086487018, 5718.914216286759, 5898.292204935392, 5914.112883067532, 6032.638041717511, 6050.334463209044, 23626.64560031601, 24043.759562404328, 24859.62710559343, 25640.48719025609, 26002.0, 2104.810567048973, 2281.258590957945, 2326.336993956714, 2674.3314171457137, 2750.2849259192553, 10048.849267260997, 10239.180865530814, 10334.7416314592, 10412.219083568687, 10620.362778233331, 1805.6403915200433, 1887.4243355776482, 1896.6680830854546, 1911.5125642961095, 1976.3675623800389, 95906.643267486, 113891.0, 118345.0, 119413.61172776496, 126903.0, 10330.431826249629, 10596.993285637853, 10846.806851246356, 11737.589184378507, 11741.060715142768, 14694.779578306334, 14800.925995369955, 14863.479778207711, 14868.925655371653, 14897.627559311104, 8157.225063619066, 8690.522021288889, 8773.38586298953, 8828.470291782514, 8835.867461988075, 8613.181747733955, 8630.741077767678, 8637.611307491177, 8737.30101591871, 8748.912510874938, 3758.3047136279874, 4560.315952607136, 4561.840335588238, 4645.605123564467, 4687.695299805502, 1819.7907240667228, 1858.2566973210824, 1983.3157560641555, 2037.7758446570865, 2039.4493486758493, 877.4954401219325, 948.6300342866288, 953.1945506047379, 956.2636769687302, 996.3324572536418, 20424.183032678822, 21316.934153292274, 21507.247246346302, 21521.246756363453, 21597.46006349854, 908.9954550227338, 939.6711151097061, 969.6751588217885, 1026.1175389165312, 1032.9018743219383, 11256.549738010388, 11300.830487542693, 11337.773244535063, 11349.659510214708, 11419.885801142049, 568127.0, 573471.0, 585259.0, 588700.0, 595948.0, 144536.27731861483, 147756.26121869322, 148858.7671184933, 148863.25568372305, 149045.0, 951.2532661860406, 1009.1926458833051, 1022.728976821143, 1023.9078482936571, 1040.9115225205942, 15757.527274181793, 18298.261665141792, 18876.716849246997, 19551.70672439943, 19656.01719914027, 50777.23834142565, 51863.74068129635, 53938.73030634822, 54646.18030729463, 54810.0, 72406.0, 74259.25740742523, 75001.87497187438, 75007.0, 75426.24573754192, 6208.2860471044605, 8115.715949941724, 8222.931018967496, 8320.625571849403, 8401.11788262248, 9194.908050919405, 12408.75182496363, 12892.355382230664, 12977.610671679864, 13228.867711323148, 9461.0, 10240.0, 11000.614978475714, 11016.669499915015, 11343.75218725956, 10886.782264354826, 11100.555977761122, 11111.0, 11135.053520450852, 11287.604933827277, 9704.611815527582, 11221.438928053401, 11237.213395062594, 11314.321140731583, 11382.772344552897, 8124.471909325875, 8174.550399728072, 8199.0980992092, 8306.377021723274, 8389.580520973805, 10089.646862359783, 11218.887811122111, 11284.661460156209, 11326.603568875049, 11346.489407976826], "value": 9469.24394142683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.6746380868592, 1530.4566878758042, 1564.7731078993554, 1719.6818588561116, 1885.4155211884308, 7937.08723496798, 8464.66141354345, 8479.788005299863, 8926.107389261071, 8945.016048234696, 8579.871301930476, 8602.526861022641, 8726.91273087269, 8741.388102832805, 8758.08040155784, 2360.2683168217823, 2382.3924899150693, 2383.9761602384015, 2387.1048356866177, 2390.988045059772, 2007.3275452723356, 2355.128602417108, 2374.0622454130607, 2374.7387787343378, 2408.987955060231, 2348.635961425973, 2366.3137690069857, 2375.2992867104244, 2381.809455243585, 2386.3795413192606, 1983.9504012399805, 2322.175627652176, 2352.0709319818748, 2373.32360277321, 2375.5605213035556, 2348.6007378745485, 2352.482453860148, 2352.764723527651, 2354.199572145462, 2356.1871154451687, 8658.826823463496, 8722.956385218033, 8779.429337093068, 8786.121387861163, 8823.338249631302, 5145.845624631267, 5208.374995000586, 5238.554722848525, 5250.159974404077, 5305.124654432014, 3007.6090108286003, 3540.8760693375866, 3667.7799332040163, 3670.210904655485, 3674.3386190485703, 1080.9891901080966, 1095.2497539185683, 1103.2773533335678, 1119.7648493816228, 1121.0863146535548, 11514.136439767051, 11685.94157029218, 11698.23961442512, 11698.707532311675, 11749.118816088741, 4312.956870431318, 4571.977140114278, 4595.1728688835665, 4614.446266447981, 4774.809007639656, 9648.421094734338, 9838.212942964547, 9851.50742462874, 9861.260405469618, 9872.012798720214, 5284.603654725911, 5301.0, 5310.070737620901, 5311.814086506954, 5324.760385782649, 3993.381025940984, 4367.410399596078, 4778.522147785194, 4834.903301933941, 4836.830710925101, 3505.9824700876334, 3593.8320045984638, 3943.783091929914, 3976.264391087658, 4057.472528571267, 1264.3109505319624, 1278.5844600504872, 1278.9424475898606, 1297.6431481342518, 1301.1022394547922, 920.1028996728137, 923.8429466990627, 929.5259417697023, 946.3580687507169, 982.9705108846603, 5059.241113832954, 6041.124037014608, 6078.817635470943, 6177.567570270038, 6241.407066328691, 958.4488918871676, 974.0940924939732, 986.4525188520297, 997.5211898288862, 1072.9034386905216, 3273.4107860585323, 4003.499562554681, 4105.261053010427, 4152.584741525884, 4164.167166566699, 10668.573257069786, 10757.731056723487, 10779.299345542515, 10870.0, 10871.782564348669, 10644.574217031386, 10683.73290667724, 10809.189310801565, 10821.83767243492, 10828.296160749525, 8019.759407217793, 8155.388345874083, 8288.668453261922, 8367.372447066495, 8378.53918034514, 5824.097264923944, 5917.5857689961285, 6015.548833837567, 6045.395460453919, 6118.0, 9842.901570984484, 10124.493775311057, 10167.339122956984, 10221.846672299773, 10271.178305735373, 8370.037445693579, 10247.077763001365, 10322.277440579088, 10349.430781307101, 10369.585216591251, 6534.019897015486, 6905.413039891465, 7032.578045317297, 7159.463040271896, 7161.140663120459, 8501.914980850112, 8540.615672294885, 8559.144085591392, 8607.225349718305, 8660.17728315809, 7432.554046757212, 7551.773446796605, 7583.431242656933, 7645.541267523744, 7645.5794931279315, 6307.779227727007, 7472.850542989218, 7484.363829074372, 7518.210587888412, 7520.210377910241, 6586.242582103119, 7067.328603782633, 7076.32774886385, 7081.504294699322, 7089.184743754534, 4296.570342965803, 4899.240617704261, 5062.797488100435, 5088.0587091388215, 5145.970805838922, 5026.321446604661, 5047.29337892688, 5064.620153488577, 5246.737663116753, 5333.813316533902], "value": 4590.540849194491, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.43218317380143, 301.6772053902324, 308.2017574482091, 315.6701247196679, 317.2702783597725, 5463.672179669223, 5574.470425309594, 5593.979251204345, 5704.63088858674, 5719.370869204389, 4926.507349265072, 5597.748101335439, 5648.209250704895, 5675.772969081242, 5703.914441283384, 3836.2135762168828, 4115.938260926088, 4275.444192255002, 4284.33592793116, 4323.913521729563, 4117.979410102946, 4137.8965525861795, 4292.935605965898, 4298.624440179144, 4308.956910430903, 3634.56385233771, 4198.769067701275, 4231.830726770925, 4274.97862510687, 4276.315789473685, 3824.2733880562605, 4283.614474697292, 4311.762853043066, 4314.331278651814, 4318.568143185687, 2862.3845873137166, 3553.3299349305785, 3709.182500574726, 3720.2001569662384, 3770.6417890300377, 4749.95250047502, 5395.325584301962, 5455.918161227585, 5489.478499542537, 5517.779288828442, 4857.975710121462, 4917.582005529534, 4933.876653083666, 4947.342640215532, 4975.104481193383, 4811.0, 5878.941210587882, 6022.3074346450285, 6025.794841031811, 6047.308947518356, 3786.715996300261, 4058.79706014701, 4062.0860306431114, 4074.7147699661036, 4074.7962601870086, 6306.747730090791, 6329.240491141092, 6393.296737358887, 6398.680065996728, 6412.679366031726, 3499.3001399720156, 3968.7420317679257, 4106.07613287014, 4117.21772863161, 4152.501699796044, 3550.9112272192883, 3704.8144593730285, 3892.82482288292, 4177.43346245158, 4178.1643671265865, 4920.827771027997, 4924.876878078005, 4937.407511098691, 4948.727819969864, 4999.150144475448, 4217.746935183837, 4886.926696099561, 6091.177691011745, 6109.75560977556, 6134.9079763803575, 1221.328269451798, 1424.0, 1438.2592964623227, 1441.344188394278, 1442.3365251983996, 838.8675288360786, 860.350435421253, 860.5009094725044, 878.2798105553385, 887.2325438495654, 4035.4665227213472, 4107.342825147962, 4586.036932244266, 4695.906081878344, 4753.5008824072975, 4806.759662016885, 4895.4370247422, 4929.827456039022, 5118.948810511922, 5261.421243663194, 3935.543848775971, 4252.936205956913, 4633.745144017044, 4656.278276867091, 4740.620750339965, 2905.941881162365, 3260.2990357072867, 3266.5590145330552, 3271.8364081795817, 3287.2110693433883, 5547.889042219133, 6362.6500542469685, 6380.521460890359, 6385.425311721968, 6423.646699431576, 5335.493128152819, 5968.985272503684, 6008.639481631116, 6361.872762544724, 6387.872242555123, 5639.689817060019, 6363.840903977438, 6408.967955160288, 6428.614283143026, 6439.259485159173, 5695.715214239189, 5826.242588463642, 5852.887951289242, 6067.484263837623, 6244.718987645658, 5085.46602606721, 6073.694155756357, 6100.206973093469, 6121.969390153199, 6130.2030736004035, 6370.426661600478, 6385.329540398191, 6406.942854428967, 6410.839729006812, 6418.775342862978, 3828.674562662205, 3982.9203415932097, 3986.9800650997718, 3998.7600743955454, 4029.3553031514816, 5033.615755667221, 5235.3717553893775, 5945.524358051259, 6098.481629061579, 6124.908126378197, 5859.179714840011, 5966.075258334965, 5967.194428752063, 5968.8806223876145, 5998.88002239944, 5272.578193744568, 5494.972525137348, 5527.5577953764405, 6047.455728984412, 6053.488976393769, 5331.680099194061, 5412.78348866041, 5820.621659592113, 5847.356790753099, 5917.112433135064, 5103.948960510347, 5113.258577506166, 5800.1439539347, 5996.820095397145, 6002.459778619946, 5474.39781624029, 5580.2745643067765, 5676.545876329967, 5867.6772777497645, 5869.882602348014], "value": 4261.071116456604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.8062403928692, 699.1295836683329, 725.8729722298597, 727.0294157300006, 741.5180132913603, 15011.0, 15152.84847151528, 15555.766663500057, 15606.609834754137, 15642.983206091596, 12784.93607531961, 12844.743105137892, 14274.78587821181, 14391.424343026267, 14400.783988240184, 687.4740823270203, 833.1668331668332, 833.6707000734706, 864.6757465950269, 864.9697260595881, 874.1913729799942, 876.3164731509432, 877.4428238068808, 882.3073886998708, 884.0717246890739, 879.5734068976541, 883.7216276872797, 924.2236521322088, 925.7269105613826, 928.5821380378823, 890.6214858685098, 914.9725508234764, 918.5040078357679, 918.8070505193917, 918.8529835226365, 682.2256738601723, 682.3483573187594, 684.4387602166225, 684.7295318349273, 686.3685409423342, 12589.496420143183, 14680.55958321252, 15001.174935378549, 15024.023438476574, 15129.546113616609, 14484.203368814711, 14544.854551454457, 14621.122732636077, 14666.926665366605, 14844.851551484562, 11817.881821181763, 12102.818457723053, 12233.0, 12345.0, 12407.255564666058, 3817.2556351511466, 3850.788206648633, 3951.7826519541413, 4064.268431682296, 4087.693422993287, 77011.0, 108236.45881770541, 109275.36086958622, 109326.0, 109760.4511977435, 10652.62715804943, 10852.348859068481, 10936.125109991182, 10958.616448424267, 10964.396958167217, 22920.426989325068, 23332.7666723334, 23453.88273058624, 23526.294211173692, 23858.880705596708, 7242.49302548827, 7647.732329368446, 7663.348615367756, 7865.331446826969, 8256.793580160424, 4466.129104824593, 4581.312803079565, 4588.839390621312, 4590.449146102423, 4598.195315819718, 3502.7898326100517, 3600.351936651428, 3631.763935344247, 3640.7815531068222, 3655.067957670777, 675.2470994840717, 677.1501765284536, 679.9184097908184, 687.9140107486564, 692.8579641173548, 530.7425898439254, 555.361334465368, 601.2934801608093, 608.6973875905566, 639.2648454277618, 8075.475094118863, 8779.375815474157, 9035.683751068682, 9102.044285350075, 9291.07089291079, 534.0973754355127, 541.8645338665334, 554.9028919938994, 562.2241306996319, 584.7602482981957, 4399.956000440018, 4442.267025940748, 4458.598726114666, 4508.41390619224, 4519.887002824956, 145381.27309363597, 151889.0, 157047.0, 164202.0, 165928.0, 83833.58083209545, 84504.15495844962, 102410.46384304241, 119546.20680689797, 131418.0, 72025.19937001617, 72482.27517724861, 75142.62428687821, 104460.47769761103, 105952.47023764832, 9592.520373981413, 9684.757881052748, 9974.850377244493, 9983.051610097029, 10054.497275136067, 58967.0, 64846.0, 67500.6624966872, 67677.3232267671, 67795.3220467789, 64291.0, 66076.33923660702, 89825.55087224522, 101699.0, 105014.0, 12510.061745369316, 12992.8051079232, 13661.79507307411, 13673.042886998213, 14273.857261427253, 14953.893901262634, 15000.699986000001, 15055.849441505883, 15235.619109522351, 15806.9209653951, 13557.322133893464, 13931.860681393462, 13940.163590184622, 13959.511417100353, 14178.183674177095, 11451.541938322374, 13568.38942247621, 14049.157050576998, 14088.506902258372, 14576.562703118923, 13592.388342524808, 13635.863641363856, 13739.656508587364, 13807.17156970585, 14079.872363763194, 11543.19197656156, 11644.301341919512, 11651.358725526368, 11681.415929203677, 11808.76382472363, 11086.22396432242, 11116.666500005012, 11368.94315528417, 11373.488193031499, 11447.141464390037], "value": 7741.36990680981, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 108.0, 117.0, 117.0, 118.0, 114.0, 117.0, 118.0, 116.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 115.0, 149.0, 144.0, 167.0, 122.0, 141.0, 185.0, 140.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 199.0, 197.0, 216.0, 260.0, 248.0, 198.0, 197.0, 200.0, 198.0], "value": 211.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 75.0, 156.0, 83.0, 83.0, 60.0, 58.0, 134.0, 79.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 118.0, 69.0, 74.0, 92.0, 113.0, 70.0, 76.0, 116.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 87.0, 90.0, 86.0, 90.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 151.0, 133.0, 95.0, 94.0, 97.0, 96.0, 96.0, 95.0, 96.0], "value": 105.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 101.0, 90.0, 92.0, 94.0, 91.0, 92.0, 93.0, 98.0, 92.0], "value": 93.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 55.0, 57.0, 58.0, 56.0, 58.0, 59.0, 63.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 78.0, 73.0, 76.0, 74.0, 69.0, 72.0, 70.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 89.0, 83.0, 89.0, 92.0, 87.0, 84.0, 86.0, 88.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 162.0, 154.0, 152.0, 139.0, 128.0, 154.0, 155.0, 212.0, 165.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 190.0, 238.0, 177.0, 176.0, 201.0, 242.0, 194.0, 176.0, 170.0], "value": 193.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 72.0, 84.0, 96.0, 86.0, 90.0, 84.0, 68.0, 67.0], "value": 78.5, "unit": "score"}]}]} 05:42:03 INFO - Return code: 0 05:42:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:42:03 ERROR - # TBPL SUCCESS # 05:42:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:03 INFO - 2016-07-04 05:42:03.795 screenresolution[1686:13084] starting screenresolution argv=screenresolution get 05:42:03 INFO - 2016-07-04 05:42:03.798 screenresolution[1686:13084] Display 0: 1600x1200x32@60 05:42:03 INFO - 2016-07-04 05:42:03.804 screenresolution[1687:13087] starting screenresolution argv=screenresolution list 05:42:03 INFO - Available Modes on Display 0 05:42:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:04 INFO - Intel Iris: 05:42:04 INFO - Chipset Model: Intel Iris 05:42:04 INFO - Type: GPU 05:42:04 INFO - Bus: Built-In 05:42:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:04 INFO - Vendor: Intel (0x8086) 05:42:04 INFO - Device ID: 0x0a2e 05:42:04 INFO - Revision ID: 0x0009 05:42:04 INFO - Displays: 05:42:04 INFO - Display: 05:42:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:04 INFO - Display Serial Number: 1600x1200 60 05:42:04 INFO - Main Display: Yes 05:42:04 INFO - Mirror: Off 05:42:04 INFO - Online: Yes 05:42:04 INFO - Rotation: Supported 05:42:04 INFO - Return code: 0 05:42:04 INFO - Running post-action listener: _resource_record_post_action 05:42:04 INFO - ##### 05:42:04 INFO - ##### Finished run-tests step (success) 05:42:04 INFO - ##### 05:42:04 INFO - Running post-run listener: _resource_record_post_run 05:42:04 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 31591936; Write bytes: 709806592; Read time: 192; Write time: 1969 05:42:04 INFO - TinderboxPrint: CPU usage
54.4% 05:42:04 INFO - TinderboxPrint: I/O read bytes / time
31,591,936 / 192 05:42:04 INFO - TinderboxPrint: I/O write bytes / time
709,806,592 / 1,969 05:42:04 INFO - TinderboxPrint: CPU idle
2,206.5 (45.6%) 05:42:04 INFO - TinderboxPrint: CPU system
974.3 (20.2%) 05:42:04 INFO - TinderboxPrint: CPU user
1,652.9 (34.2%) 05:42:04 INFO - TinderboxPrint: Swap in / out
434,384,896 / 0 05:42:04 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396384768; Write bytes: 390326272; Read time: 29293; Write time: 777 05:42:04 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 29724160; Write bytes: 299569664; Read time: 177; Write time: 1142 05:42:04 INFO - Running post-run listener: _upload_blobber_files 05:42:04 INFO - Blob upload gear active. 05:42:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:42:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:42:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:06 INFO - (blobuploader) - INFO - Done attempting. 05:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:42:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:08 INFO - (blobuploader) - INFO - Done attempting. 05:42:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:42:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:42:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:10 INFO - (blobuploader) - INFO - Done attempting. 05:42:10 INFO - (blobuploader) - INFO - Iteration through files over. 05:42:10 INFO - Return code: 0 05:42:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:42:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:42:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/755a3d910454d083c35e599a8c2849ab6595e53faba29c991590da2c0d7c0f7df7f12bf1cd501f8c67dba000c86d4db8056d61c3d1230aedadc454b8e7d6e11c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a1b997619691ede37242f04fdde3650d2053825f014785ba4f614097f7fec5fbcbaa094b7743215b22b74b956083d83abd2b0afa23729221d0e9af065c1502", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c53b8dc5d5b5bc0f907003f6bad5ce2131f35074226e112fae40a25d9d87238caee4b85f57b7243221c0f438dd1f71ad401f8aff0f83b46d67ae5ce3b51c0cb"} 05:42:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:42:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:42:10 INFO - Contents: 05:42:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/755a3d910454d083c35e599a8c2849ab6595e53faba29c991590da2c0d7c0f7df7f12bf1cd501f8c67dba000c86d4db8056d61c3d1230aedadc454b8e7d6e11c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a1b997619691ede37242f04fdde3650d2053825f014785ba4f614097f7fec5fbcbaa094b7743215b22b74b956083d83abd2b0afa23729221d0e9af065c1502", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c53b8dc5d5b5bc0f907003f6bad5ce2131f35074226e112fae40a25d9d87238caee4b85f57b7243221c0f438dd1f71ad401f8aff0f83b46d67ae5ce3b51c0cb"} 05:42:10 INFO - Running post-run listener: copy_logs_to_upload_dir 05:42:10 INFO - Copying logs to upload dir... 05:42:10 INFO - mkdir: /builds/slave/test/build/upload/logs 05:42:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.582115 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-04 05:42:10.580335) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 05:42:10.583757) ========= 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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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/755a3d910454d083c35e599a8c2849ab6595e53faba29c991590da2c0d7c0f7df7f12bf1cd501f8c67dba000c86d4db8056d61c3d1230aedadc454b8e7d6e11c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a1b997619691ede37242f04fdde3650d2053825f014785ba4f614097f7fec5fbcbaa094b7743215b22b74b956083d83abd2b0afa23729221d0e9af065c1502", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c53b8dc5d5b5bc0f907003f6bad5ce2131f35074226e112fae40a25d9d87238caee4b85f57b7243221c0f438dd1f71ad401f8aff0f83b46d67ae5ce3b51c0cb"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011682 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280497/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/755a3d910454d083c35e599a8c2849ab6595e53faba29c991590da2c0d7c0f7df7f12bf1cd501f8c67dba000c86d4db8056d61c3d1230aedadc454b8e7d6e11c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a1b997619691ede37242f04fdde3650d2053825f014785ba4f614097f7fec5fbcbaa094b7743215b22b74b956083d83abd2b0afa23729221d0e9af065c1502", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c53b8dc5d5b5bc0f907003f6bad5ce2131f35074226e112fae40a25d9d87238caee4b85f57b7243221c0f438dd1f71ad401f8aff0f83b46d67ae5ce3b51c0cb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 05:42:10.619256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:42:10.619604) ========= 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.0HDeRJt0Kv/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.EDNCcHyZpq/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.005194 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:42:10.668171) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 05:42:10.668522) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 05:42:10.668834) ========= ========= Total master_lag: 0.31 =========