builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0366 starttime: 1470305094.81 results: success (0) buildid: 20160710030201 builduid: d14d3ed84c7e4c14b363dafb2f47d2ce revision: 679118259e91f40d4a8f968f03ec4cff066cdb5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:54.811961) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:54.812414) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:54.812710) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:54.901779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:54.902045) ========= 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.fbEZE7aVXX/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.o63iksQe0F/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-04 03:04:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 74.2M=0s 2016-08-04 03:04:55 (74.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.196640 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:55.123578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:55.123866) ========= 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.fbEZE7aVXX/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.o63iksQe0F/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.025951 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:55.173047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 03:04:55.173405) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 679118259e91f40d4a8f968f03ec4cff066cdb5b --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 679118259e91f40d4a8f968f03ec4cff066cdb5b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fbEZE7aVXX/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.o63iksQe0F/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-04 03:04:55,255 truncating revision to first 12 chars 2016-08-04 03:04:55,255 Setting DEBUG logging. 2016-08-04 03:04:55,256 attempt 1/10 2016-08-04 03:04:55,256 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/679118259e91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-04 03:04:56,245 unpacking tar archive at: mozilla-central-679118259e91/testing/mozharness/ program finished with exit code 0 elapsedTime=1.253840 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 03:04:56.452331) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:56.452623) ========= script_repo_revision: 679118259e91f40d4a8f968f03ec4cff066cdb5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:56.452991) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:56.453275) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 03:04:56.479655) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-08-04 03:04:56.479971) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fbEZE7aVXX/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.o63iksQe0F/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:04:56 INFO - MultiFileLogger online at 20160804 03:04:56 in /builds/slave/test 03:04:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:04:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:04:56 INFO - {'append_to_log': False, 03:04:56 INFO - 'base_work_dir': '/builds/slave/test', 03:04:56 INFO - 'blob_upload_branch': 'Firefox', 03:04:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:04:56 INFO - 'branch': 'Firefox', 03:04:56 INFO - 'buildbot_json_path': 'buildprops.json', 03:04:56 INFO - 'config_files': ('talos/mac_config.py',), 03:04:56 INFO - 'default_actions': ('clobber', 03:04:56 INFO - 'read-buildbot-config', 03:04:56 INFO - 'download-and-extract', 03:04:56 INFO - 'populate-webroot', 03:04:56 INFO - 'create-virtualenv', 03:04:56 INFO - 'install', 03:04:56 INFO - 'run-tests'), 03:04:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:04:56 INFO - 'download_minidump_stackwalk': True, 03:04:56 INFO - 'download_symbols': 'ondemand', 03:04:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:04:56 INFO - 'tooltool.py': '/tools/tooltool.py', 03:04:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:04:56 INFO - '/tools/misc-python/virtualenv.py')}, 03:04:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:04:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:04:56 INFO - 'installer_path': 'installer.exe', 03:04:56 INFO - 'log_level': 'info', 03:04:56 INFO - 'log_name': 'talos', 03:04:56 INFO - 'log_to_console': True, 03:04:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:04:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:04:56 INFO - 'opt_config_files': (), 03:04:56 INFO - 'pip_index': False, 03:04:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:04:56 INFO - 'cmd': ('bash', 03:04:56 INFO - '-c', 03:04:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:04:56 INFO - 'enabled': True, 03:04:56 INFO - 'halt_on_failure': False, 03:04:56 INFO - 'name': 'check_screen_resolution'},), 03:04:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:04:56 INFO - 'cmd': ('bash', 03:04:56 INFO - '-c', 03:04:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:04:56 INFO - 'enabled': True, 03:04:56 INFO - 'halt_on_failure': False, 03:04:56 INFO - 'name': 'check_screen_resolution'},), 03:04:56 INFO - 'run_cmd_checks_enabled': True, 03:04:56 INFO - 'sps_profile': False, 03:04:56 INFO - 'sps_profile_interval': 0, 03:04:56 INFO - 'suite': 'dromaeojs', 03:04:56 INFO - 'system_bits': '32', 03:04:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:04:56 INFO - 'title': 't-yosemite-r7-0366', 03:04:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:04:56 INFO - 'use_talos_json': True, 03:04:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:04:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:04:56 INFO - 'work_dir': 'build'} 03:04:56 INFO - ##### 03:04:56 INFO - ##### Running clobber step. 03:04:56 INFO - ##### 03:04:56 INFO - Running pre-action listener: _resource_record_pre_action 03:04:56 INFO - Running main action method: clobber 03:04:56 INFO - rmtree: /builds/slave/test/build 03:04:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:04:58 INFO - Running post-action listener: _resource_record_post_action 03:04:58 INFO - ##### 03:04:58 INFO - ##### Finished clobber step (success) 03:04:58 INFO - ##### 03:04:58 INFO - ##### 03:04:58 INFO - ##### Running read-buildbot-config step. 03:04:58 INFO - ##### 03:04:58 INFO - Running pre-action listener: _resource_record_pre_action 03:04:58 INFO - Running main action method: read_buildbot_config 03:04:58 INFO - Using buildbot properties: 03:04:58 INFO - { 03:04:58 INFO - "project": "", 03:04:58 INFO - "product": "firefox", 03:04:58 INFO - "script_repo_revision": "production", 03:04:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:04:58 INFO - "repository": "", 03:04:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:04:58 INFO - "buildid": "20160710030201", 03:04:58 INFO - "stage_platform": "macosx64", 03:04:58 INFO - "basedir": "/builds/slave/test", 03:04:58 INFO - "nightly_build": "True", 03:04:58 INFO - "buildnumber": 24, 03:04:58 INFO - "slavename": "t-yosemite-r7-0366", 03:04:58 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:04:58 INFO - "platform": "yosemite_r7", 03:04:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:04:58 INFO - "branch": "mozilla-central", 03:04:58 INFO - "repo_path": "mozilla-central", 03:04:58 INFO - "revision": "679118259e91f40d4a8f968f03ec4cff066cdb5b", 03:04:58 INFO - "pgo_build": "False", 03:04:58 INFO - "builduid": "d14d3ed84c7e4c14b363dafb2f47d2ce", 03:04:58 INFO - "slavebuilddir": "test" 03:04:58 INFO - } 03:04:58 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg. 03:04:58 INFO - Running post-action listener: _resource_record_post_action 03:04:58 INFO - ##### 03:04:58 INFO - ##### Finished read-buildbot-config step (success) 03:04:58 INFO - ##### 03:04:58 INFO - ##### 03:04:58 INFO - ##### Running download-and-extract step. 03:04:58 INFO - ##### 03:04:58 INFO - Running pre-action listener: _resource_record_pre_action 03:04:58 INFO - Running main action method: download_and_extract 03:04:58 INFO - mkdir: /builds/slave/test/build/tests 03:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:58 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.test_packages.json 03:04:58 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 03:04:58 INFO - Downloaded 1199 bytes. 03:04:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:04:58 INFO - Using the following test package requirements: 03:04:58 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:04:58 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:04:58 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:04:58 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'jsshell-mac.zip'], 03:04:58 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:04:58 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:04:58 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:04:58 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:04:58 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:04:58 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:04:58 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:04:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:58 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.common.tests.zip 03:04:58 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:04:59 INFO - Downloaded 18806968 bytes. 03:04:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:04:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:05:02 INFO - Return code: 0 03:05:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:05:02 INFO - Downloaded 18806968 bytes. 03:05:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:05:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:05:06 INFO - Return code: 0 03:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.talos.tests.zip 03:05:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:05:14 INFO - Downloaded 14403333 bytes. 03:05:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:05:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:05:15 INFO - Return code: 0 03:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg 03:05:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:05:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:05:17 INFO - Downloaded 112509917 bytes. 03:05:17 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg 03:05:17 INFO - mkdir: /builds/slave/test/properties 03:05:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:05:17 INFO - Writing to file /builds/slave/test/properties/build_url 03:05:17 INFO - Contents: 03:05:17 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg 03:05:17 INFO - Running post-action listener: _resource_record_post_action 03:05:17 INFO - Running post-action listener: set_extra_try_arguments 03:05:17 INFO - ##### 03:05:17 INFO - ##### Finished download-and-extract step (success) 03:05:17 INFO - ##### 03:05:17 INFO - ##### 03:05:17 INFO - ##### Running populate-webroot step. 03:05:17 INFO - ##### 03:05:17 INFO - Running pre-action listener: _resource_record_pre_action 03:05:17 INFO - Running main action method: populate_webroot 03:05:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:05:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:05:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:05:17 INFO - '--tppagecycles', 03:05:17 INFO - '7'], 03:05:17 INFO - 'tests': ['tsvgm']}}, 03:05:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:05:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:05:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:05:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:05:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['damp', 'tps']}, 03:05:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'tests': ['damp', 'tps']}, 03:05:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['dromaeo_dom']}, 03:05:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:05:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['basic_compositor_video']}, 03:05:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:05:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['a11yr', 03:05:17 INFO - 'ts_paint', 03:05:17 INFO - 'tpaint', 03:05:17 INFO - 'sessionrestore', 03:05:17 INFO - 'sessionrestore_no_auto_restore', 03:05:17 INFO - 'tabpaint']}, 03:05:17 INFO - 'other-e10s': {'tests': ['a11yr', 03:05:17 INFO - 'ts_paint', 03:05:17 INFO - 'tpaint', 03:05:17 INFO - 'sessionrestore', 03:05:17 INFO - 'sessionrestore_no_auto_restore', 03:05:17 INFO - 'tabpaint']}, 03:05:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:05:17 INFO - 'ts_paint', 03:05:17 INFO - 'tpaint', 03:05:17 INFO - 'sessionrestore', 03:05:17 INFO - 'sessionrestore_no_auto_restore', 03:05:17 INFO - 'tabpaint']}, 03:05:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:05:17 INFO - 'ts_paint', 03:05:17 INFO - 'tpaint', 03:05:17 INFO - 'sessionrestore', 03:05:17 INFO - 'sessionrestore_no_auto_restore', 03:05:17 INFO - 'tabpaint']}, 03:05:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['a11yr', 03:05:17 INFO - 'ts_paint', 03:05:17 INFO - 'tpaint', 03:05:17 INFO - 'sessionrestore', 03:05:17 INFO - 'sessionrestore_no_auto_restore', 03:05:17 INFO - 'tabpaint']}, 03:05:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['a11yr', 03:05:17 INFO - 'ts_paint', 03:05:17 INFO - 'tpaint', 03:05:17 INFO - 'sessionrestore', 03:05:17 INFO - 'sessionrestore_no_auto_restore', 03:05:17 INFO - 'tabpaint']}, 03:05:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['tsvgx', 03:05:17 INFO - 'tsvgr_opacity', 03:05:17 INFO - 'tart', 03:05:17 INFO - 'tscrollx', 03:05:17 INFO - 'cart']}, 03:05:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:05:17 INFO - 'tsvgr_opacity', 03:05:17 INFO - 'tart', 03:05:17 INFO - 'tscrollx', 03:05:17 INFO - 'cart']}, 03:05:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'talos_options': ['--disable-e10s'], 03:05:17 INFO - 'tests': ['tp5o']}, 03:05:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'tests': ['tp5o']}, 03:05:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'talos_options': ['--disable-e10s', 03:05:17 INFO - '--xperf_path', 03:05:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:17 INFO - 'tests': ['tp5n']}, 03:05:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:17 INFO - 'talos_options': ['--xperf_path', 03:05:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:17 INFO - 'tests': ['tp5n']}}, 03:05:17 INFO - 'talos.zip': {'path': '', 03:05:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:05:17 INFO - Running post-action listener: _resource_record_post_action 03:05:17 INFO - ##### 03:05:17 INFO - ##### Finished populate-webroot step (success) 03:05:17 INFO - ##### 03:05:17 INFO - ##### 03:05:17 INFO - ##### Running create-virtualenv step. 03:05:17 INFO - ##### 03:05:17 INFO - Running pre-action listener: _resource_record_pre_action 03:05:17 INFO - Running main action method: create_virtualenv 03:05:17 INFO - Creating virtualenv /builds/slave/test/build/venv 03:05:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:05:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:05:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:05:17 INFO - Using real prefix '/tools/python27' 03:05:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:05:18 INFO - Installing distribute.............................................................................................................................................................................................done. 03:05:21 INFO - Installing pip.................done. 03:05:21 INFO - Return code: 0 03:05:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:21 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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:05:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:21 INFO - 'HOME': '/Users/cltbld', 03:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:21 INFO - 'LOGNAME': 'cltbld', 03:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:21 INFO - 'MOZ_NO_REMOTE': '1', 03:05:21 INFO - 'NO_EM_RESTART': '1', 03:05:21 INFO - 'PAGER': '/bin/cat', 03:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:21 INFO - 'PWD': '/builds/slave/test', 03:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:21 INFO - 'SHELL': '/bin/bash', 03:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:21 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:21 INFO - 'USER': 'cltbld', 03:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:21 INFO - 'XPC_FLAGS': '0x0', 03:05:21 INFO - 'XPC_SERVICE_NAME': '0', 03:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:05:21 INFO - Cleaning up... 03:05:21 INFO - Return code: 0 03:05:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:21 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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:05:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:21 INFO - 'HOME': '/Users/cltbld', 03:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:21 INFO - 'LOGNAME': 'cltbld', 03:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:21 INFO - 'MOZ_NO_REMOTE': '1', 03:05:21 INFO - 'NO_EM_RESTART': '1', 03:05:21 INFO - 'PAGER': '/bin/cat', 03:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:21 INFO - 'PWD': '/builds/slave/test', 03:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:21 INFO - 'SHELL': '/bin/bash', 03:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:21 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:21 INFO - 'USER': 'cltbld', 03:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:21 INFO - 'XPC_FLAGS': '0x0', 03:05:21 INFO - 'XPC_SERVICE_NAME': '0', 03:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:21 INFO - Downloading/unpacking psutil>=3.1.1 03:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:05:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:25 INFO - Installing collected packages: psutil 03:05:25 INFO - Running setup.py install for psutil 03:05:25 INFO - building 'psutil._psutil_osx' extension 03:05:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:05:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:05:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:05:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:05:26 INFO - building 'psutil._psutil_posix' extension 03:05:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:05:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:05:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:05:26 INFO - ^ 03:05:26 INFO - 1 warning generated. 03:05:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:26 INFO - Successfully installed psutil 03:05:26 INFO - Cleaning up... 03:05:26 INFO - Return code: 0 03:05:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:26 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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:26 INFO - 'HOME': '/Users/cltbld', 03:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:26 INFO - 'LOGNAME': 'cltbld', 03:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:26 INFO - 'MOZ_NO_REMOTE': '1', 03:05:26 INFO - 'NO_EM_RESTART': '1', 03:05:26 INFO - 'PAGER': '/bin/cat', 03:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:26 INFO - 'PWD': '/builds/slave/test', 03:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:26 INFO - 'SHELL': '/bin/bash', 03:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:26 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:26 INFO - 'USER': 'cltbld', 03:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:26 INFO - 'XPC_FLAGS': '0x0', 03:05:26 INFO - 'XPC_SERVICE_NAME': '0', 03:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:05:29 INFO - Installing collected packages: mozsystemmonitor 03:05:29 INFO - Running setup.py install for mozsystemmonitor 03:05:29 INFO - Successfully installed mozsystemmonitor 03:05:29 INFO - Cleaning up... 03:05:29 INFO - Return code: 0 03:05:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:29 INFO - 'HOME': '/Users/cltbld', 03:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:29 INFO - 'LOGNAME': 'cltbld', 03:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:29 INFO - 'MOZ_NO_REMOTE': '1', 03:05:29 INFO - 'NO_EM_RESTART': '1', 03:05:29 INFO - 'PAGER': '/bin/cat', 03:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:29 INFO - 'PWD': '/builds/slave/test', 03:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:29 INFO - 'SHELL': '/bin/bash', 03:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:29 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:29 INFO - 'USER': 'cltbld', 03:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:29 INFO - 'XPC_FLAGS': '0x0', 03:05:29 INFO - 'XPC_SERVICE_NAME': '0', 03:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:29 INFO - Downloading/unpacking jsonschema==2.5.1 03:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:05:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:32 INFO - Downloading functools32-3.2.3-2.tar.gz 03:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:05:32 INFO - warning: no files found matching '*.txt' 03:05:32 INFO - no previously-included directories found matching 'build' 03:05:32 INFO - no previously-included directories found matching 'dist' 03:05:32 INFO - no previously-included directories found matching '.git*' 03:05:32 INFO - Installing collected packages: jsonschema, functools32 03:05:32 INFO - Running setup.py install for functools32 03:05:32 INFO - warning: no files found matching '*.txt' 03:05:32 INFO - no previously-included directories found matching 'build' 03:05:32 INFO - no previously-included directories found matching 'dist' 03:05:32 INFO - no previously-included directories found matching '.git*' 03:05:32 INFO - Successfully installed jsonschema functools32 03:05:32 INFO - Cleaning up... 03:05:32 INFO - Return code: 0 03:05:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:32 INFO - 'HOME': '/Users/cltbld', 03:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:32 INFO - 'LOGNAME': 'cltbld', 03:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:32 INFO - 'MOZ_NO_REMOTE': '1', 03:05:32 INFO - 'NO_EM_RESTART': '1', 03:05:32 INFO - 'PAGER': '/bin/cat', 03:05:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:32 INFO - 'PWD': '/builds/slave/test', 03:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:32 INFO - 'SHELL': '/bin/bash', 03:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:32 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:32 INFO - 'USER': 'cltbld', 03:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:32 INFO - 'XPC_FLAGS': '0x0', 03:05:32 INFO - 'XPC_SERVICE_NAME': '0', 03:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:32 INFO - Downloading/unpacking blobuploader==1.2.4 03:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:35 INFO - Downloading blobuploader-1.2.4.tar.gz 03:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:05:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:05:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:05: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 03:05: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 03:05: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 03:05: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 03:05: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 03:05: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 03:05:36 INFO - Downloading docopt-0.6.1.tar.gz 03:05:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:05:36 INFO - Installing collected packages: blobuploader, requests, docopt 03:05:36 INFO - Running setup.py install for blobuploader 03:05:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:05:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:05:36 INFO - Running setup.py install for requests 03:05:37 INFO - Running setup.py install for docopt 03:05:37 INFO - Successfully installed blobuploader requests docopt 03:05:37 INFO - Cleaning up... 03:05:37 INFO - Return code: 0 03:05:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:37 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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:37 INFO - 'HOME': '/Users/cltbld', 03:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:37 INFO - 'LOGNAME': 'cltbld', 03:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:37 INFO - 'MOZ_NO_REMOTE': '1', 03:05:37 INFO - 'NO_EM_RESTART': '1', 03:05:37 INFO - 'PAGER': '/bin/cat', 03:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:37 INFO - 'PWD': '/builds/slave/test', 03:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:37 INFO - 'SHELL': '/bin/bash', 03:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:37 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:37 INFO - 'USER': 'cltbld', 03:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:37 INFO - 'XPC_FLAGS': '0x0', 03:05:37 INFO - 'XPC_SERVICE_NAME': '0', 03:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:37 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-_ARByG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:37 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-KPniuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-dN4qSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-tXEZg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-6cFClt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-knEGJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-9IlYDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-1OFGI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-TttYG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-_xtq8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-YukiLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:38 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Ncd18m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:39 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-XEW7U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:39 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-rxE6FQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:39 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-cGlZ4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:39 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-23ykqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:39 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-KevSvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:05:39 INFO - Running setup.py install for manifestparser 03:05:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:05:39 INFO - Running setup.py install for mozcrash 03:05:39 INFO - Running setup.py install for mozdebug 03:05:39 INFO - Running setup.py install for mozdevice 03:05:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Running setup.py install for mozfile 03:05:40 INFO - Running setup.py install for mozhttpd 03:05:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Running setup.py install for mozinfo 03:05:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Running setup.py install for mozInstall 03:05:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:05:40 INFO - Running setup.py install for mozleak 03:05:40 INFO - Running setup.py install for mozlog 03:05:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:05:41 INFO - Running setup.py install for moznetwork 03:05:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:05:41 INFO - Running setup.py install for mozprocess 03:05:41 INFO - Running setup.py install for mozprofile 03:05:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:05:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:05:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:05:41 INFO - Running setup.py install for mozrunner 03:05:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:05:41 INFO - Running setup.py install for mozscreenshot 03:05:41 INFO - Running setup.py install for moztest 03:05:42 INFO - Running setup.py install for mozversion 03:05:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:05:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:05:42 INFO - Cleaning up... 03:05:42 INFO - Return code: 0 03:05:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:42 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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:05:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:42 INFO - 'HOME': '/Users/cltbld', 03:05:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:42 INFO - 'LOGNAME': 'cltbld', 03:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:42 INFO - 'MOZ_NO_REMOTE': '1', 03:05:42 INFO - 'NO_EM_RESTART': '1', 03:05:42 INFO - 'PAGER': '/bin/cat', 03:05:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:42 INFO - 'PWD': '/builds/slave/test', 03:05:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:42 INFO - 'SHELL': '/bin/bash', 03:05:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:42 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:42 INFO - 'USER': 'cltbld', 03:05:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:42 INFO - 'XPC_FLAGS': '0x0', 03:05:42 INFO - 'XPC_SERVICE_NAME': '0', 03:05:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-FVBfFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-duJK29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Zr0k9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-U64h2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-u1Za4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-mfX1xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-ErQ6aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-XSleQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-RHZYsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-UILi2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Y6ZP5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-isiHnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-GRoSDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-XBlt3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-1ooj9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:44 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-DVIs2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:44 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-QwiYij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:46 INFO - Downloading blessings-1.6.tar.gz 03:05:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:05:46 INFO - Installing collected packages: blessings 03:05:46 INFO - Running setup.py install for blessings 03:05:46 INFO - Successfully installed blessings 03:05:46 INFO - Cleaning up... 03:05:47 INFO - Return code: 0 03:05:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:05:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:47 INFO - Reading from file tmpfile_stdout 03:05:47 INFO - Current package versions: 03:05:47 INFO - blessings == 1.6 03:05:47 INFO - blobuploader == 1.2.4 03:05:47 INFO - docopt == 0.6.1 03:05:47 INFO - functools32 == 3.2.3-2 03:05:47 INFO - jsonschema == 2.5.1 03:05:47 INFO - manifestparser == 1.1 03:05:47 INFO - mozInstall == 1.12 03:05:47 INFO - mozcrash == 0.17 03:05:47 INFO - mozdebug == 0.1 03:05:47 INFO - mozdevice == 0.48 03:05:47 INFO - mozfile == 1.2 03:05:47 INFO - mozhttpd == 0.7 03:05:47 INFO - mozinfo == 0.9 03:05:47 INFO - mozleak == 0.1 03:05:47 INFO - mozlog == 3.2 03:05:47 INFO - moznetwork == 0.27 03:05:47 INFO - mozprocess == 0.23 03:05:47 INFO - mozprofile == 0.28 03:05:47 INFO - mozrunner == 6.12 03:05:47 INFO - mozscreenshot == 0.1 03:05:47 INFO - mozsystemmonitor == 0.3 03:05:47 INFO - moztest == 0.7 03:05:47 INFO - mozversion == 1.4 03:05:47 INFO - psutil == 3.1.1 03:05:47 INFO - requests == 1.2.3 03:05:47 INFO - wsgiref == 0.1.2 03:05:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:47 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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:05:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:47 INFO - 'HOME': '/Users/cltbld', 03:05:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:47 INFO - 'LOGNAME': 'cltbld', 03:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:47 INFO - 'MOZ_NO_REMOTE': '1', 03:05:47 INFO - 'NO_EM_RESTART': '1', 03:05:47 INFO - 'PAGER': '/bin/cat', 03:05:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:47 INFO - 'PWD': '/builds/slave/test', 03:05:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:47 INFO - 'SHELL': '/bin/bash', 03:05:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:47 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:47 INFO - 'USER': 'cltbld', 03:05:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:47 INFO - 'XPC_FLAGS': '0x0', 03:05:47 INFO - 'XPC_SERVICE_NAME': '0', 03:05:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:05:47 INFO - Cleaning up... 03:05:47 INFO - Return code: 0 03:05:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:47 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 0x109d2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dc3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8451572550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, '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.fbEZE7aVXX/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.o63iksQe0F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:05:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:05:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:47 INFO - 'HOME': '/Users/cltbld', 03:05:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:47 INFO - 'LOGNAME': 'cltbld', 03:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:47 INFO - 'MOZ_NO_REMOTE': '1', 03:05:47 INFO - 'NO_EM_RESTART': '1', 03:05:47 INFO - 'PAGER': '/bin/cat', 03:05:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:47 INFO - 'PWD': '/builds/slave/test', 03:05:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:47 INFO - 'SHELL': '/bin/bash', 03:05:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:05:47 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:05:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:47 INFO - 'USER': 'cltbld', 03:05:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:47 INFO - 'XPC_FLAGS': '0x0', 03:05:47 INFO - 'XPC_SERVICE_NAME': '0', 03:05:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:05:47 INFO - Cleaning up... 03:05:47 INFO - Return code: 0 03:05:47 INFO - Running post-action listener: _resource_record_post_action 03:05:47 INFO - Running post-action listener: _start_resource_monitoring 03:05:47 INFO - Starting resource monitoring. 03:05:47 INFO - ##### 03:05:47 INFO - ##### Finished create-virtualenv step (success) 03:05:47 INFO - ##### 03:05:47 INFO - ##### 03:05:47 INFO - ##### Running install step. 03:05:47 INFO - ##### 03:05:47 INFO - Running pre-action listener: _resource_record_pre_action 03:05:47 INFO - Running main action method: install 03:05:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:48 INFO - Reading from file tmpfile_stdout 03:05:48 INFO - Detecting whether we're running mozinstall >=1.0... 03:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:05:48 INFO - Reading from file tmpfile_stdout 03:05:48 INFO - Output received: 03:05:48 INFO - Usage: mozinstall [options] installer 03:05:48 INFO - Options: 03:05:48 INFO - -h, --help show this help message and exit 03:05:48 INFO - -d DEST, --destination=DEST 03:05:48 INFO - Directory to install application into. [default: 03:05:48 INFO - "/builds/slave/test"] 03:05:48 INFO - --app=APP Application being installed. [default: firefox] 03:05:48 INFO - mkdir: /builds/slave/test/build/application 03:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:06:23 INFO - Reading from file tmpfile_stdout 03:06:23 INFO - Output received: 03:06:23 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 03:06:23 INFO - Running post-action listener: _resource_record_post_action 03:06:23 INFO - ##### 03:06:23 INFO - ##### Finished install step (success) 03:06:23 INFO - ##### 03:06:23 INFO - ##### 03:06:23 INFO - ##### Running run-tests step. 03:06:23 INFO - ##### 03:06:23 INFO - Running pre-action listener: _resource_record_pre_action 03:06:23 INFO - Running main action method: run_tests 03:06:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:06:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:06:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:06:23 INFO - 2016-08-04 03:06:23.338 screenresolution[1643:7793] starting screenresolution argv=screenresolution get 03:06:23 INFO - 2016-08-04 03:06:23.341 screenresolution[1643:7793] Display 0: 1600x1200x32@60 03:06:23 INFO - 2016-08-04 03:06:23.346 screenresolution[1644:7795] starting screenresolution argv=screenresolution list 03:06:23 INFO - Available Modes on Display 0 03:06:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:06:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:06:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:06:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:06:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:06:23 INFO - Intel Iris: 03:06:23 INFO - Chipset Model: Intel Iris 03:06:23 INFO - Type: GPU 03:06:23 INFO - Bus: Built-In 03:06:23 INFO - VRAM (Dynamic, Max): 1536 MB 03:06:23 INFO - Vendor: Intel (0x8086) 03:06:23 INFO - Device ID: 0x0a2e 03:06:23 INFO - Revision ID: 0x0009 03:06:23 INFO - Displays: 03:06:23 INFO - Display: 03:06:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:06:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:06:23 INFO - Display Serial Number: 1600x1200 60 03:06:23 INFO - Main Display: Yes 03:06:23 INFO - Mirror: Off 03:06:23 INFO - Online: Yes 03:06:23 INFO - Rotation: Supported 03:06:23 INFO - Return code: 0 03:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:06:23 INFO - Python 2.7.3 03:06:23 INFO - Return code: 0 03:06:23 INFO - grabbing minidump binary from tooltool 03:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:23 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 0x109c92d30>, '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 0x1099f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c8ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:06:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:06:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:06:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:06:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:06:23 INFO - Return code: 0 03:06:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:06:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:06:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:06:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:06:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0366 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fbEZE7aVXX/Render', 03:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:23 INFO - 'HOME': '/Users/cltbld', 03:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:23 INFO - 'LOGNAME': 'cltbld', 03:06:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:06:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:23 INFO - 'MOZ_NO_REMOTE': '1', 03:06:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:06:23 INFO - 'NO_EM_RESTART': '1', 03:06:23 INFO - 'PAGER': '/bin/cat', 03:06:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:23 INFO - 'PWD': '/builds/slave/test', 03:06:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:23 INFO - 'SHELL': '/bin/bash', 03:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o63iksQe0F/Listeners', 03:06:23 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 03:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:23 INFO - 'USER': 'cltbld', 03:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:23 INFO - 'XPC_FLAGS': '0x0', 03:06:23 INFO - 'XPC_SERVICE_NAME': '0', 03:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:06:23 INFO - mozversion application_buildid: 20160710030201 03:06:23 INFO - mozversion application_changeset: 679118259e91f40d4a8f968f03ec4cff066cdb5b 03:06:23 INFO - mozversion application_display_name: Nightly 03:06:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:06:23 INFO - mozversion application_name: Firefox 03:06:23 INFO - mozversion application_remotingname: firefox 03:06:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:06:23 INFO - mozversion application_vendor: Mozilla 03:06:23 INFO - mozversion application_version: 50.0a1 03:06:23 INFO - mozversion platform_buildid: 20160710030201 03:06:23 INFO - mozversion platform_changeset: 679118259e91f40d4a8f968f03ec4cff066cdb5b 03:06:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:06:23 INFO - mozversion platform_version: 50.0a1 03:06:23 INFO - using testdate: 1470305183 03:06:23 INFO - actual date: 1470305183 03:06:23 INFO - starting webserver on 'localhost:49261' 03:06:23 INFO - SUITE-START | Running 2 tests 03:06:23 INFO - TEST-START | dromaeo_css 03:06:23 INFO - operating with platform_type : mac_ 03:06:23 INFO - Initialising browser for dromaeo_css test... 03:06:23 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpZs8LYm/profile http://localhost:49261/getInfo.html) 03:06:26 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 03:06:26 INFO - PROCESS | 1654 | colorDepth:24 03:06:26 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 03:06:26 INFO - PROCESS | 1654 | __metrics 03:06:26 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:06:26 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:06:27 INFO - TEST-INFO | 1654: exit 0 03:06:27 INFO - Browser initialized. 03:06:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:06:27 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpZs8LYm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:06:39 INFO - PROCESS | 1658 | RSS: Main: 243634176 03:06:39 INFO - PROCESS | 1658 | 03:07:30 INFO - PROCESS | 1658 | 1470305250382 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:09:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49261/tests/dromaeo/cssquery-ext.html) 03:09:43 INFO - PROCESS | 1658 | RSS: Main: 360787968 03:09:43 INFO - PROCESS | 1658 | 03:12:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-ext.html (next: http://localhost:49261/tests/dromaeo/cssquery-jquery.html) 03:12:46 INFO - PROCESS | 1658 | RSS: Main: 381313024 03:12:46 INFO - PROCESS | 1658 | 03:15:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49261/tests/dromaeo/cssquery-mootools.html) 03:15:49 INFO - PROCESS | 1658 | RSS: Main: 371666944 03:15:49 INFO - PROCESS | 1658 | 03:18:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49261/tests/dromaeo/cssquery-prototype.html) 03:18:52 INFO - PROCESS | 1658 | RSS: Main: 372252672 03:18:52 INFO - PROCESS | 1658 | 03:21:56 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49261/tests/dromaeo/cssquery-yui.html) 03:21:56 INFO - PROCESS | 1658 | RSS: Main: 342884352 03:21:56 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-yui.html (next: http://localhost:49261/tests/dromaeo/cssquery-dojo.html) 03:25:00 INFO - PROCESS | 1658 | RSS: Main: 388001792 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | __start_tp_report 03:25:00 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 03:25:00 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 03:25:00 INFO - PROCESS | 1658 | |i|pagename|runs| 03:25:00 INFO - PROCESS | 1658 | |0;dojo.html;3336.366090442815;3362.9663703362976;3394.9151271218207;3405.335959487898;3429.759916805823;5552.11166213406;5594.188842617828;5636.0982242841155;5664.915026274609;5667.319921609413;5624.100143976964;5661.2923384576925;5763.82708518744;5765.394633563468;5799.304083509975;5064.316317297173;5073.1122053640465;5146.202338637517;5161.638685292031;5232.869178270555;5223.686578805284;5236.738163091849;5256.5794736421185;5266.578673706094;5291.497307755757;5080.74596270185;5134.1528647773075;5148.897022059557;5151.896962060775;5197.58419326453;5182.844514664566;5188.143956247215;5188.507091826271;5193.870153246161;5202.661826981234;5041.798328066873;5094.439611642716;5120.308758317654;5151.304573882516;5161.767720452589;4832.251001094836;5324.0150572144275;5667.744951477193;5686.431356864322;5710.857228569277;6194.721237544321;6246.687665616746;6253.843653908643;6255.74977000924;6270.467010304084;6109.222480666408;6129.059628563038;6248.9062664060075;6256.217972753406;6273.62358258506;5823.155642431909;5829.766809327622;5875.823725288248;5876.529877609823;5894.587378883437;6219.129321894939;6227.159333490012;6228.941080016408;6239.875202495925;6243.875122497616;5979.222701048836;6057.394260573908;6084.60450070744;6097.645669838137;6118.449339559372;6260;6271.62370257786;6280.152179455807;6281.528885333527;6290.591111577733;5243.344581927259;6212.347703491158;6231.22109736283;6243.43809057187;6255.437010669061;5334.946650533445;6029.547783916224;6125.809638818719;6146.907796382969;6209.534284928648;4908.705477671351;4964.652474326763;4973.229149481836;4973.925391119136;4987;4448.977755111268;4450.821967121344;4460.1971645104195;4499.460064792246;4510.165619360363;5592.748326325325;5596.552275817925;5673.120666296731;5788.88422231553;5808;6178.783742568989;6254.21822272216;6256.405641464054;6259;6261.530385221126;5730.570207234474;5745.798897038667;5790.826275211751;5844.357120716718;5846.795362162304;5442.183672449085;5452.972735136299;5459.153831156177;5459.617826752089;5468.179773034077;5324.840254792362;6205.47253483459;6242.344553821783;6249;6345.984642457184;6214.9689251553455;6256.937430625818;6270.905936410866;6288.151099601495;6308.243010838729;6266.968665156645;6297.023961286008;6306.558540902093;6307.90538141919;6318.304986451579;6088.939110608837;6121.300552271563;6231.314555398993;6234.314225435108;6252.5935814171935;6199.535034872493;6219.440250377488;6245.344238855037;6286.119943207863;6299.937000629935;6160;6161.722722477589;6168.845778855564;6170.136180934763;6173.84565385869;5959.67221802805;5984.820455386345;6145.600535965148;6149.877002459836;6153.4154255345675;5751.281089863767;5831.7375718091935;5834.94165058344;5876.764929402776;5924.437178468039;6003.489703375262;6018.037114061729;6035.6076855004285;6053.152558641882;6065.181200538047;5810.593258471867;5858.8242352729485;5868.559858010752;5868.618539794899;5871.647701137946 03:25:00 INFO - PROCESS | 1658 | |1;ext.html;469.65480371926634;532.5899057725549;535.4270930104784;543.2638774460607;543.7770514089224;30544.84727576362;42298.55865134159;43066;43138.7843060785;43165.92085197871;44486.33270500945;44854.32718509217;44896.10207795842;44948.55051449485;45006.099878002424;8759.737207883774;9704.708858734231;9882.308238423313;9882.703518894445;9886.110250077492;8714.346424018193;9722.951385243065;12015.219010764273;12036;12364.814527782046;12108.394580270995;12142.817857732141;12213.961813245885;12225.694357641041;12229.021678265763;8691.174338437839;11871.10966677494;12010.579629713005;12021.09841761867;12123.81814272791;11952.58165964187;12013.819792703116;12034.578789742318;12036.819447708203;12062.819057714141;41667.58332416668;41962.58037419648;42028.36957445641;42049;42058.78970605128;3485.982570087159;3543.362194804934;4034.11249525104;4288.849890253826;4296.162248361571;32216.35567288641;32812.6718732812;33116;33153;33432.33135337304;3232.563603913489;3407;3413.0784688134063;3427.691507764313;3430.639782822818;328170;356239.21880390693;358318;359001;360296;61276.69361653163;61498;62002;62028.79712028761;62053.37946620476;56046.71976640172;63974.32308123055;64027.039594406124;64194.679026604565;64195.67902160459;16318.878545788972;17089.743653845202;17108.14459277056;17132.571685707957;17170.484885453458;11968;12192.634220973385;12900.387451568555;13096.607101786962;13127.146735462164;16816.495505134866;16850.578735531464;16861.91569042147;16878.57803554921;16894.662106757794;7112.537685050455;7132.964335178291;7137.714491420285;7156.069710937545;7227.096612923385;919.852823548229;984.2126298960802;1046.0219694585587;1092.7541303206715;1109.0960866893456;26338.259113359778;31407.685923140933;31434.68565314363;31509.369812604076;32056.679433205834;1100.5872797700863;1113.4544073403974;1124.2186680257307;1157.6874243954253;1183.7632473505334;15151.848481515264;16283.02301861892;16546.751798723028;16656.16719164037;17001.23494442753;703649;960982;973958;976755;977153;62728.68635656792;62831.37168628347;63033;63154.684226578574;63250.68374658097;1279.8720127987312;1328.388941087072;1424.921629310398;1447.3703938786662;1461.3570029187124;33655.83172084124;34007.659923401436;34080.829595851865;35069.64930350766;35099.82450087733;60689.69655151696;61563;64068.35931640624;65433.67283163554;65467;580702;648006;655790;656691;656937;39261.41107883327;47702.28446573235;47936.7603161982;48211.794705132656;48312.275315869585;52281.7385913068;53295.73352133214;53413.46586534085;53958.730206348715;54047.45952540424;42042;42249.155016900106;42273;42744;43824;42821.79567914436;42841.78579107085;43276;43924.56075439205;44070;40787.49368835288;41173.176536468505;42245.1550968985;42299.36550951676;42486.787566063205;35543.28913421769;36109;36935.89192324234;37504.624953751205;37514;27196.592051118852;27248.727512724618;27297.863510683113;27307;27344 03:25:00 INFO - PROCESS | 1658 | |2;jquery.html;361.45059509545507;385.3199103582177;393.4668524149777;393.673251201503;414.3991212741524;15533.378664853406;17104.315827366907;20687.896560517212;21225;21261.57476850464;21102.788972110273;21376.8931155344;21412.785872141274;21449;21453.356399308006;5838.007538718428;5838.562107841918;6042.788502402417;6083.8174854754425;6095.177151084613;5983.2820061592665;6037.426444487766;6078.787242446515;6087.939120608781;6093.299270583873;5752.8849423011725;6018.15745795589;6022;6029.457348838604;6033.5173186145;5731.398203188647;5920.851978700523;5929.644221346712;5956.97021514894;5967.761289548434;5195.142801437796;5234.031704134709;5236.973815130938;5242.239875218072;5242.816501422432;21373.786262137335;25720.356991075372;25912.87043564789;26161.345966350804;26448.8677556611;2243.741969673492;2548.745125487455;3050.764440401641;3089.5365695145906;3257.96742032579;13089.018323625765;16039.35842566296;16464.670706585803;16486.010839349678;16579.171041448;1636.5826714187924;1784.9379619126598;1794.4975406886092;1825.7261410788356;1972.6942323939668;81344;118406.22390664146;119719.99999999913;120262;121679.39160304141;10899.073578745736;11136.554537818396;11382.430878456044;11432.714182145346;11458.770824583462;13863.653408664864;14012.789808152886;14017.649558761113;14033.78949315761;14041.368138433794;8719.65121395137;8905.198532132139;8906.688265910792;9005.594748236344;9026;8956.596953137125;8961.865572016424;8965.462072275684;9088.909110908939;9092.772680683036;6765.091010865375;6883;6885.51801373899;6886.414654754402;6920.4809639277255;1656.7846179996523;1862.5622978600018;1867.430433717729;1902.6692301930302;1906.170815695177;918.5636822509255;1084.5499117866154;1084.958439897707;1092.8797832238447;1131.603938621476;22372.216972405888;22754.65868011981;22761.544769104526;23067.07731690747;23154.18960336414;980.6273616025865;996.7508122969258;1033.4006276359694;1038.11760003996;1042.4683411460057;10532.631357902437;11575.76848463026;11950.115400235767;11963.641090767116;12009.699757506132;584665;602985;612109;614773;624788;114356;115101.84898150912;115170.42414787873;118163.40918295353;118410;1033.1837848100067;1048.2714513413257;1053.4785281285813;1068.8396740488834;1072.5066469424153;19558.21767129299;19791.802081978996;19960.70058949088;19967.800321996594;20054.799452005293;36815;37917;45566.54433455623;50646;54722.726386367816;78839.21160788547;79167.20832791828;86800.56599717213;90283.54858225667;90925;5565.526930210977;6446.613203207635;7509.286617771303;7991.480553763987;8379.120192379713;7437.181909989788;10070.446125462877;13556.441009284059;13608.115472494255;13673.111247768864;11476.483558240066;11616.709582260512;11877.049836013271;11880.227785193934;11890;9725.270604704534;11169.888301116885;11443.542258309908;11633.603967523952;11865.940670296593;11250.66248012561;11257.380844053656;11691.240069395462;11965.641030769091;12039.518419263131;7235.891461627974;8262.586870656323;8459.365547584079;8561.357898157788;8692.304615630608;10100.131475677023;11736.413179341169;11923.165378423428;11946.641600751991;11950.521979120738 03:25:00 INFO - PROCESS | 1658 | |3;mootools.html;1408.6196726883736;1566.7179907616623;1599.2003998001;1825.2607693883974;1961.2253160001799;7889.960550197248;8618.095100014494;8802.295816334694;8815.603297851596;8946.418482798614;8523.74428767138;8789.252913502343;8796.16436438539;8799.736007919757;8852.291816654668;2088.3734879536155;2166.3717521918625;2168.4362065862833;2336.707911511061;2369.6208606622945;2305.6310990241564;2389.832711710181;2391.198948352304;2392.2105705117356;2392.617181251;2322.1175953137786;2347.8239132065078;2360.36270207044;2361.268006917861;2368.3368656776042;2068.720722702431;2262.5361800830956;2344.0272287000876;2345.8827058647084;2350.400647834791;2376.6910301660846;2395.8203134764963;2396.508715713275;2397.160993652225;2402.519496100769;8813.603387847563;8859.114088591154;8861.335399844973;8870.423422477583;8874.334424918092;5190.143626301656;5344.839654810362;5353.759080841332;5358.33020872391;5358.3569971603265;3439.174598096464;3445.6554344565593;3511.94732079019;3548.964510354915;3559.039059453959;1066.0512144191598;1071.1002757683584;1085.4572713643179;1088.5754555723274;1093.5516438260274;11134;11399.544018239343;11457;11675.00762435194;11694.239874408137;4419.955800442019;4482.618977386894;4552.3171524270965;4587.243104887723;4607.976960115178;9059.36584439096;9062.954685226663;9065;9090.454572725657;9091.999880013193;4408.338749187647;5226.216067589892;5230.607704422184;5234.869128271747;5241.659292145999;4234.513031001413;4423.62399196072;4529;4896.094222568836;4914.238293064581;3683.465897444909;3756.9248615027545;3775.4336849472797;3830.544393130648;3856.1130939883983;1123.8258069999163;1148.6668866028924;1157.5080590748898;1159.4492616007328;1160.9593664221711;803.5500119932856;840.9243168114776;870.1820288928387;933.3046880074388;954.6229239450518;5742.770289188469;5864.885671722359;6029.698515074228;6055.697215139226;6081.422264884829;385.95368555773484;439.6329065230568;443.45898004434537;445.6234481862821;456.60275560262784;3820.808959552067;3913.8043097844993;3949.6642785363565;3949.9012524687114;3985.980070099689;10343.741406464898;10537.789244215226;10882.347059176474;10902.836457453144;10915.126789856793;10716.839247411295;10848.077913377294;10853.945730271298;10856.511456984455;10874.891251087387;8289;8293.83412331762;8348.624311905978;8413.621387037476;8421.242088212213;5902.793402231073;5905.173275741314;5905.970470147622;5939.792107276225;6005.459508644243;9644.903550964682;10052.949735251277;10070.44612546317;10098.747531311776;10156.898431015596;7981.561014144278;9891.604335826487;10288.897111028793;10392.688219353431;10409.271351005356;6282.654454005074;6940.201876784033;7047.048648432396;7160.069191005136;7162.964185179249;6692.397684208445;8236.50580965144;8328.08391076969;8431.95784021076;8434.367422443464;7482.326590606872;7496.925030749622;7527.059117610299;7606.847863042819;7634.770956871303;7305.707771689073;7386.667599958122;7445.664945077377;7515.436342274242;7520.247975202436;6037.728302226323;6762.243399221652;7127.073480447509;7134.357907788324;7136.714531418893;4953.257011448168;4992.925106123484;5034.119029169954;5051.949480505295;5104.719240441811;5135.127028405105;5155.304033955518;5170.224466330081;5252.343457067866;5301.469853014668 03:25:00 INFO - PROCESS | 1658 | |4;prototype.html;299.6673692201656;305.18819938962355;313.3810723820455;317.12473572938694;317.30668489350757;5179.922301165476;5227.954409118172;5339.665083729068;5353.9196912046355;5416.810411635589;4345.630621397185;4915.901681966358;5153.428204397657;5236.036486317621;5275.235090911825;4087.937136344542;4094.8566800162002;4097.63121319082;4104.994276402271;4115.115250221202;3403.8468268927836;3884.3590807516725;4017.3328068851392;4111.321631930728;4116.423700681907;3948.565657777642;3962.9894376933985;4033.28585351226;4091.24312002279;4108.671306295504;4002.579729128458;4018.959810401888;4050.7772072536;4061.801768478306;4065.7967101645095;3533.575970883485;3547.343741407848;3548.2193917338145;3563.1804684922354;3577.8926632201074;5081.237814327844;5105.5537512179135;5121.334226550562;5206.76569554371;5220.895582088337;4287.399764033038;4498.110793466719;4675.532446755331;4678.508756580544;4697.412823397075;4576.741396116061;4791.544803243686;5088.516590923893;5419.783208671648;5509.338879334466;3177.523371494294;3574.9821250893838;3700.0749812546865;3766.435034744783;4020.296448121567;5581.66040150365;5635.1828984797385;5713.8285851424525;5735.598508104435;5964.045752679551;3273.1489812648883;3731.8431286301334;3901.551321597996;3906.609339066071;3932.8426862925735;3148.842557872097;3668.4497325401403;3861.768293902319;4068.2270368629866;4086.7956602169775;3867.7872717000273;3883.3398322284716;4666.620023787893;4676.976615116971;4692.718436893797;4665.346851440801;5391.541718953855;5628.634138780966;5651.598070819885;5672.461116193955;1336.224989506084;1374.3197117426937;1377.2975782351073;1389.7915312703176;1393.1571399303275;689.9960557388987;820.3806126374668;855.5636625321007;860.3805260212637;874.4030230216248;3761.32296186689;3958.7426817256787;4246.133859644728;4443.111677537098;4453.910921781546;3873.6320049595242;4062.979685101556;4171.833126674899;4434.13534360803;4498.50516443191;4020.6582440492884;4146.792660366969;4423.778811059434;4436.646822719037;4486.484054333728;2531.379811946048;2719.3276135176725;2898.854952293832;3010.2775333919703;3046.6953304669355;4921.581665558396;5229.947700522946;5393.568514518829;5846.1230815377185;5930.7924222652;5178.352705911761;5769.865149894566;5801.015447769232;5847.976604094351;5876.324222714357;5451.509364157245;5707.71461426927;5985.431384018511;5993;6009.759609615654;5746.540276777764;5767.423257674199;5769.653820770767;5770.307563092456;5802.303723553032;5046.091703493406;5360.9731951341555;5672.319321681424;5685.815943071893;5701.686407247641;5190.740462976761;5277.604179686616;5534.3673616284095;5629.915551266652;5861.882762344814;3975.7614543127506;4074.103697186614;4074.409210664496;4078.2455245778438;4134.772587507716;5402.945970540244;5776.335721391925;5828.708564571839;5832.358440571618;5871.823845284648;5319.494648008433;5619.466150715675;5625.5218306442775;5663.377028526941;5673.914891276717;5127.077126117335;5531.363893152339;5581;5583.385827558884;5626.493615574618;4484.260097083945;4880.80476780939;5235.240890070994;5501.559875210054;5526.889462210814;4878.731250062396;5166.844994650167;5584.860378490408;5677.460641239076;5698.886022279614;4270.829166833292;5508.586855985737;5556.805511807067;5590.608657393944;5620.325560932714 03:25:00 INFO - PROCESS | 1658 | |5;yui.html;747.8279995601011;751.8496300739854;782.632162883445;854.9102344253852;864.7146441674248;15062.623434414132;16358.918205408985;16374.263158157883;16402.26189821458;16434.260458279376;14795.556133315991;15026.398944042225;15046.473373431932;15063.774043389358;15074.698506029872;690.6995456976219;711.3633298198109;865.9134086591322;897.3583887520422;899.4243684042218;865.0268447996004;866.8483015472299;867.0505796153202;868.1448772958631;870.4081224767156;910.867924151001;925.8009527951489;929.8977112517617;930.2000279759402;931.6599441203946;920.0891117793403;920.3833431600776;922.4649703172144;922.5525620074292;928.03020843219;543.3181357396481;681.3901558105517;685.2256949646867;701.6035939694108;702.3117345001926;15131.167785771779;15169.544913652608;15182.544523664308;15197.468088616846;15207.619809504851;13518.526851560147;14091.859081409157;14102.294885255798;14102.506412275521;14130.081544699562;12704.301263430507;12781.488740450372;12808.615741527768;13136.949044076451;13296.202227866263;4544.568266014757;4585.679002469828;4587.770611469413;4605.976970115162;4614.676972611918;94699;94925.5253723727;100762.9923700761;104131.99999999924;106264;10426.895731042592;10750.731231719114;10790.298630588986;11163.330200188015;11528.250663706833;21770.346889593337;22082.33752987413;22103.005364758625;22105.33683989483;22141.225057122923;6988.475864310197;7966.960165199137;7992.920070799218;8121.472104313319;8288.875666865002;4540.818367265272;4639.930401043987;4663.207254766698;4766.451858036301;4847.709137451764;2912.3738396244908;3577.266660334677;3583.677469027748;3585.1754096557415;3589.4436362363876;637.4772686397203;640.8397900524869;646.1954866191621;650.1580453312971;653.8201994451468;614.5575185866169;664.8730401968617;689.8275431142215;690.2924502384996;700.6146619359332;7818.68725250995;9418.29362797793;9550.188234000048;9634.325597208128;9754.853677194847;581.825452364289;626.614632001316;665.6838001949133;692.594832023272;710.3535782437957;4638.2115040443205;4751.382320298409;4759.214729569582;4774.247032326039;4886.731229782396;131131;143431;149432.56241813392;152495;158140;77859;78459.60770196113;79107.04464776738;110855;126298;100109;108526;114202;115097.42451287857;119394.4030279843;9796.853047204155;9881.407115573089;9882.11061004513;9882.752931176778;9891.802163956825;47756.52243477521;53921.19118213309;58075.57904103966;59757;66854;74392;93581.5320923391;93892.59161112452;94793;95483;13599.728005440034;13818.86181138216;13825.861741382458;14253.287335633384;14288.857111428753;15588.610284742972;15727.764083538985;15989.760153597472;16040;16258.51224463268;14260.928695356457;14371.712565748836;14390.496332628307;14444.566663000127;14807;14021.929890350892;14176.645583860074;14588.92705536508;14612.926935365254;15128.54614361571;14263;14657.04729192599;14914.254287285808;14931.477398291008;14947.402103915721;11444.535187140238;11532.827007594726;11544.94227528857;11683.474243659226;11841.644750657493;10389.324693894872;11073.390963497084;11220.719482013015;11313.71715707081;11676.474558645052 03:25:00 INFO - PROCESS | 1658 | __end_tp_report 03:25:00 INFO - PROCESS | 1658 | __start_cc_report 03:25:00 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1300 03:25:00 INFO - PROCESS | 1658 | __end_cc_report 03:25:00 INFO - PROCESS | 1658 | __startTimestamp1470306300361__endTimestamp 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | ------- Summary: start ------- 03:25:00 INFO - PROCESS | 1658 | Number of tests: 6 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5714.50 Median:5871.65 stddev:634.97 (10.8%) stddev-sans-first:608.89 03:25:00 INFO - PROCESS | 1658 | Values: 3336.4 3363.0 3394.9 3405.3 3429.8 5552.1 5594.2 5636.1 5664.9 5667.3 5624.1 5661.3 5763.8 5765.4 5799.3 5064.3 5073.1 5146.2 5161.6 5232.9 5223.7 5236.7 5256.6 5266.6 5291.5 5080.7 5134.2 5148.9 5151.9 5197.6 5182.8 5188.1 5188.5 5193.9 5202.7 5041.8 5094.4 5120.3 5151.3 5161.8 4832.3 5324.0 5667.7 5686.4 5710.9 6194.7 6246.7 6253.8 6255.7 6270.5 6109.2 6129.1 6248.9 6256.2 6273.6 5823.2 5829.8 5875.8 5876.5 5894.6 6219.1 6227.2 6228.9 6239.9 6243.9 5979.2 6057.4 6084.6 6097.6 6118.4 6260.0 6271.6 6280.2 6281.5 6290.6 5243.3 6212.3 6231.2 6243.4 6255.4 5334.9 6029.5 6125.8 6146.9 6209.5 4908.7 4964.7 4973.2 4973.9 4987.0 4449.0 4450.8 4460.2 4499.5 4510.2 5592.7 5596.6 5673.1 5788.9 5808.0 6178.8 6254.2 6256.4 6259.0 6261.5 5730.6 5745.8 5790.8 5844.4 5846.8 5442.2 5453.0 5459.2 5459.6 5468.2 5324.8 6205.5 6242.3 6249.0 6346.0 6215.0 6256.9 6270.9 6288.2 6308.2 6267.0 6297.0 6306.6 6307.9 6318.3 6088.9 6121.3 6231.3 6234.3 6252.6 6199.5 6219.4 6245.3 6286.1 6299.9 6160.0 6161.7 6168.8 6170.1 6173.8 5959.7 5984.8 6145.6 6149.9 6153.4 5751.3 5831.7 5834.9 5876.8 5924.4 6003.5 6018.0 6035.6 6053.2 6065.2 5810.6 5858.8 5868.6 5868.6 5871.6 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:78203.81 Median:40980.34 stddev:183694.04 (448.2%) stddev-sans-first:184116.63 03:25:00 INFO - PROCESS | 1658 | Values: 469.7 532.6 535.4 543.3 543.8 30544.8 42298.6 43066.0 43138.8 43165.9 44486.3 44854.3 44896.1 44948.6 45006.1 8759.7 9704.7 9882.3 9882.7 9886.1 8714.3 9723.0 12015.2 12036.0 12364.8 12108.4 12142.8 12214.0 12225.7 12229.0 8691.2 11871.1 12010.6 12021.1 12123.8 11952.6 12013.8 12034.6 12036.8 12062.8 41667.6 41962.6 42028.4 42049.0 42058.8 3486.0 3543.4 4034.1 4288.8 4296.2 32216.4 32812.7 33116.0 33153.0 33432.3 3232.6 3407.0 3413.1 3427.7 3430.6 328170.0 356239.2 358318.0 359001.0 360296.0 61276.7 61498.0 62002.0 62028.8 62053.4 56046.7 63974.3 64027.0 64194.7 64195.7 16318.9 17089.7 17108.1 17132.6 17170.5 11968.0 12192.6 12900.4 13096.6 13127.1 16816.5 16850.6 16861.9 16878.6 16894.7 7112.5 7133.0 7137.7 7156.1 7227.1 919.9 984.2 1046.0 1092.8 1109.1 26338.3 31407.7 31434.7 31509.4 32056.7 1100.6 1113.5 1124.2 1157.7 1183.8 15151.8 16283.0 16546.8 16656.2 17001.2 703649.0 960982.0 973958.0 976755.0 977153.0 62728.7 62831.4 63033.0 63154.7 63250.7 1279.9 1328.4 1424.9 1447.4 1461.4 33655.8 34007.7 34080.8 35069.6 35099.8 60689.7 61563.0 64068.4 65433.7 65467.0 580702.0 648006.0 655790.0 656691.0 656937.0 39261.4 47702.3 47936.8 48211.8 48312.3 52281.7 53295.7 53413.5 53958.7 54047.5 42042.0 42249.2 42273.0 42744.0 43824.0 42821.8 42841.8 43276.0 43924.6 44070.0 40787.5 41173.2 42245.2 42299.4 42486.8 35543.3 36109.0 36935.9 37504.6 37514.0 27196.6 27248.7 27297.9 27307.0 27344.0 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35132.40 Median:12307.98 stddev:101009.06 (820.7%) stddev-sans-first:101258.67 03:25:00 INFO - PROCESS | 1658 | Values: 361.5 385.3 393.5 393.7 414.4 15533.4 17104.3 20687.9 21225.0 21261.6 21102.8 21376.9 21412.8 21449.0 21453.4 5838.0 5838.6 6042.8 6083.8 6095.2 5983.3 6037.4 6078.8 6087.9 6093.3 5752.9 6018.2 6022.0 6029.5 6033.5 5731.4 5920.9 5929.6 5957.0 5967.8 5195.1 5234.0 5237.0 5242.2 5242.8 21373.8 25720.4 25912.9 26161.3 26448.9 2243.7 2548.7 3050.8 3089.5 3258.0 13089.0 16039.4 16464.7 16486.0 16579.2 1636.6 1784.9 1794.5 1825.7 1972.7 81344.0 118406.2 119720.0 120262.0 121679.4 10899.1 11136.6 11382.4 11432.7 11458.8 13863.7 14012.8 14017.6 14033.8 14041.4 8719.7 8905.2 8906.7 9005.6 9026.0 8956.6 8961.9 8965.5 9088.9 9092.8 6765.1 6883.0 6885.5 6886.4 6920.5 1656.8 1862.6 1867.4 1902.7 1906.2 918.6 1084.5 1085.0 1092.9 1131.6 22372.2 22754.7 22761.5 23067.1 23154.2 980.6 996.8 1033.4 1038.1 1042.5 10532.6 11575.8 11950.1 11963.6 12009.7 584665.0 602985.0 612109.0 614773.0 624788.0 114356.0 115101.8 115170.4 118163.4 118410.0 1033.2 1048.3 1053.5 1068.8 1072.5 19558.2 19791.8 19960.7 19967.8 20054.8 36815.0 37917.0 45566.5 50646.0 54722.7 78839.2 79167.2 86800.6 90283.5 90925.0 5565.5 6446.6 7509.3 7991.5 8379.1 7437.2 10070.4 13556.4 13608.1 13673.1 11476.5 11616.7 11877.0 11880.2 11890.0 9725.3 11169.9 11443.5 11633.6 11865.9 11250.7 11257.4 11691.2 11965.6 12039.5 7235.9 8262.6 8459.4 8561.4 8692.3 10100.1 11736.4 11923.2 11946.6 11950.5 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5636.46 Median:4752.04 stddev:3192.04 (67.2%) stddev-sans-first:3185.18 03:25:00 INFO - PROCESS | 1658 | Values: 1408.6 1566.7 1599.2 1825.3 1961.2 7890.0 8618.1 8802.3 8815.6 8946.4 8523.7 8789.3 8796.2 8799.7 8852.3 2088.4 2166.4 2168.4 2336.7 2369.6 2305.6 2389.8 2391.2 2392.2 2392.6 2322.1 2347.8 2360.4 2361.3 2368.3 2068.7 2262.5 2344.0 2345.9 2350.4 2376.7 2395.8 2396.5 2397.2 2402.5 8813.6 8859.1 8861.3 8870.4 8874.3 5190.1 5344.8 5353.8 5358.3 5358.4 3439.2 3445.7 3511.9 3549.0 3559.0 1066.1 1071.1 1085.5 1088.6 1093.6 11134.0 11399.5 11457.0 11675.0 11694.2 4420.0 4482.6 4552.3 4587.2 4608.0 9059.4 9063.0 9065.0 9090.5 9092.0 4408.3 5226.2 5230.6 5234.9 5241.7 4234.5 4423.6 4529.0 4896.1 4914.2 3683.5 3756.9 3775.4 3830.5 3856.1 1123.8 1148.7 1157.5 1159.4 1161.0 803.6 840.9 870.2 933.3 954.6 5742.8 5864.9 6029.7 6055.7 6081.4 386.0 439.6 443.5 445.6 456.6 3820.8 3913.8 3949.7 3949.9 3986.0 10343.7 10537.8 10882.3 10902.8 10915.1 10716.8 10848.1 10853.9 10856.5 10874.9 8289.0 8293.8 8348.6 8413.6 8421.2 5902.8 5905.2 5906.0 5939.8 6005.5 9644.9 10052.9 10070.4 10098.7 10156.9 7981.6 9891.6 10288.9 10392.7 10409.3 6282.7 6940.2 7047.0 7160.1 7163.0 6692.4 8236.5 8328.1 8432.0 8434.4 7482.3 7496.9 7527.1 7606.8 7634.8 7305.7 7386.7 7445.7 7515.4 7520.2 6037.7 6762.2 7127.1 7134.4 7136.7 4953.3 4992.9 5034.1 5051.9 5104.7 5135.1 5155.3 5170.2 5252.3 5301.5 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4440.51 Median:4835.14 stddev:1367.64 (28.3%) stddev-sans-first:1335.69 03:25:00 INFO - PROCESS | 1658 | Values: 299.7 305.2 313.4 317.1 317.3 5179.9 5228.0 5339.7 5353.9 5416.8 4345.6 4915.9 5153.4 5236.0 5275.2 4087.9 4094.9 4097.6 4105.0 4115.1 3403.8 3884.4 4017.3 4111.3 4116.4 3948.6 3963.0 4033.3 4091.2 4108.7 4002.6 4019.0 4050.8 4061.8 4065.8 3533.6 3547.3 3548.2 3563.2 3577.9 5081.2 5105.6 5121.3 5206.8 5220.9 4287.4 4498.1 4675.5 4678.5 4697.4 4576.7 4791.5 5088.5 5419.8 5509.3 3177.5 3575.0 3700.1 3766.4 4020.3 5581.7 5635.2 5713.8 5735.6 5964.0 3273.1 3731.8 3901.6 3906.6 3932.8 3148.8 3668.4 3861.8 4068.2 4086.8 3867.8 3883.3 4666.6 4677.0 4692.7 4665.3 5391.5 5628.6 5651.6 5672.5 1336.2 1374.3 1377.3 1389.8 1393.2 690.0 820.4 855.6 860.4 874.4 3761.3 3958.7 4246.1 4443.1 4453.9 3873.6 4063.0 4171.8 4434.1 4498.5 4020.7 4146.8 4423.8 4436.6 4486.5 2531.4 2719.3 2898.9 3010.3 3046.7 4921.6 5229.9 5393.6 5846.1 5930.8 5178.4 5769.9 5801.0 5848.0 5876.3 5451.5 5707.7 5985.4 5993.0 6009.8 5746.5 5767.4 5769.7 5770.3 5802.3 5046.1 5361.0 5672.3 5685.8 5701.7 5190.7 5277.6 5534.4 5629.9 5861.9 3975.8 4074.1 4074.4 4078.2 4134.8 5402.9 5776.3 5828.7 5832.4 5871.8 5319.5 5619.5 5625.5 5663.4 5673.9 5127.1 5531.4 5581.0 5583.4 5626.5 4484.3 4880.8 5235.2 5501.6 5526.9 4878.7 5166.8 5584.9 5677.5 5698.9 4270.8 5508.6 5556.8 5590.6 5620.3 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:23742.60 Median:4542.69 stddev:36679.15 (807.4%) stddev-sans-first:36741.41 03:25:00 INFO - PROCESS | 1658 | Values: 747.8 751.8 782.6 854.9 864.7 15062.6 16358.9 16374.3 16402.3 16434.3 14795.6 15026.4 15046.5 15063.8 15074.7 690.7 711.4 865.9 897.4 899.4 865.0 866.8 867.1 868.1 870.4 910.9 925.8 929.9 930.2 931.7 920.1 920.4 922.5 922.6 928.0 543.3 681.4 685.2 701.6 702.3 15131.2 15169.5 15182.5 15197.5 15207.6 13518.5 14091.9 14102.3 14102.5 14130.1 12704.3 12781.5 12808.6 13136.9 13296.2 4544.6 4585.7 4587.8 4606.0 4614.7 94699.0 94925.5 100763.0 104132.0 106264.0 10426.9 10750.7 10790.3 11163.3 11528.3 21770.3 22082.3 22103.0 22105.3 22141.2 6988.5 7967.0 7992.9 8121.5 8288.9 4540.8 4639.9 4663.2 4766.5 4847.7 2912.4 3577.3 3583.7 3585.2 3589.4 637.5 640.8 646.2 650.2 653.8 614.6 664.9 689.8 690.3 700.6 7818.7 9418.3 9550.2 9634.3 9754.9 581.8 626.6 665.7 692.6 710.4 4638.2 4751.4 4759.2 4774.2 4886.7 131131.0 143431.0 149432.6 152495.0 158140.0 77859.0 78459.6 79107.0 110855.0 126298.0 100109.0 108526.0 114202.0 115097.4 119394.4 9796.9 9881.4 9882.1 9882.8 9891.8 47756.5 53921.2 58075.6 59757.0 66854.0 74392.0 93581.5 93892.6 94793.0 95483.0 13599.7 13818.9 13825.9 14253.3 14288.9 15588.6 15727.8 15989.8 16040.0 16258.5 14260.9 14371.7 14390.5 14444.6 14807.0 14021.9 14176.6 14588.9 14612.9 15128.5 14263.0 14657.0 14914.3 14931.5 14947.4 11444.5 11532.8 11544.9 11683.5 11841.6 10389.3 11073.4 11220.7 11313.7 11676.5 03:25:00 INFO - PROCESS | 1658 | -------- Summary: end -------- 03:25:00 INFO - PROCESS | 1658 | 03:25:00 INFO - TEST-INFO | 1658: exit 0 03:25:00 INFO - TEST-OK | dromaeo_css | took 1117044ms 03:25:00 INFO - TEST-START | kraken 03:25:00 INFO - operating with platform_type : mac_ 03:25:00 INFO - Initialising browser for kraken test... 03:25:00 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpvhfA_G/profile http://localhost:49261/getInfo.html) 03:25:03 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 03:25:03 INFO - PROCESS | 1663 | colorDepth:24 03:25:03 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 03:25:03 INFO - PROCESS | 1663 | __metrics 03:25:03 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:25:03 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:25:04 INFO - TEST-INFO | 1663: exit 0 03:25:04 INFO - Browser initialized. 03:25:04 INFO - Running cycle 1/1 for kraken test... 03:25:04 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpvhfA_G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:25:16 INFO - PROCESS | 1665 | RSS: Main: 243859456 03:25:16 INFO - PROCESS | 1665 | 03:25:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-beat-detection) 03:25:19 INFO - PROCESS | 1665 | RSS: Main: 294510592 03:25:19 INFO - PROCESS | 1665 | 03:25:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-dft) 03:25:23 INFO - PROCESS | 1665 | RSS: Main: 304635904 03:25:23 INFO - PROCESS | 1665 | 03:25:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-fft) 03:25:27 INFO - PROCESS | 1665 | RSS: Main: 388677632 03:25:27 INFO - PROCESS | 1665 | 03:25:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-oscillator) 03:25:30 INFO - PROCESS | 1665 | RSS: Main: 290095104 03:25:30 INFO - PROCESS | 1665 | 03:25:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49261/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:25:32 INFO - PROCESS | 1665 | RSS: Main: 294567936 03:25:32 INFO - PROCESS | 1665 | 03:25:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49261/tests/kraken/driver.html?testName=imaging-darkroom) 03:25:36 INFO - PROCESS | 1665 | RSS: Main: 330375168 03:25:36 INFO - PROCESS | 1665 | 03:25:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49261/tests/kraken/driver.html?testName=imaging-desaturate) 03:25:39 INFO - PROCESS | 1665 | RSS: Main: 334483456 03:25:39 INFO - PROCESS | 1665 | 03:25:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49261/tests/kraken/driver.html?testName=json-parse-financial) 03:25:43 INFO - PROCESS | 1665 | RSS: Main: 333012992 03:25:43 INFO - PROCESS | 1665 | 03:25:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49261/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:25:45 INFO - PROCESS | 1665 | RSS: Main: 312156160 03:25:45 INFO - PROCESS | 1665 | 03:25:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:25:47 INFO - PROCESS | 1665 | RSS: Main: 319586304 03:25:47 INFO - PROCESS | 1665 | 03:25:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:25:50 INFO - PROCESS | 1665 | RSS: Main: 316182528 03:25:50 INFO - PROCESS | 1665 | 03:25:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:25:54 INFO - PROCESS | 1665 | RSS: Main: 324911104 03:25:54 INFO - PROCESS | 1665 | 03:25:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:25:57 INFO - PROCESS | 1665 | RSS: Main: 327577600 03:25:57 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49261/tests/kraken/driver.html?testName=ai-astar) 03:25:59 INFO - PROCESS | 1665 | RSS: Main: 371019776 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | __start_tp_report 03:25:59 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 03:25:59 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 03:25:59 INFO - PROCESS | 1665 | |i|pagename|runs| 03:25:59 INFO - PROCESS | 1665 | |0;ai-astar;100;112;112;112;113;112;112;112;109;112 03:25:59 INFO - PROCESS | 1665 | |1;audio-beat-detection;148;108;99;154;126;130;128;104;158;105 03:25:59 INFO - PROCESS | 1665 | |2;audio-dft;197;262;203;183;182;181;182;182;182;180 03:25:59 INFO - PROCESS | 1665 | |3;audio-fft;78;73;57;107;57;68;64;76;106;78 03:25:59 INFO - PROCESS | 1665 | |4;audio-oscillator;82;90;90;71;70;67;96;70;67;97 03:25:59 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;89;87;90;87;89;88;77;113;131;100 03:25:59 INFO - PROCESS | 1665 | |6;imaging-darkroom;95;95;95;96;95;95;96;96;96;95 03:25:59 INFO - PROCESS | 1665 | |7;imaging-desaturate;84;84;86;85;84;83;83;86;85;87 03:25:59 INFO - PROCESS | 1665 | |8;json-parse-financial;55;54;54;55;56;56;54;54;57;55 03:25:59 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;67;78;80;70;74;71;71;71;70 03:25:59 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;83;93;99;98;84;84;84;81;93;107 03:25:59 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;140;141;136;136;137;135;134;122;228;138 03:25:59 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;157;215;219;162;163;161;166;163;159;221 03:25:59 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;66;66;67;66;66;66;66;68;66;66 03:25:59 INFO - PROCESS | 1665 | __end_tp_report 03:25:59 INFO - PROCESS | 1665 | __start_cc_report 03:25:59 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2046 03:25:59 INFO - PROCESS | 1665 | __end_cc_report 03:25:59 INFO - PROCESS | 1665 | __startTimestamp1470306359839__endTimestamp 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | ------- Summary: start ------- 03:25:59 INFO - PROCESS | 1665 | Number of tests: 14 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.86 (3.5%) stddev-sans-first:1.09 03:25:59 INFO - PROCESS | 1665 | Values: 100.0 112.0 112.0 112.0 113.0 112.0 112.0 112.0 109.0 112.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:139.00 stddev:21.83 (15.7%) stddev-sans-first:21.66 03:25:59 INFO - PROCESS | 1665 | Values: 148.0 108.0 99.0 154.0 126.0 130.0 128.0 104.0 158.0 105.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:193.40 Median:182.50 stddev:25.33 (13.9%) stddev-sans-first:26.83 03:25:59 INFO - PROCESS | 1665 | Values: 197.0 262.0 203.0 183.0 182.0 181.0 182.0 182.0 182.0 180.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:76.40 Median:70.50 stddev:17.66 (25.0%) stddev-sans-first:18.72 03:25:59 INFO - PROCESS | 1665 | Values: 78.0 73.0 57.0 107.0 57.0 68.0 64.0 76.0 106.0 78.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:86.00 stddev:12.33 (14.3%) stddev-sans-first:13.06 03:25:59 INFO - PROCESS | 1665 | Values: 82.0 90.0 90.0 71.0 70.0 67.0 96.0 70.0 67.0 97.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:87.50 stddev:15.79 (18.0%) stddev-sans-first:16.59 03:25:59 INFO - PROCESS | 1665 | Values: 89.0 87.0 90.0 87.0 89.0 88.0 77.0 113.0 131.0 100.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 03:25:59 INFO - PROCESS | 1665 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.39 03:25:59 INFO - PROCESS | 1665 | Values: 84.0 84.0 86.0 85.0 84.0 83.0 83.0 86.0 85.0 87.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.05 (1.9%) stddev-sans-first:1.12 03:25:59 INFO - PROCESS | 1665 | Values: 55.0 54.0 54.0 55.0 56.0 56.0 54.0 54.0 57.0 55.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.00 stddev:4.42 (6.2%) stddev-sans-first:4.16 03:25:59 INFO - PROCESS | 1665 | Values: 66.0 67.0 78.0 80.0 70.0 74.0 71.0 71.0 71.0 70.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:88.50 stddev:8.73 (9.9%) stddev-sans-first:8.82 03:25:59 INFO - PROCESS | 1665 | Values: 83.0 93.0 99.0 98.0 84.0 84.0 84.0 81.0 93.0 107.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:137.50 stddev:29.73 (21.6%) stddev-sans-first:31.48 03:25:59 INFO - PROCESS | 1665 | Values: 140.0 141.0 136.0 136.0 137.0 135.0 134.0 122.0 228.0 138.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:164.50 stddev:27.56 (16.8%) stddev-sans-first:28.10 03:25:59 INFO - PROCESS | 1665 | Values: 157.0 215.0 219.0 162.0 163.0 161.0 166.0 163.0 159.0 221.0 03:25:59 INFO - PROCESS | 1665 | 03:25:59 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 03:25:59 INFO - PROCESS | 1665 | Values: 66.0 66.0 67.0 66.0 66.0 66.0 66.0 68.0 66.0 66.0 03:25:59 INFO - PROCESS | 1665 | -------- Summary: end -------- 03:25:59 INFO - PROCESS | 1665 | 03:26:00 INFO - TEST-INFO | 1665: exit 0 03:26:00 INFO - TEST-OK | kraken | took 59693ms 03:26:00 INFO - SUITE-END | took 1176s 03:26:01 INFO - Completed test suite (00:19:37) 03:26:01 INFO - javascript benchmark 03:26:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.366090442815, 3362.9663703362976, 3394.9151271218207, 3405.335959487898, 3429.759916805823, 5552.11166213406, 5594.188842617828, 5636.0982242841155, 5664.915026274609, 5667.319921609413, 5624.100143976964, 5661.2923384576925, 5763.82708518744, 5765.394633563468, 5799.304083509975, 5064.316317297173, 5073.1122053640465, 5146.202338637517, 5161.638685292031, 5232.869178270555, 5223.686578805284, 5236.738163091849, 5256.5794736421185, 5266.578673706094, 5291.497307755757, 5080.74596270185, 5134.1528647773075, 5148.897022059557, 5151.896962060775, 5197.58419326453, 5182.844514664566, 5188.143956247215, 5188.507091826271, 5193.870153246161, 5202.661826981234, 5041.798328066873, 5094.439611642716, 5120.308758317654, 5151.304573882516, 5161.767720452589, 4832.251001094836, 5324.0150572144275, 5667.744951477193, 5686.431356864322, 5710.857228569277, 6194.721237544321, 6246.687665616746, 6253.843653908643, 6255.74977000924, 6270.467010304084, 6109.222480666408, 6129.059628563038, 6248.9062664060075, 6256.217972753406, 6273.62358258506, 5823.155642431909, 5829.766809327622, 5875.823725288248, 5876.529877609823, 5894.587378883437, 6219.129321894939, 6227.159333490012, 6228.941080016408, 6239.875202495925, 6243.875122497616, 5979.222701048836, 6057.394260573908, 6084.60450070744, 6097.645669838137, 6118.449339559372, 6260.0, 6271.62370257786, 6280.152179455807, 6281.528885333527, 6290.591111577733, 5243.344581927259, 6212.347703491158, 6231.22109736283, 6243.43809057187, 6255.437010669061, 5334.946650533445, 6029.547783916224, 6125.809638818719, 6146.907796382969, 6209.534284928648, 4908.705477671351, 4964.652474326763, 4973.229149481836, 4973.925391119136, 4987.0, 4448.977755111268, 4450.821967121344, 4460.1971645104195, 4499.460064792246, 4510.165619360363, 5592.748326325325, 5596.552275817925, 5673.120666296731, 5788.88422231553, 5808.0, 6178.783742568989, 6254.21822272216, 6256.405641464054, 6259.0, 6261.530385221126, 5730.570207234474, 5745.798897038667, 5790.826275211751, 5844.357120716718, 5846.795362162304, 5442.183672449085, 5452.972735136299, 5459.153831156177, 5459.617826752089, 5468.179773034077, 5324.840254792362, 6205.47253483459, 6242.344553821783, 6249.0, 6345.984642457184, 6214.9689251553455, 6256.937430625818, 6270.905936410866, 6288.151099601495, 6308.243010838729, 6266.968665156645, 6297.023961286008, 6306.558540902093, 6307.90538141919, 6318.304986451579, 6088.939110608837, 6121.300552271563, 6231.314555398993, 6234.314225435108, 6252.5935814171935, 6199.535034872493, 6219.440250377488, 6245.344238855037, 6286.119943207863, 6299.937000629935, 6160.0, 6161.722722477589, 6168.845778855564, 6170.136180934763, 6173.84565385869, 5959.67221802805, 5984.820455386345, 6145.600535965148, 6149.877002459836, 6153.4154255345675, 5751.281089863767, 5831.7375718091935, 5834.94165058344, 5876.764929402776, 5924.437178468039, 6003.489703375262, 6018.037114061729, 6035.6076855004285, 6053.152558641882, 6065.181200538047, 5810.593258471867, 5858.8242352729485, 5868.559858010752, 5868.618539794899, 5871.647701137946], "value": 5674.755594009798, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.65480371926634, 532.5899057725549, 535.4270930104784, 543.2638774460607, 543.7770514089224, 30544.84727576362, 42298.55865134159, 43066.0, 43138.7843060785, 43165.92085197871, 44486.33270500945, 44854.32718509217, 44896.10207795842, 44948.55051449485, 45006.099878002424, 8759.737207883774, 9704.708858734231, 9882.308238423313, 9882.703518894445, 9886.110250077492, 8714.346424018193, 9722.951385243065, 12015.219010764273, 12036.0, 12364.814527782046, 12108.394580270995, 12142.817857732141, 12213.961813245885, 12225.694357641041, 12229.021678265763, 8691.174338437839, 11871.10966677494, 12010.579629713005, 12021.09841761867, 12123.81814272791, 11952.58165964187, 12013.819792703116, 12034.578789742318, 12036.819447708203, 12062.819057714141, 41667.58332416668, 41962.58037419648, 42028.36957445641, 42049.0, 42058.78970605128, 3485.982570087159, 3543.362194804934, 4034.11249525104, 4288.849890253826, 4296.162248361571, 32216.35567288641, 32812.6718732812, 33116.0, 33153.0, 33432.33135337304, 3232.563603913489, 3407.0, 3413.0784688134063, 3427.691507764313, 3430.639782822818, 328170.0, 356239.21880390693, 358318.0, 359001.0, 360296.0, 61276.69361653163, 61498.0, 62002.0, 62028.79712028761, 62053.37946620476, 56046.71976640172, 63974.32308123055, 64027.039594406124, 64194.679026604565, 64195.67902160459, 16318.878545788972, 17089.743653845202, 17108.14459277056, 17132.571685707957, 17170.484885453458, 11968.0, 12192.634220973385, 12900.387451568555, 13096.607101786962, 13127.146735462164, 16816.495505134866, 16850.578735531464, 16861.91569042147, 16878.57803554921, 16894.662106757794, 7112.537685050455, 7132.964335178291, 7137.714491420285, 7156.069710937545, 7227.096612923385, 919.852823548229, 984.2126298960802, 1046.0219694585587, 1092.7541303206715, 1109.0960866893456, 26338.259113359778, 31407.685923140933, 31434.68565314363, 31509.369812604076, 32056.679433205834, 1100.5872797700863, 1113.4544073403974, 1124.2186680257307, 1157.6874243954253, 1183.7632473505334, 15151.848481515264, 16283.02301861892, 16546.751798723028, 16656.16719164037, 17001.23494442753, 703649.0, 960982.0, 973958.0, 976755.0, 977153.0, 62728.68635656792, 62831.37168628347, 63033.0, 63154.684226578574, 63250.68374658097, 1279.8720127987312, 1328.388941087072, 1424.921629310398, 1447.3703938786662, 1461.3570029187124, 33655.83172084124, 34007.659923401436, 34080.829595851865, 35069.64930350766, 35099.82450087733, 60689.69655151696, 61563.0, 64068.35931640624, 65433.67283163554, 65467.0, 580702.0, 648006.0, 655790.0, 656691.0, 656937.0, 39261.41107883327, 47702.28446573235, 47936.7603161982, 48211.794705132656, 48312.275315869585, 52281.7385913068, 53295.73352133214, 53413.46586534085, 53958.730206348715, 54047.45952540424, 42042.0, 42249.155016900106, 42273.0, 42744.0, 43824.0, 42821.79567914436, 42841.78579107085, 43276.0, 43924.56075439205, 44070.0, 40787.49368835288, 41173.176536468505, 42245.1550968985, 42299.36550951676, 42486.787566063205, 35543.28913421769, 36109.0, 36935.89192324234, 37504.624953751205, 37514.0, 27196.592051118852, 27248.727512724618, 27297.863510683113, 27307.0, 27344.0], "value": 21728.92592808977, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.45059509545507, 385.3199103582177, 393.4668524149777, 393.673251201503, 414.3991212741524, 15533.378664853406, 17104.315827366907, 20687.896560517212, 21225.0, 21261.57476850464, 21102.788972110273, 21376.8931155344, 21412.785872141274, 21449.0, 21453.356399308006, 5838.007538718428, 5838.562107841918, 6042.788502402417, 6083.8174854754425, 6095.177151084613, 5983.2820061592665, 6037.426444487766, 6078.787242446515, 6087.939120608781, 6093.299270583873, 5752.8849423011725, 6018.15745795589, 6022.0, 6029.457348838604, 6033.5173186145, 5731.398203188647, 5920.851978700523, 5929.644221346712, 5956.97021514894, 5967.761289548434, 5195.142801437796, 5234.031704134709, 5236.973815130938, 5242.239875218072, 5242.816501422432, 21373.786262137335, 25720.356991075372, 25912.87043564789, 26161.345966350804, 26448.8677556611, 2243.741969673492, 2548.745125487455, 3050.764440401641, 3089.5365695145906, 3257.96742032579, 13089.018323625765, 16039.35842566296, 16464.670706585803, 16486.010839349678, 16579.171041448, 1636.5826714187924, 1784.9379619126598, 1794.4975406886092, 1825.7261410788356, 1972.6942323939668, 81344.0, 118406.22390664146, 119719.99999999913, 120262.0, 121679.39160304141, 10899.073578745736, 11136.554537818396, 11382.430878456044, 11432.714182145346, 11458.770824583462, 13863.653408664864, 14012.789808152886, 14017.649558761113, 14033.78949315761, 14041.368138433794, 8719.65121395137, 8905.198532132139, 8906.688265910792, 9005.594748236344, 9026.0, 8956.596953137125, 8961.865572016424, 8965.462072275684, 9088.909110908939, 9092.772680683036, 6765.091010865375, 6883.0, 6885.51801373899, 6886.414654754402, 6920.4809639277255, 1656.7846179996523, 1862.5622978600018, 1867.430433717729, 1902.6692301930302, 1906.170815695177, 918.5636822509255, 1084.5499117866154, 1084.958439897707, 1092.8797832238447, 1131.603938621476, 22372.216972405888, 22754.65868011981, 22761.544769104526, 23067.07731690747, 23154.18960336414, 980.6273616025865, 996.7508122969258, 1033.4006276359694, 1038.11760003996, 1042.4683411460057, 10532.631357902437, 11575.76848463026, 11950.115400235767, 11963.641090767116, 12009.699757506132, 584665.0, 602985.0, 612109.0, 614773.0, 624788.0, 114356.0, 115101.84898150912, 115170.42414787873, 118163.40918295353, 118410.0, 1033.1837848100067, 1048.2714513413257, 1053.4785281285813, 1068.8396740488834, 1072.5066469424153, 19558.21767129299, 19791.802081978996, 19960.70058949088, 19967.800321996594, 20054.799452005293, 36815.0, 37917.0, 45566.54433455623, 50646.0, 54722.726386367816, 78839.21160788547, 79167.20832791828, 86800.56599717213, 90283.54858225667, 90925.0, 5565.526930210977, 6446.613203207635, 7509.286617771303, 7991.480553763987, 8379.120192379713, 7437.181909989788, 10070.446125462877, 13556.441009284059, 13608.115472494255, 13673.111247768864, 11476.483558240066, 11616.709582260512, 11877.049836013271, 11880.227785193934, 11890.0, 9725.270604704534, 11169.888301116885, 11443.542258309908, 11633.603967523952, 11865.940670296593, 11250.66248012561, 11257.380844053656, 11691.240069395462, 11965.641030769091, 12039.518419263131, 7235.891461627974, 8262.586870656323, 8459.365547584079, 8561.357898157788, 8692.304615630608, 10100.131475677023, 11736.413179341169, 11923.165378423428, 11946.641600751991, 11950.521979120738], "value": 9928.26189324778, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.6196726883736, 1566.7179907616623, 1599.2003998001, 1825.2607693883974, 1961.2253160001799, 7889.960550197248, 8618.095100014494, 8802.295816334694, 8815.603297851596, 8946.418482798614, 8523.74428767138, 8789.252913502343, 8796.16436438539, 8799.736007919757, 8852.291816654668, 2088.3734879536155, 2166.3717521918625, 2168.4362065862833, 2336.707911511061, 2369.6208606622945, 2305.6310990241564, 2389.832711710181, 2391.198948352304, 2392.2105705117356, 2392.617181251, 2322.1175953137786, 2347.8239132065078, 2360.36270207044, 2361.268006917861, 2368.3368656776042, 2068.720722702431, 2262.5361800830956, 2344.0272287000876, 2345.8827058647084, 2350.400647834791, 2376.6910301660846, 2395.8203134764963, 2396.508715713275, 2397.160993652225, 2402.519496100769, 8813.603387847563, 8859.114088591154, 8861.335399844973, 8870.423422477583, 8874.334424918092, 5190.143626301656, 5344.839654810362, 5353.759080841332, 5358.33020872391, 5358.3569971603265, 3439.174598096464, 3445.6554344565593, 3511.94732079019, 3548.964510354915, 3559.039059453959, 1066.0512144191598, 1071.1002757683584, 1085.4572713643179, 1088.5754555723274, 1093.5516438260274, 11134.0, 11399.544018239343, 11457.0, 11675.00762435194, 11694.239874408137, 4419.955800442019, 4482.618977386894, 4552.3171524270965, 4587.243104887723, 4607.976960115178, 9059.36584439096, 9062.954685226663, 9065.0, 9090.454572725657, 9091.999880013193, 4408.338749187647, 5226.216067589892, 5230.607704422184, 5234.869128271747, 5241.659292145999, 4234.513031001413, 4423.62399196072, 4529.0, 4896.094222568836, 4914.238293064581, 3683.465897444909, 3756.9248615027545, 3775.4336849472797, 3830.544393130648, 3856.1130939883983, 1123.8258069999163, 1148.6668866028924, 1157.5080590748898, 1159.4492616007328, 1160.9593664221711, 803.5500119932856, 840.9243168114776, 870.1820288928387, 933.3046880074388, 954.6229239450518, 5742.770289188469, 5864.885671722359, 6029.698515074228, 6055.697215139226, 6081.422264884829, 385.95368555773484, 439.6329065230568, 443.45898004434537, 445.6234481862821, 456.60275560262784, 3820.808959552067, 3913.8043097844993, 3949.6642785363565, 3949.9012524687114, 3985.980070099689, 10343.741406464898, 10537.789244215226, 10882.347059176474, 10902.836457453144, 10915.126789856793, 10716.839247411295, 10848.077913377294, 10853.945730271298, 10856.511456984455, 10874.891251087387, 8289.0, 8293.83412331762, 8348.624311905978, 8413.621387037476, 8421.242088212213, 5902.793402231073, 5905.173275741314, 5905.970470147622, 5939.792107276225, 6005.459508644243, 9644.903550964682, 10052.949735251277, 10070.44612546317, 10098.747531311776, 10156.898431015596, 7981.561014144278, 9891.604335826487, 10288.897111028793, 10392.688219353431, 10409.271351005356, 6282.654454005074, 6940.201876784033, 7047.048648432396, 7160.069191005136, 7162.964185179249, 6692.397684208445, 8236.50580965144, 8328.08391076969, 8431.95784021076, 8434.367422443464, 7482.326590606872, 7496.925030749622, 7527.059117610299, 7606.847863042819, 7634.770956871303, 7305.707771689073, 7386.667599958122, 7445.664945077377, 7515.436342274242, 7520.247975202436, 6037.728302226323, 6762.243399221652, 7127.073480447509, 7134.357907788324, 7136.714531418893, 4953.257011448168, 4992.925106123484, 5034.119029169954, 5051.949480505295, 5104.719240441811, 5135.127028405105, 5155.304033955518, 5170.224466330081, 5252.343457067866, 5301.469853014668], "value": 4441.635822490652, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.6673692201656, 305.18819938962355, 313.3810723820455, 317.12473572938694, 317.30668489350757, 5179.922301165476, 5227.954409118172, 5339.665083729068, 5353.9196912046355, 5416.810411635589, 4345.630621397185, 4915.901681966358, 5153.428204397657, 5236.036486317621, 5275.235090911825, 4087.937136344542, 4094.8566800162002, 4097.63121319082, 4104.994276402271, 4115.115250221202, 3403.8468268927836, 3884.3590807516725, 4017.3328068851392, 4111.321631930728, 4116.423700681907, 3948.565657777642, 3962.9894376933985, 4033.28585351226, 4091.24312002279, 4108.671306295504, 4002.579729128458, 4018.959810401888, 4050.7772072536, 4061.801768478306, 4065.7967101645095, 3533.575970883485, 3547.343741407848, 3548.2193917338145, 3563.1804684922354, 3577.8926632201074, 5081.237814327844, 5105.5537512179135, 5121.334226550562, 5206.76569554371, 5220.895582088337, 4287.399764033038, 4498.110793466719, 4675.532446755331, 4678.508756580544, 4697.412823397075, 4576.741396116061, 4791.544803243686, 5088.516590923893, 5419.783208671648, 5509.338879334466, 3177.523371494294, 3574.9821250893838, 3700.0749812546865, 3766.435034744783, 4020.296448121567, 5581.66040150365, 5635.1828984797385, 5713.8285851424525, 5735.598508104435, 5964.045752679551, 3273.1489812648883, 3731.8431286301334, 3901.551321597996, 3906.609339066071, 3932.8426862925735, 3148.842557872097, 3668.4497325401403, 3861.768293902319, 4068.2270368629866, 4086.7956602169775, 3867.7872717000273, 3883.3398322284716, 4666.620023787893, 4676.976615116971, 4692.718436893797, 4665.346851440801, 5391.541718953855, 5628.634138780966, 5651.598070819885, 5672.461116193955, 1336.224989506084, 1374.3197117426937, 1377.2975782351073, 1389.7915312703176, 1393.1571399303275, 689.9960557388987, 820.3806126374668, 855.5636625321007, 860.3805260212637, 874.4030230216248, 3761.32296186689, 3958.7426817256787, 4246.133859644728, 4443.111677537098, 4453.910921781546, 3873.6320049595242, 4062.979685101556, 4171.833126674899, 4434.13534360803, 4498.50516443191, 4020.6582440492884, 4146.792660366969, 4423.778811059434, 4436.646822719037, 4486.484054333728, 2531.379811946048, 2719.3276135176725, 2898.854952293832, 3010.2775333919703, 3046.6953304669355, 4921.581665558396, 5229.947700522946, 5393.568514518829, 5846.1230815377185, 5930.7924222652, 5178.352705911761, 5769.865149894566, 5801.015447769232, 5847.976604094351, 5876.324222714357, 5451.509364157245, 5707.71461426927, 5985.431384018511, 5993.0, 6009.759609615654, 5746.540276777764, 5767.423257674199, 5769.653820770767, 5770.307563092456, 5802.303723553032, 5046.091703493406, 5360.9731951341555, 5672.319321681424, 5685.815943071893, 5701.686407247641, 5190.740462976761, 5277.604179686616, 5534.3673616284095, 5629.915551266652, 5861.882762344814, 3975.7614543127506, 4074.103697186614, 4074.409210664496, 4078.2455245778438, 4134.772587507716, 5402.945970540244, 5776.335721391925, 5828.708564571839, 5832.358440571618, 5871.823845284648, 5319.494648008433, 5619.466150715675, 5625.5218306442775, 5663.377028526941, 5673.914891276717, 5127.077126117335, 5531.363893152339, 5581.0, 5583.385827558884, 5626.493615574618, 4484.260097083945, 4880.80476780939, 5235.240890070994, 5501.559875210054, 5526.889462210814, 4878.731250062396, 5166.844994650167, 5584.860378490408, 5677.460641239076, 5698.886022279614, 4270.829166833292, 5508.586855985737, 5556.805511807067, 5590.608657393944, 5620.325560932714], "value": 4008.3737562792876, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.8279995601011, 751.8496300739854, 782.632162883445, 854.9102344253852, 864.7146441674248, 15062.623434414132, 16358.918205408985, 16374.263158157883, 16402.26189821458, 16434.260458279376, 14795.556133315991, 15026.398944042225, 15046.473373431932, 15063.774043389358, 15074.698506029872, 690.6995456976219, 711.3633298198109, 865.9134086591322, 897.3583887520422, 899.4243684042218, 865.0268447996004, 866.8483015472299, 867.0505796153202, 868.1448772958631, 870.4081224767156, 910.867924151001, 925.8009527951489, 929.8977112517617, 930.2000279759402, 931.6599441203946, 920.0891117793403, 920.3833431600776, 922.4649703172144, 922.5525620074292, 928.03020843219, 543.3181357396481, 681.3901558105517, 685.2256949646867, 701.6035939694108, 702.3117345001926, 15131.167785771779, 15169.544913652608, 15182.544523664308, 15197.468088616846, 15207.619809504851, 13518.526851560147, 14091.859081409157, 14102.294885255798, 14102.506412275521, 14130.081544699562, 12704.301263430507, 12781.488740450372, 12808.615741527768, 13136.949044076451, 13296.202227866263, 4544.568266014757, 4585.679002469828, 4587.770611469413, 4605.976970115162, 4614.676972611918, 94699.0, 94925.5253723727, 100762.9923700761, 104131.99999999924, 106264.0, 10426.895731042592, 10750.731231719114, 10790.298630588986, 11163.330200188015, 11528.250663706833, 21770.346889593337, 22082.33752987413, 22103.005364758625, 22105.33683989483, 22141.225057122923, 6988.475864310197, 7966.960165199137, 7992.920070799218, 8121.472104313319, 8288.875666865002, 4540.818367265272, 4639.930401043987, 4663.207254766698, 4766.451858036301, 4847.709137451764, 2912.3738396244908, 3577.266660334677, 3583.677469027748, 3585.1754096557415, 3589.4436362363876, 637.4772686397203, 640.8397900524869, 646.1954866191621, 650.1580453312971, 653.8201994451468, 614.5575185866169, 664.8730401968617, 689.8275431142215, 690.2924502384996, 700.6146619359332, 7818.68725250995, 9418.29362797793, 9550.188234000048, 9634.325597208128, 9754.853677194847, 581.825452364289, 626.614632001316, 665.6838001949133, 692.594832023272, 710.3535782437957, 4638.2115040443205, 4751.382320298409, 4759.214729569582, 4774.247032326039, 4886.731229782396, 131131.0, 143431.0, 149432.56241813392, 152495.0, 158140.0, 77859.0, 78459.60770196113, 79107.04464776738, 110855.0, 126298.0, 100109.0, 108526.0, 114202.0, 115097.42451287857, 119394.4030279843, 9796.853047204155, 9881.407115573089, 9882.11061004513, 9882.752931176778, 9891.802163956825, 47756.52243477521, 53921.19118213309, 58075.57904103966, 59757.0, 66854.0, 74392.0, 93581.5320923391, 93892.59161112452, 94793.0, 95483.0, 13599.728005440034, 13818.86181138216, 13825.861741382458, 14253.287335633384, 14288.857111428753, 15588.610284742972, 15727.764083538985, 15989.760153597472, 16040.0, 16258.51224463268, 14260.928695356457, 14371.712565748836, 14390.496332628307, 14444.566663000127, 14807.0, 14021.929890350892, 14176.645583860074, 14588.92705536508, 14612.926935365254, 15128.54614361571, 14263.0, 14657.04729192599, 14914.254287285808, 14931.477398291008, 14947.402103915721, 11444.535187140238, 11532.827007594726, 11544.94227528857, 11683.474243659226, 11841.644750657493, 10389.324693894872, 11073.390963497084, 11220.719482013015, 11313.71715707081, 11676.474558645052], "value": 7955.793273113539, "unit": "score"}], "name": "dromaeo_css", "value": 7467.654537592061, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0, 112.0, 109.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 108.0, 99.0, 154.0, 126.0, 130.0, 128.0, 104.0, 158.0, 105.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 262.0, 203.0, 183.0, 182.0, 181.0, 182.0, 182.0, 182.0, 180.0], "value": 193.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 57.0, 107.0, 57.0, 68.0, 64.0, 76.0, 106.0, 78.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 90.0, 71.0, 70.0, 67.0, 96.0, 70.0, 67.0, 97.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 89.0, 88.0, 77.0, 113.0, 131.0, 100.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 85.0, 84.0, 83.0, 83.0, 86.0, 85.0, 87.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 55.0, 56.0, 56.0, 54.0, 54.0, 57.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 78.0, 80.0, 70.0, 74.0, 71.0, 71.0, 71.0, 70.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 99.0, 98.0, 84.0, 84.0, 84.0, 81.0, 93.0, 107.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 136.0, 136.0, 137.0, 135.0, 134.0, 122.0, 228.0, 138.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 215.0, 219.0, 162.0, 163.0, 161.0, 166.0, 163.0, 159.0, 221.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 68.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1468.6, "alertThreshold": 2.0}]} 03:26:01 INFO - Return code: 0 03:26:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:26:01 INFO - # TBPL SUCCESS # 03:26:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:26:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:26:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:26:01 INFO - 2016-08-04 03:26:01.171 screenresolution[1669:9813] starting screenresolution argv=screenresolution get 03:26:01 INFO - 2016-08-04 03:26:01.174 screenresolution[1669:9813] Display 0: 1600x1200x32@60 03:26:01 INFO - 2016-08-04 03:26:01.180 screenresolution[1670:9816] starting screenresolution argv=screenresolution list 03:26:01 INFO - Available Modes on Display 0 03:26:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:26:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:26:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:26:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:26:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:26:01 INFO - Intel Iris: 03:26:01 INFO - Chipset Model: Intel Iris 03:26:01 INFO - Type: GPU 03:26:01 INFO - Bus: Built-In 03:26:01 INFO - VRAM (Dynamic, Max): 1536 MB 03:26:01 INFO - Vendor: Intel (0x8086) 03:26:01 INFO - Device ID: 0x0a2e 03:26:01 INFO - Revision ID: 0x0009 03:26:01 INFO - Displays: 03:26:01 INFO - Display: 03:26:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:26:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:26:01 INFO - Display Serial Number: 1600x1200 60 03:26:01 INFO - Main Display: Yes 03:26:01 INFO - Mirror: Off 03:26:01 INFO - Online: Yes 03:26:01 INFO - Rotation: Supported 03:26:01 INFO - Return code: 0 03:26:01 INFO - Running post-action listener: _resource_record_post_action 03:26:01 INFO - ##### 03:26:01 INFO - ##### Finished run-tests step (success) 03:26:01 INFO - ##### 03:26:01 INFO - Running post-run listener: _resource_record_post_run 03:26:01 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31841792; Write bytes: 705026048; Read time: 192; Write time: 2006 03:26:01 INFO - TinderboxPrint: CPU usage
53.3% 03:26:01 INFO - TinderboxPrint: I/O read bytes / time
31,841,792 / 192 03:26:01 INFO - TinderboxPrint: I/O write bytes / time
705,026,048 / 2,006 03:26:01 INFO - TinderboxPrint: CPU idle
2,269.0 (46.7%) 03:26:01 INFO - TinderboxPrint: CPU system
964.5 (19.9%) 03:26:01 INFO - TinderboxPrint: CPU user
1,620.1 (33.4%) 03:26:01 INFO - TinderboxPrint: Swap in / out
433,803,264 / 0 03:26:01 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 400095744; Write bytes: 394397696; Read time: 30271; Write time: 881 03:26:01 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29875712; Write bytes: 295071744; Read time: 175; Write time: 1085 03:26:01 INFO - Running post-run listener: _upload_blobber_files 03:26:01 INFO - Blob upload gear active. 03:26:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:26:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:26:02 INFO - (blobuploader) - INFO - Open directory for files ... 03:26:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:26:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:26:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:26:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:26:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:26:03 INFO - (blobuploader) - INFO - Done attempting. 03:26:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:26:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:26:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:26:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:26:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:26:03 INFO - (blobuploader) - INFO - Done attempting. 03:26:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:26:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:26:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:26:06 INFO - (blobuploader) - INFO - Done attempting. 03:26:06 INFO - (blobuploader) - INFO - Iteration through files over. 03:26:06 INFO - Return code: 0 03:26:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:26:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:26:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3a37b48994642f546eb8b3baeceb6fee0452aebaac14de55ae832e47aebfc2459262d83314734d8e7d5b740a1db26caa0fafdc60ed3bbd1351ea0a85c1a7e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f49bd5247c4b622d44807f92b10681d32c701f6c6005f45e53d9e76b7301b596f7bc4bd94d06aa798e99975c4ec352c0ed198e518e9bd14d8f38b8eb2e473233", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/365fc8b009a5cfc877e1404f7202a22d6b49531036abe8051706fc91063c9f884815c04ea0487607fec71ef50742b56186689680c1de9a0a738f8e3e8c7547a3"} 03:26:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:26:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:26:06 INFO - Contents: 03:26:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3a37b48994642f546eb8b3baeceb6fee0452aebaac14de55ae832e47aebfc2459262d83314734d8e7d5b740a1db26caa0fafdc60ed3bbd1351ea0a85c1a7e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f49bd5247c4b622d44807f92b10681d32c701f6c6005f45e53d9e76b7301b596f7bc4bd94d06aa798e99975c4ec352c0ed198e518e9bd14d8f38b8eb2e473233", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/365fc8b009a5cfc877e1404f7202a22d6b49531036abe8051706fc91063c9f884815c04ea0487607fec71ef50742b56186689680c1de9a0a738f8e3e8c7547a3"} 03:26:06 INFO - Running post-run listener: copy_logs_to_upload_dir 03:26:06 INFO - Copying logs to upload dir... 03:26:06 INFO - mkdir: /builds/slave/test/build/upload/logs 03:26:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.855292 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-08-04 03:26:06.432977) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 03:26:06.436924) ========= 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.fbEZE7aVXX/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.o63iksQe0F/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3a37b48994642f546eb8b3baeceb6fee0452aebaac14de55ae832e47aebfc2459262d83314734d8e7d5b740a1db26caa0fafdc60ed3bbd1351ea0a85c1a7e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f49bd5247c4b622d44807f92b10681d32c701f6c6005f45e53d9e76b7301b596f7bc4bd94d06aa798e99975c4ec352c0ed198e518e9bd14d8f38b8eb2e473233", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/365fc8b009a5cfc877e1404f7202a22d6b49531036abe8051706fc91063c9f884815c04ea0487607fec71ef50742b56186689680c1de9a0a738f8e3e8c7547a3"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011154 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1468144921/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3a37b48994642f546eb8b3baeceb6fee0452aebaac14de55ae832e47aebfc2459262d83314734d8e7d5b740a1db26caa0fafdc60ed3bbd1351ea0a85c1a7e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f49bd5247c4b622d44807f92b10681d32c701f6c6005f45e53d9e76b7301b596f7bc4bd94d06aa798e99975c4ec352c0ed198e518e9bd14d8f38b8eb2e473233", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/365fc8b009a5cfc877e1404f7202a22d6b49531036abe8051706fc91063c9f884815c04ea0487607fec71ef50742b56186689680c1de9a0a738f8e3e8c7547a3"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 03:26:06.556607) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 03:26:06.556935) ========= 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.fbEZE7aVXX/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.o63iksQe0F/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.005222 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 03:26:06.582858) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 03:26:06.583191) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 03:26:06.583555) ========= ========= Total master_lag: 0.30 =========