builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0354 starttime: 1467648457.8 results: success (0) buildid: 20160630030201 builduid: ee9e9e5bb18c43e1aef8c9c84c97401e revision: d700dc054751333e0735f975fce3d3adf153c62a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:37.798837) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:37.799181) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-04 09:07:37.799446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-04 09:07:40.789334) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:40.789652) ========= 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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-04 09:07:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.4M=0.001s 2016-07-04 09:07:40 (14.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141072 ========= master_lag: 0.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:41.601913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:41.602191) ========= 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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.026187 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:41.650891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-04 09:07:41.651187) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d700dc054751333e0735f975fce3d3adf153c62a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d700dc054751333e0735f975fce3d3adf153c62a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vr1BjKEUhz/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.HzPKQoOMd2/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-04 09:07:41,730 truncating revision to first 12 chars 2016-07-04 09:07:41,730 Setting DEBUG logging. 2016-07-04 09:07:41,730 attempt 1/10 2016-07-04 09:07:41,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d700dc054751?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 09:07:42,415 unpacking tar archive at: mozilla-central-d700dc054751/testing/mozharness/ program finished with exit code 0 elapsedTime=0.958065 ========= master_lag: 1.76 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-04 09:07:44.364754) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:44.365058) ========= script_repo_revision: d700dc054751333e0735f975fce3d3adf153c62a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:44.365413) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:44.365691) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 09:07:44.398675) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-04 09:07:44.399069) ========= /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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:07:44 INFO - MultiFileLogger online at 20160704 09:07:44 in /builds/slave/test 09:07:44 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 09:07:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:44 INFO - {'append_to_log': False, 09:07:44 INFO - 'base_work_dir': '/builds/slave/test', 09:07:44 INFO - 'blob_upload_branch': 'Firefox', 09:07:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:44 INFO - 'branch': 'Firefox', 09:07:44 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:44 INFO - 'config_files': ('talos/mac_config.py',), 09:07:44 INFO - 'default_actions': ('clobber', 09:07:44 INFO - 'read-buildbot-config', 09:07:44 INFO - 'download-and-extract', 09:07:44 INFO - 'populate-webroot', 09:07:44 INFO - 'create-virtualenv', 09:07:44 INFO - 'install', 09:07:44 INFO - 'run-tests'), 09:07:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:44 INFO - 'download_minidump_stackwalk': True, 09:07:44 INFO - 'download_symbols': 'ondemand', 09:07:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:44 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:44 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:44 INFO - 'installer_path': 'installer.exe', 09:07:44 INFO - 'log_level': 'info', 09:07:44 INFO - 'log_name': 'talos', 09:07:44 INFO - 'log_to_console': True, 09:07:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:07:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:07:44 INFO - 'opt_config_files': (), 09:07:44 INFO - 'pip_index': False, 09:07:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:07:44 INFO - 'cmd': ('bash', 09:07:44 INFO - '-c', 09:07:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:07:44 INFO - 'enabled': True, 09:07:44 INFO - 'halt_on_failure': False, 09:07:44 INFO - 'name': 'check_screen_resolution'},), 09:07:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:07:44 INFO - 'cmd': ('bash', 09:07:44 INFO - '-c', 09:07:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:07:44 INFO - 'enabled': True, 09:07:44 INFO - 'halt_on_failure': False, 09:07:44 INFO - 'name': 'check_screen_resolution'},), 09:07:44 INFO - 'run_cmd_checks_enabled': True, 09:07:44 INFO - 'sps_profile': False, 09:07:44 INFO - 'sps_profile_interval': 0, 09:07:44 INFO - 'suite': 'dromaeojs', 09:07:44 INFO - 'system_bits': '32', 09:07:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:44 INFO - 'title': 't-yosemite-r7-0354', 09:07:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:44 INFO - 'use_talos_json': True, 09:07:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:44 INFO - 'work_dir': 'build'} 09:07:44 INFO - ##### 09:07:44 INFO - ##### Running clobber step. 09:07:44 INFO - ##### 09:07:44 INFO - Running pre-action listener: _resource_record_pre_action 09:07:44 INFO - Running main action method: clobber 09:07:44 INFO - rmtree: /builds/slave/test/build 09:07:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:46 INFO - Running post-action listener: _resource_record_post_action 09:07:46 INFO - ##### 09:07:46 INFO - ##### Finished clobber step (success) 09:07:46 INFO - ##### 09:07:46 INFO - ##### 09:07:46 INFO - ##### Running read-buildbot-config step. 09:07:46 INFO - ##### 09:07:46 INFO - Running pre-action listener: _resource_record_pre_action 09:07:46 INFO - Running main action method: read_buildbot_config 09:07:46 INFO - Using buildbot properties: 09:07:46 INFO - { 09:07:46 INFO - "project": "", 09:07:46 INFO - "product": "firefox", 09:07:46 INFO - "script_repo_revision": "production", 09:07:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:07:46 INFO - "repository": "", 09:07:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:07:46 INFO - "buildid": "20160630030201", 09:07:46 INFO - "stage_platform": "macosx64", 09:07:46 INFO - "basedir": "/builds/slave/test", 09:07:46 INFO - "nightly_build": "True", 09:07:46 INFO - "buildnumber": 3, 09:07:46 INFO - "slavename": "t-yosemite-r7-0354", 09:07:46 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:07:46 INFO - "platform": "yosemite_r7", 09:07:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:07:46 INFO - "branch": "mozilla-central", 09:07:46 INFO - "repo_path": "mozilla-central", 09:07:46 INFO - "revision": "d700dc054751333e0735f975fce3d3adf153c62a", 09:07:46 INFO - "pgo_build": "False", 09:07:46 INFO - "builduid": "ee9e9e5bb18c43e1aef8c9c84c97401e", 09:07:46 INFO - "slavebuilddir": "test" 09:07:46 INFO - } 09:07:46 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg. 09:07:46 INFO - Running post-action listener: _resource_record_post_action 09:07:46 INFO - ##### 09:07:46 INFO - ##### Finished read-buildbot-config step (success) 09:07:46 INFO - ##### 09:07:46 INFO - ##### 09:07:46 INFO - ##### Running download-and-extract step. 09:07:46 INFO - ##### 09:07:46 INFO - Running pre-action listener: _resource_record_pre_action 09:07:46 INFO - Running main action method: download_and_extract 09:07:46 INFO - mkdir: /builds/slave/test/build/tests 09:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:46 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.test_packages.json 09:07:46 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:07:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/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 09:07:46 INFO - Downloaded 1199 bytes. 09:07:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:07:46 INFO - Using the following test package requirements: 09:07:46 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:07:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:07:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:07:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'jsshell-mac.zip'], 09:07:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:07:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:07:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:07:46 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:07:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:07:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:07:46 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:07:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:46 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.common.tests.zip 09:07:46 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:07:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/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 09:07:47 INFO - Downloaded 18788625 bytes. 09:07:47 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'] 09:07:47 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 09:07:50 INFO - Return code: 0 09:07:50 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 09:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.common.tests.zip 09:07:50 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:07:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/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 09:07:50 INFO - Downloaded 18788625 bytes. 09:07:50 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'] 09:07:50 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 09:07:54 INFO - Return code: 0 09:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:54 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.talos.tests.zip 09:07:54 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:07:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/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 09:07:59 INFO - Downloaded 14405245 bytes. 09:07:59 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'] 09:07:59 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 09:07:59 INFO - Return code: 0 09:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg 09:07:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:07:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:08:02 INFO - Downloaded 112596742 bytes. 09:08:02 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg 09:08:02 INFO - mkdir: /builds/slave/test/properties 09:08:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:08:02 INFO - Writing to file /builds/slave/test/properties/build_url 09:08:02 INFO - Contents: 09:08:02 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg 09:08:02 INFO - Running post-action listener: _resource_record_post_action 09:08:02 INFO - Running post-action listener: set_extra_try_arguments 09:08:02 INFO - ##### 09:08:02 INFO - ##### Finished download-and-extract step (success) 09:08:02 INFO - ##### 09:08:02 INFO - ##### 09:08:02 INFO - ##### Running populate-webroot step. 09:08:02 INFO - ##### 09:08:02 INFO - Running pre-action listener: _resource_record_pre_action 09:08:02 INFO - Running main action method: populate_webroot 09:08:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:08:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:08:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:08:02 INFO - '--tppagecycles', 09:08:02 INFO - '7'], 09:08:02 INFO - 'tests': ['tsvgm']}}, 09:08:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:08:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:08:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:08:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:08:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['damp', 'tps']}, 09:08:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'tests': ['damp', 'tps']}, 09:08:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['dromaeo_dom']}, 09:08:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:08:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['basic_compositor_video']}, 09:08:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:08:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['a11yr', 09:08:02 INFO - 'ts_paint', 09:08:02 INFO - 'tpaint', 09:08:02 INFO - 'sessionrestore', 09:08:02 INFO - 'sessionrestore_no_auto_restore', 09:08:02 INFO - 'tabpaint']}, 09:08:02 INFO - 'other-e10s': {'tests': ['a11yr', 09:08:02 INFO - 'ts_paint', 09:08:02 INFO - 'tpaint', 09:08:02 INFO - 'sessionrestore', 09:08:02 INFO - 'sessionrestore_no_auto_restore', 09:08:02 INFO - 'tabpaint']}, 09:08:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:08:02 INFO - 'ts_paint', 09:08:02 INFO - 'tpaint', 09:08:02 INFO - 'sessionrestore', 09:08:02 INFO - 'sessionrestore_no_auto_restore', 09:08:02 INFO - 'tabpaint']}, 09:08:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:08:02 INFO - 'ts_paint', 09:08:02 INFO - 'tpaint', 09:08:02 INFO - 'sessionrestore', 09:08:02 INFO - 'sessionrestore_no_auto_restore', 09:08:02 INFO - 'tabpaint']}, 09:08:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['a11yr', 09:08:02 INFO - 'ts_paint', 09:08:02 INFO - 'tpaint', 09:08:02 INFO - 'sessionrestore', 09:08:02 INFO - 'sessionrestore_no_auto_restore', 09:08:02 INFO - 'tabpaint']}, 09:08:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['a11yr', 09:08:02 INFO - 'ts_paint', 09:08:02 INFO - 'tpaint', 09:08:02 INFO - 'sessionrestore', 09:08:02 INFO - 'sessionrestore_no_auto_restore', 09:08:02 INFO - 'tabpaint']}, 09:08:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['tsvgx', 09:08:02 INFO - 'tsvgr_opacity', 09:08:02 INFO - 'tart', 09:08:02 INFO - 'tscrollx', 09:08:02 INFO - 'cart']}, 09:08:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:08:02 INFO - 'tsvgr_opacity', 09:08:02 INFO - 'tart', 09:08:02 INFO - 'tscrollx', 09:08:02 INFO - 'cart']}, 09:08:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'talos_options': ['--disable-e10s'], 09:08:02 INFO - 'tests': ['tp5o']}, 09:08:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'tests': ['tp5o']}, 09:08:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'talos_options': ['--disable-e10s', 09:08:02 INFO - '--xperf_path', 09:08:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:02 INFO - 'tests': ['tp5n']}, 09:08:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:02 INFO - 'talos_options': ['--xperf_path', 09:08:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:02 INFO - 'tests': ['tp5n']}}, 09:08:02 INFO - 'talos.zip': {'path': '', 09:08:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:08:02 INFO - Running post-action listener: _resource_record_post_action 09:08:02 INFO - ##### 09:08:02 INFO - ##### Finished populate-webroot step (success) 09:08:02 INFO - ##### 09:08:02 INFO - ##### 09:08:02 INFO - ##### Running create-virtualenv step. 09:08:02 INFO - ##### 09:08:02 INFO - Running pre-action listener: _resource_record_pre_action 09:08:02 INFO - Running main action method: create_virtualenv 09:08:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:08:02 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 09:08:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:08:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:08:02 INFO - Using real prefix '/tools/python27' 09:08:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:08:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:08:06 INFO - Installing pip.................done. 09:08:06 INFO - Return code: 0 09:08:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:08:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:08:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:06 INFO - 'HOME': '/Users/cltbld', 09:08:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:06 INFO - 'LOGNAME': 'cltbld', 09:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:06 INFO - 'MOZ_NO_REMOTE': '1', 09:08:06 INFO - 'NO_EM_RESTART': '1', 09:08:06 INFO - 'PAGER': '/bin/cat', 09:08:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:06 INFO - 'PWD': '/builds/slave/test', 09:08:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:06 INFO - 'SHELL': '/bin/bash', 09:08:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:06 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:06 INFO - 'USER': 'cltbld', 09:08:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:06 INFO - 'XPC_FLAGS': '0x0', 09:08:06 INFO - 'XPC_SERVICE_NAME': '0', 09:08:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:06 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 09:08:06 INFO - Cleaning up... 09:08:06 INFO - Return code: 0 09:08:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:08:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:08:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:06 INFO - 'HOME': '/Users/cltbld', 09:08:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:06 INFO - 'LOGNAME': 'cltbld', 09:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:06 INFO - 'MOZ_NO_REMOTE': '1', 09:08:06 INFO - 'NO_EM_RESTART': '1', 09:08:06 INFO - 'PAGER': '/bin/cat', 09:08:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:06 INFO - 'PWD': '/builds/slave/test', 09:08:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:06 INFO - 'SHELL': '/bin/bash', 09:08:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:06 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:06 INFO - 'USER': 'cltbld', 09:08:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:06 INFO - 'XPC_FLAGS': '0x0', 09:08:06 INFO - 'XPC_SERVICE_NAME': '0', 09:08:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:06 INFO - Downloading/unpacking psutil>=3.1.1 09:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:08:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:10 INFO - Installing collected packages: psutil 09:08:10 INFO - Running setup.py install for psutil 09:08:10 INFO - building 'psutil._psutil_osx' extension 09:08:10 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 09:08:10 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 09:08:10 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 09:08:10 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 09:08:10 INFO - building 'psutil._psutil_posix' extension 09:08:10 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 09:08:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:08:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:08:10 INFO - ^ 09:08:11 INFO - 1 warning generated. 09:08:11 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 09:08:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:11 INFO - Successfully installed psutil 09:08:11 INFO - Cleaning up... 09:08:11 INFO - Return code: 0 09:08:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:11 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 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:11 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 09:08:11 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 09:08:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:11 INFO - 'HOME': '/Users/cltbld', 09:08:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:11 INFO - 'LOGNAME': 'cltbld', 09:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:11 INFO - 'MOZ_NO_REMOTE': '1', 09:08:11 INFO - 'NO_EM_RESTART': '1', 09:08:11 INFO - 'PAGER': '/bin/cat', 09:08:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:11 INFO - 'PWD': '/builds/slave/test', 09:08:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:11 INFO - 'SHELL': '/bin/bash', 09:08:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:11 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:11 INFO - 'USER': 'cltbld', 09:08:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:11 INFO - 'XPC_FLAGS': '0x0', 09:08:11 INFO - 'XPC_SERVICE_NAME': '0', 09:08:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:08:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:08:13 INFO - Installing collected packages: mozsystemmonitor 09:08:13 INFO - Running setup.py install for mozsystemmonitor 09:08:14 INFO - Successfully installed mozsystemmonitor 09:08:14 INFO - Cleaning up... 09:08:14 INFO - Return code: 0 09:08:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:14 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 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:14 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 09:08:14 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 09:08:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:14 INFO - 'HOME': '/Users/cltbld', 09:08:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:14 INFO - 'LOGNAME': 'cltbld', 09:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:14 INFO - 'MOZ_NO_REMOTE': '1', 09:08:14 INFO - 'NO_EM_RESTART': '1', 09:08:14 INFO - 'PAGER': '/bin/cat', 09:08:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:14 INFO - 'PWD': '/builds/slave/test', 09:08:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:14 INFO - 'SHELL': '/bin/bash', 09:08:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:14 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:14 INFO - 'USER': 'cltbld', 09:08:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:14 INFO - 'XPC_FLAGS': '0x0', 09:08:14 INFO - 'XPC_SERVICE_NAME': '0', 09:08:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:14 INFO - Downloading/unpacking jsonschema==2.5.1 09:08:14 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 09:08:14 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 09:08:14 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 09:08:14 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 09:08:14 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 09:08:14 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 09:08:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:08:17 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 09:08:17 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 09:08:17 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 09:08:17 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 09:08:17 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 09:08:17 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 09:08:17 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:17 INFO - warning: no files found matching '*.txt' 09:08:17 INFO - no previously-included directories found matching 'build' 09:08:17 INFO - no previously-included directories found matching 'dist' 09:08:17 INFO - no previously-included directories found matching '.git*' 09:08:17 INFO - Installing collected packages: jsonschema, functools32 09:08:17 INFO - Running setup.py install for functools32 09:08:17 INFO - warning: no files found matching '*.txt' 09:08:17 INFO - no previously-included directories found matching 'build' 09:08:17 INFO - no previously-included directories found matching 'dist' 09:08:17 INFO - no previously-included directories found matching '.git*' 09:08:17 INFO - Successfully installed jsonschema functools32 09:08:17 INFO - Cleaning up... 09:08:17 INFO - Return code: 0 09:08:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:17 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 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:17 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 09:08:17 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 09:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:17 INFO - 'HOME': '/Users/cltbld', 09:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:17 INFO - 'LOGNAME': 'cltbld', 09:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:17 INFO - 'MOZ_NO_REMOTE': '1', 09:08:17 INFO - 'NO_EM_RESTART': '1', 09:08:17 INFO - 'PAGER': '/bin/cat', 09:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:17 INFO - 'PWD': '/builds/slave/test', 09:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:17 INFO - 'SHELL': '/bin/bash', 09:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:17 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:17 INFO - 'USER': 'cltbld', 09:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:17 INFO - 'XPC_FLAGS': '0x0', 09:08:17 INFO - 'XPC_SERVICE_NAME': '0', 09:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:08:18 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 09:08:18 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 09:08:18 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 09:08:18 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 09:08:18 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 09:08:18 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 09:08:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:08:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:08:20 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 09:08:20 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 09:08:20 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 09:08:20 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 09:08:20 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 09:08:20 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 09:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:08:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:21 INFO - Downloading docopt-0.6.1.tar.gz 09:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:08:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:08:21 INFO - Running setup.py install for blobuploader 09:08:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:08:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:08:21 INFO - Running setup.py install for requests 09:08:22 INFO - Running setup.py install for docopt 09:08:22 INFO - Successfully installed blobuploader requests docopt 09:08:22 INFO - Cleaning up... 09:08:22 INFO - Return code: 0 09:08:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:22 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')]} 09:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:22 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')]} 09:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:22 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 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:22 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 09:08:22 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 09:08:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:22 INFO - 'HOME': '/Users/cltbld', 09:08:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:22 INFO - 'LOGNAME': 'cltbld', 09:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:22 INFO - 'MOZ_NO_REMOTE': '1', 09:08:22 INFO - 'NO_EM_RESTART': '1', 09:08:22 INFO - 'PAGER': '/bin/cat', 09:08:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:22 INFO - 'PWD': '/builds/slave/test', 09:08:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:22 INFO - 'SHELL': '/bin/bash', 09:08:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:22 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:22 INFO - 'USER': 'cltbld', 09:08:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:22 INFO - 'XPC_FLAGS': '0x0', 09:08:22 INFO - 'XPC_SERVICE_NAME': '0', 09:08:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:22 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-0rDlcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-NXsiQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-NTR6YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-QEdYgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-PA1zlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-0M1pU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-OFEiHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-SxUPRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Lp2qaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-zaZAJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:23 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-HpI_9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-U9jCIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-tlJCwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-vn2J0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-H0ikrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-3ieGae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Yhyyj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:08:24 INFO - Running setup.py install for manifestparser 09:08:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:08:24 INFO - Running setup.py install for mozcrash 09:08:24 INFO - Running setup.py install for mozdebug 09:08:25 INFO - Running setup.py install for mozdevice 09:08:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Running setup.py install for mozfile 09:08:25 INFO - Running setup.py install for mozhttpd 09:08:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Running setup.py install for mozinfo 09:08:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Running setup.py install for mozInstall 09:08:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:08:25 INFO - Running setup.py install for mozleak 09:08:25 INFO - Running setup.py install for mozlog 09:08:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:08:26 INFO - Running setup.py install for moznetwork 09:08:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:08:26 INFO - Running setup.py install for mozprocess 09:08:26 INFO - Running setup.py install for mozprofile 09:08:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:08:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:08:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:08:26 INFO - Running setup.py install for mozrunner 09:08:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:08:26 INFO - Running setup.py install for mozscreenshot 09:08:26 INFO - Running setup.py install for moztest 09:08:27 INFO - Running setup.py install for mozversion 09:08:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:08:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:08:27 INFO - Cleaning up... 09:08:27 INFO - Return code: 0 09:08:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:27 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')]} 09:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:27 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')]} 09:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:27 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 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:27 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 09:08:27 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 09:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:27 INFO - 'HOME': '/Users/cltbld', 09:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:27 INFO - 'LOGNAME': 'cltbld', 09:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:27 INFO - 'MOZ_NO_REMOTE': '1', 09:08:27 INFO - 'NO_EM_RESTART': '1', 09:08:27 INFO - 'PAGER': '/bin/cat', 09:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:27 INFO - 'PWD': '/builds/slave/test', 09:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:27 INFO - 'SHELL': '/bin/bash', 09:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:27 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:27 INFO - 'USER': 'cltbld', 09:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:27 INFO - 'XPC_FLAGS': '0x0', 09:08:27 INFO - 'XPC_SERVICE_NAME': '0', 09:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:27 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-OI7EE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:27 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)) 09:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:27 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-HOmLLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-mk0csi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-efuwm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-sR_QW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-or5lsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-mXVv4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-SOMgai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-qQ7sm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-rNdM9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:28 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)) 09:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:28 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Ehp6Qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:29 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)) 09:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:29 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-_cJTtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:29 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Cv1k6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:29 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)) 09:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:29 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-2b_XZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:29 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-7wP_KN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:29 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)) 09:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:29 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-jGL_zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:29 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)) 09:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:29 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-T_XBSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:29 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)) 09:08:29 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)) 09:08:29 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)) 09:08:29 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)) 09:08:29 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)) 09:08:29 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)) 09:08:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:33 INFO - Downloading blessings-1.6.tar.gz 09:08:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:33 INFO - Installing collected packages: blessings 09:08:33 INFO - Running setup.py install for blessings 09:08:33 INFO - Successfully installed blessings 09:08:33 INFO - Cleaning up... 09:08:33 INFO - Return code: 0 09:08:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:34 INFO - Reading from file tmpfile_stdout 09:08:34 INFO - Current package versions: 09:08:34 INFO - blessings == 1.6 09:08:34 INFO - blobuploader == 1.2.4 09:08:34 INFO - docopt == 0.6.1 09:08:34 INFO - functools32 == 3.2.3-2 09:08:34 INFO - jsonschema == 2.5.1 09:08:34 INFO - manifestparser == 1.1 09:08:34 INFO - mozInstall == 1.12 09:08:34 INFO - mozcrash == 0.17 09:08:34 INFO - mozdebug == 0.1 09:08:34 INFO - mozdevice == 0.48 09:08:34 INFO - mozfile == 1.2 09:08:34 INFO - mozhttpd == 0.7 09:08:34 INFO - mozinfo == 0.9 09:08:34 INFO - mozleak == 0.1 09:08:34 INFO - mozlog == 3.2 09:08:34 INFO - moznetwork == 0.27 09:08:34 INFO - mozprocess == 0.22 09:08:34 INFO - mozprofile == 0.28 09:08:34 INFO - mozrunner == 6.11 09:08:34 INFO - mozscreenshot == 0.1 09:08:34 INFO - mozsystemmonitor == 0.3 09:08:34 INFO - moztest == 0.7 09:08:34 INFO - mozversion == 1.4 09:08:34 INFO - psutil == 3.1.1 09:08:34 INFO - requests == 1.2.3 09:08:34 INFO - wsgiref == 0.1.2 09:08:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:34 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 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:34 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 09:08:34 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 09:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:34 INFO - 'HOME': '/Users/cltbld', 09:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:34 INFO - 'LOGNAME': 'cltbld', 09:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:34 INFO - 'MOZ_NO_REMOTE': '1', 09:08:34 INFO - 'NO_EM_RESTART': '1', 09:08:34 INFO - 'PAGER': '/bin/cat', 09:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:34 INFO - 'PWD': '/builds/slave/test', 09:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:34 INFO - 'SHELL': '/bin/bash', 09:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:34 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:34 INFO - 'USER': 'cltbld', 09:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:34 INFO - 'XPC_FLAGS': '0x0', 09:08:34 INFO - 'XPC_SERVICE_NAME': '0', 09:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 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)) 09:08:34 INFO - Cleaning up... 09:08:34 INFO - Return code: 0 09:08:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b1ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bb1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9680cb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, '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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 09:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:34 INFO - 'HOME': '/Users/cltbld', 09:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:34 INFO - 'LOGNAME': 'cltbld', 09:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:34 INFO - 'MOZ_NO_REMOTE': '1', 09:08:34 INFO - 'NO_EM_RESTART': '1', 09:08:34 INFO - 'PAGER': '/bin/cat', 09:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:34 INFO - 'PWD': '/builds/slave/test', 09:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:34 INFO - 'SHELL': '/bin/bash', 09:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:08:34 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:34 INFO - 'USER': 'cltbld', 09:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:34 INFO - 'XPC_FLAGS': '0x0', 09:08:34 INFO - 'XPC_SERVICE_NAME': '0', 09:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:08:34 INFO - Cleaning up... 09:08:34 INFO - Return code: 0 09:08:34 INFO - Running post-action listener: _resource_record_post_action 09:08:34 INFO - Running post-action listener: _start_resource_monitoring 09:08:34 INFO - Starting resource monitoring. 09:08:34 INFO - ##### 09:08:34 INFO - ##### Finished create-virtualenv step (success) 09:08:34 INFO - ##### 09:08:34 INFO - ##### 09:08:34 INFO - ##### Running install step. 09:08:34 INFO - ##### 09:08:34 INFO - Running pre-action listener: _resource_record_pre_action 09:08:34 INFO - Running main action method: install 09:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:34 INFO - Reading from file tmpfile_stdout 09:08:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:34 INFO - Reading from file tmpfile_stdout 09:08:34 INFO - Output received: 09:08:34 INFO - Usage: mozinstall [options] installer 09:08:34 INFO - Options: 09:08:34 INFO - -h, --help show this help message and exit 09:08:34 INFO - -d DEST, --destination=DEST 09:08:34 INFO - Directory to install application into. [default: 09:08:34 INFO - "/builds/slave/test"] 09:08:34 INFO - --app=APP Application being installed. [default: firefox] 09:08:34 INFO - mkdir: /builds/slave/test/build/application 09:08:34 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'] 09:08:34 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 09:09:09 INFO - Reading from file tmpfile_stdout 09:09:09 INFO - Output received: 09:09:09 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 09:09:09 INFO - Running post-action listener: _resource_record_post_action 09:09:09 INFO - ##### 09:09:09 INFO - ##### Finished install step (success) 09:09:09 INFO - ##### 09:09:09 INFO - ##### 09:09:09 INFO - ##### Running run-tests step. 09:09:09 INFO - ##### 09:09:09 INFO - Running pre-action listener: _resource_record_pre_action 09:09:09 INFO - Running main action method: run_tests 09:09:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:09 INFO - 2016-07-04 09:09:09.670 screenresolution[1640:7590] starting screenresolution argv=screenresolution get 09:09:09 INFO - 2016-07-04 09:09:09.673 screenresolution[1640:7590] Display 0: 1600x1200x32@60 09:09:09 INFO - 2016-07-04 09:09:09.679 screenresolution[1641:7592] starting screenresolution argv=screenresolution list 09:09:09 INFO - Available Modes on Display 0 09:09:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:09 INFO - Intel Iris: 09:09:09 INFO - Chipset Model: Intel Iris 09:09:09 INFO - Type: GPU 09:09:09 INFO - Bus: Built-In 09:09:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:09 INFO - Vendor: Intel (0x8086) 09:09:09 INFO - Device ID: 0x0a2e 09:09:09 INFO - Revision ID: 0x0009 09:09:09 INFO - Displays: 09:09:09 INFO - Display: 09:09:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:09 INFO - Display Serial Number: 1600x1200 60 09:09:09 INFO - Main Display: Yes 09:09:09 INFO - Mirror: Off 09:09:09 INFO - Online: Yes 09:09:09 INFO - Rotation: Supported 09:09:09 INFO - Return code: 0 09:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:09:09 INFO - Python 2.7.3 09:09:09 INFO - Return code: 0 09:09:09 INFO - grabbing minidump binary from tooltool 09:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:09 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 0x108a80d30>, '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 0x1087e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a78b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:09:09 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 09:09:09 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 09:09:09 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 09:09:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:09:10 INFO - Return code: 0 09:09:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:09:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:09:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:09:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:09:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:09:10 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/1467280921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 09:09:10 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/1467280921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0354 --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 09:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vr1BjKEUhz/Render', 09:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:10 INFO - 'HOME': '/Users/cltbld', 09:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:10 INFO - 'LOGNAME': 'cltbld', 09:09:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:09:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:10 INFO - 'MOZ_NO_REMOTE': '1', 09:09:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:09:10 INFO - 'NO_EM_RESTART': '1', 09:09:10 INFO - 'PAGER': '/bin/cat', 09:09:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:10 INFO - 'PWD': '/builds/slave/test', 09:09:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:10 INFO - 'SHELL': '/bin/bash', 09:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzPKQoOMd2/Listeners', 09:09:10 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 09:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:10 INFO - 'USER': 'cltbld', 09:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:10 INFO - 'XPC_FLAGS': '0x0', 09:09:10 INFO - 'XPC_SERVICE_NAME': '0', 09:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:10 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/1467280921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 09:09:10 INFO - mozversion application_buildid: 20160630030201 09:09:10 INFO - mozversion application_changeset: d700dc054751333e0735f975fce3d3adf153c62a 09:09:10 INFO - mozversion application_display_name: Nightly 09:09:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:09:10 INFO - mozversion application_name: Firefox 09:09:10 INFO - mozversion application_remotingname: firefox 09:09:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:09:10 INFO - mozversion application_vendor: Mozilla 09:09:10 INFO - mozversion application_version: 50.0a1 09:09:10 INFO - mozversion platform_buildid: 20160630030201 09:09:10 INFO - mozversion platform_changeset: d700dc054751333e0735f975fce3d3adf153c62a 09:09:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:09:10 INFO - mozversion platform_version: 50.0a1 09:09:10 INFO - using testdate: 1467648550 09:09:10 INFO - actual date: 1467648550 09:09:10 INFO - starting webserver on 'localhost:49265' 09:09:10 INFO - SUITE-START | Running 2 tests 09:09:10 INFO - TEST-START | dromaeo_css 09:09:10 INFO - operating with platform_type : mac_ 09:09:10 INFO - Initialising browser for dromaeo_css test... 09:09:10 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmp8WNQU7/profile http://localhost:49265/getInfo.html) 09:09:12 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:09:12 INFO - PROCESS | 1651 | colorDepth:24 09:09:12 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:09:12 INFO - PROCESS | 1651 | __metrics 09:09:13 INFO - PROCESS | 1651 | 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" 09:09:13 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:09:13 INFO - TEST-INFO | 1651: exit 0 09:09:13 INFO - Browser initialized. 09:09:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:09:13 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmp8WNQU7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:25 INFO - PROCESS | 1657 | RSS: Main: 243654656 09:09:25 INFO - PROCESS | 1657 | 09:10:17 INFO - PROCESS | 1657 | 1467648617416 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] 09:12:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 09:12:29 INFO - PROCESS | 1657 | RSS: Main: 349491200 09:12:29 INFO - PROCESS | 1657 | 09:15:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 09:15:32 INFO - PROCESS | 1657 | RSS: Main: 378138624 09:15:32 INFO - PROCESS | 1657 | 09:18:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 09:18:35 INFO - PROCESS | 1657 | RSS: Main: 368537600 09:18:35 INFO - PROCESS | 1657 | 09:21:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 09:21:38 INFO - PROCESS | 1657 | RSS: Main: 358256640 09:21:38 INFO - PROCESS | 1657 | 09:24:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 09:24:42 INFO - PROCESS | 1657 | RSS: Main: 339484672 09:24:42 INFO - PROCESS | 1657 | 09:27:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 09:27:46 INFO - PROCESS | 1657 | RSS: Main: 380874752 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | __start_tp_report 09:27:46 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:27:46 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:27:46 INFO - PROCESS | 1657 | |i|pagename|runs| 09:27:46 INFO - PROCESS | 1657 | |0;dojo.html;3337.0823023668495;3345.9665403345975;3371.359441706076;3382.6786455286738;3414.931701365973;5596.328440587135;5631.60578759487;5696.572757043223;5822;5824.534037277018;5685.317761868581;5703.600747947635;5816.156657284691;5834.854128646786;5836.182934389179;5138.3577052868395;5163.63854530183;5212.452692467293;5224.947750522503;5248.8425347239645;5244.082285600024;5251.343582052244;5274.525292723654;5294.867628309285;5314.282571852789;5146.845594632167;5160.716160611165;5168.534831865112;5219.42586315505;5227.477252274742;5189.325387699575;5191.169412893919;5223.791048358061;5314.256004159421;5339.866503337409;5180.6891586504935;5223.981323641891;5248.134057880445;5265.76304066318;5324.840254792362;5644.125160600113;5810.82567522975;5847.8245652630485;5890.911636325459;5891.646501209898;6134.355892631254;6151.661658608775;6175.87648247037;6226.6264024158245;6263.530235232331;6294.842628934268;6323.241211054703;6358.237011558596;6375.93624063758;6392.360763923617;5676.744546495335;5764.221830052889;5926.081457374114;5938.524918006549;5939.13882487037;6263.937360626426;6272.560920735505;6290.779822706183;6305.747770089145;6309.179806625201;6209.627422354673;6240.500759939194;6243.75024999004;6250.124982502453;6252.249730032425;6075.513958883279;6107.877842443127;6217.502599792097;6311.842203944846;6324.9051264231075;5220.634555581073;6095.359987201368;6146.955017647011;6157.538184636171;6167.809500062336;6265.592736472206;6281.623102613858;6300.936990630035;6303.747850086037;6331.905021424683;4914.803407863646;4963.677360971526;4984.77568509418;5043.798248070036;5071.594272458195;3808.676262517717;4439;4474.015716542355;4510.391097201901;4541.091781643684;5793.8841223175305;5805.94194058054;5825.533957283407;5826.417358264224;5897.439743224387;6135.5398345124295;6216.347283535164;6217.440430361199;6231.003039513656;6245.469135123565;5762.135679648002;5806.477417032487;5845.357010728816;5879;5902.321233058254;5499.587530935196;5505.724713764376;5506.972465137648;5522.779088836482;5535.6955367454375;6206.410391012847;6356.459700925473;6370.8088757337355;6375.77684781049;6431.678416079271;5381.596380271417;6118.771466972081;6199.783007594712;6243.282022567463;6249.96875015622;6238.220222472191;6280.874382512233;6300.905486417799;6337.397947195009;6374.681265936777;6160.907586386297;6238.469730072995;6246;6246.6876656167915;6252.812415627538;6048.758049678139;6187.628742275478;6231.9688401559515;6260.718267678057;6277.623342599459;6093.664848433379;6198.81403557894;6215.96892015537;6224.657643829632;6237.844053898689;6163.137160797582;6191.226096737908;6192.256929168529;6201.627902325875;6223.3465486123305;5873.853153671193;5931.08068249422;5947.167396564571;6012.759489620366;6066.090086487062;6157.384261573807;6183.84540386494;6193.256809182927;6207.379262073757;6234.719437625227;5867.677277749594;6070.6357618543025;6072.210612620331;6072.99795533732;6086.055570499105 09:27:46 INFO - PROCESS | 1657 | |1;ext.html;451.71767645221735;494.37708487305986;504.29902435614525;524.4807640435968;552.9391766905641;29982.70017299829;39281.410778838304;40029.39955900657;40117.39823902636;40133;42275.36586951198;42535.78732106335;42585.36121958165;42668;42732.145357092915;8617.482951022926;9461.81076378472;9618.326717129803;9620.230381569492;9624.663136790214;8634.611442485071;11539.711507212303;11794.233374830652;11805.94097029518;12101.757964840697;11813;11874.7625047499;11877.04983601314;11878.881211187907;11891.881081189207;7353.227911069314;11239.606613768561;11489.368084755335;11616.419179041057;11838;11532.59635912739;11594.362310072942;11613.419329033515;11616.36110013949;11623.012043976272;38877.02807429837;38986.805065974775;39172.80413597914;39241;39295.60704392948;3414.163529935182;3462.4633181856852;3482.2687235680537;3488.8081155536433;3603.045193023851;30449.391012179854;30660.846695766602;31195.532067019012;31521.89673361444;31842.726290948332;3250.089974807058;3262.706356427933;3299.4390953537954;3323.567936168307;3412.4198886189406;502909.48545257404;504929;505348;505531;506406.4679676615;32737.67262327346;33164.66835331664;60343.39656603378;60417.79164416775;60717.39282607206;34854.47718284177;35009.1247718804;35043.29913401718;35101.64898350984;35121.47317790235;14377.424903003763;14714.190719510316;14722;14731;14750.55748327552;11310.09519238459;11452.484638191301;11805.23265987708;11913.821292680617;12459.003279737599;6490.935090649127;7744.612769361509;7765.339946104531;7799.064112306446;7812.257835505618;3164.626530102423;3217.549543063973;3224.1617179533023;3226.709596136312;3238.757093217971;903.7785742493131;948.2840455456087;954.6754103604808;972.2076507646245;999.7500624843789;29668.554971675443;29783;29995.850020750193;30512.389752204832;30516.237094072825;889.9955500222458;978.4080631218153;1031.8091153136693;1106.2422240765102;1124.6457365929707;12501.562445314552;12753.91591714696;15390;15570.149134380836;15838;618130;620148;621932;622439;624353;57580;58314.41685583175;58378.7081064592;58383.54041149005;58701;1239.6962744127566;1276.1194775604804;1347.4879545772935;1375.4635692080096;1392.9930350348386;29578.112656620335;30367.392652147275;30538.931137410083;31249.84375078201;31376.529352060195;56068;56296;56926.715366422904;57198.7140064297;57403;630736;637094;638162;638777.0305742393;639624;35527.46708799422;37316.25367492689;42942.57057429511;43047.56952430436;43457.565424345354;36820.63179368172;46009.00572955985;46762;46818.59544213679;47183.764081179375;38416.423753644274;38884.61115388923;39147.41278880762;39461.8119528731;39956;38905.41641875431;39095.609043910335;39631.80184099061;39866;40724.18551628892;35816.64183358238;38497.6150238494;38588;38601.227975440896;38815.029624259616;33551;34868.47697284492;35168.824155879054;35285;35353.292934140656;25046.74953250444;25543.978240870158;25583.360415989006;26080.608790868533;26172 09:27:46 INFO - PROCESS | 1657 | |2;jquery.html;361.50473850824375;381.3345711733026;387.76927728001846;390.3442217075314;410.57915636472615;15529.611759706013;16186.676266474678;18145.909270453663;21701.67447488285;22090;20395;20664.586708265826;21175;21623.135074596998;21693.45766355842;5383.650062745909;5974.492168165701;6206.6586337751405;6219.1915051043525;6264.185655864754;4259.382389553512;4518.006038671487;6103.908441373383;6581.473482121419;6886.655667216644;6578;6841.657917104149;6884.793456196322;6886.035954966309;6893.310668933092;6785.491088168383;6816.693248803816;6818.318168183142;6825.453963682893;6853.314668533157;3799.088218827489;4034.374671925856;5890.617109887887;5921.61509501881;5950.34546199922;26762.73237267622;27099.593506097423;27634.585481217797;27798;27973.881044758185;2199.0763879170627;2251.876146811941;2254.413852398372;2256.007356763019;2971.9746687392817;15249.466268680653;15596.22018899062;16292.755608665879;16356.427525036566;16868.24092915822;1726.8359505846925;1745.9825401745948;1820.071763400662;1883.5761953560477;1896.7913529511743;119749.21630094103;123762;124354;124849.75150248472;127336.36331818374;9400.15398614128;10940.905909408995;10967.396793176293;11246.212765106528;11544.59593914209;14088.788668169986;14210.85789142116;14252.429902803771;14260.714785704227;14272;8625;8831.293496520262;8862.379633425599;8917.687880924137;8939.865902011476;8868.556572171366;8873.64505419776;8906.55467226636;9034.728958131267;9040.005599384063;6861.862762744717;7019.473539484559;7022.54353467023;7025.648717564101;7034.155901291878;1713.6744018636418;1719.2270936764141;1785.7678501794944;1878.6235412203573;1931.8988176739406;927.3693888156118;937.7514958535993;964.609333220047;966.9613215471443;1042.61944390297;22356.217532386614;22390.664140037912;23174.65238021431;23413.882930585238;23465.88267058654;937.3438592984937;1016.1261315268863;1021.1881554164457;1048.2557384257263;1049.0872940541778;10021.599136034623;11342.773144537228;12235.32705701195;12375.344711932516;12395.62813115608;568535;581868;582482;594016;594259;72368.63815680993;103433.48283258536;119078;122871;131677;1002.4837208837308;1012.336919317848;1043.186314674555;1043.5929987304958;1045.153425725165;20585.382438526867;20648.793512065287;20734.481637959172;20767.480812979797;20855.37433876986;50069.74965125151;52039.479605203465;53885.730571346896;54032.72983635057;54738.726306369805;73799.63100184465;74120.14699632552;76853.23146768461;78741;80451.59774201325;7922.405819563671;8185.386096042702;8254.752357429286;8419.200175983271;8454.788630284293;7875.684972601031;9309.022552631875;12906.41921113571;13525.458981640624;13712.52006179779;11153.88846111561;11400.087992960378;11714;11728.472218750007;11786.76426471483;11708.648740537797;11718.17972741934;11752.58865939688;11792.941035295113;11894.405279736151;11985.398701001435;12031.338276394532;12130.514779408724;12196.390180490762;12236.632901012983;8386.790330241549;8491.532965686947;8544.359173061921;8565.657373705231;8679.479231245892;10831.620893268699;11760.647180584596;11824.645260642195;11947.163698541019;12061.336626485627 09:27:46 INFO - PROCESS | 1657 | |3;mootools.html;1473.580029691538;1534.2635534943227;1688.222873373301;1846.9353572624962;1853.2216469082991;7590.089189297279;7849.68601255949;8141.226583474568;8639.179277968591;8640.654373825048;7708;7797.25926037027;8149.103598604163;8375.246227839492;8384.161583841627;2291.32405940248;2307.711536057997;2336.4626135988733;2338.8012018978407;2343.1372059212968;2055.9074841632164;2303.815694744425;2317.2353123469297;2342.0865862313626;2343.6836027136374;1954.2671498188179;2103.852730308879;2266.614675505168;2342.871142087193;2347.507023525062;2307.3885420363617;2331.7668233176714;2333.5099629077745;2339.4385347516645;2341.3678306857055;2029.482481967089;2112.524681946565;2327.7439481657;2333.0317918063984;2337.2637619149914;7775.339096176832;7855.450118491708;8248.958755206186;8437.156284371575;8457;5154;5278.102722537178;5278.551323137538;5293.470652934714;5293.788248470057;3526.418141006731;3578.141246100943;3604.1350075981577;3604.1710406606626;3613.7109031277696;1055.3878750324793;1058.2063452410691;1064.1912146768511;1070.6520380876168;1081.6268387406333;11015.559377624886;11112.611058612909;11120.165987550885;11124.110071194285;11140.997310242035;4491.371208030879;4494.438195225597;4717.976410117927;4723.6929599575915;4730.385049943554;9621.374610650426;9622.13400793918;9622.711318660451;9639.469829159323;9658.275629327829;5216.66091704038;5232.424433312332;5237.685738855681;5238.71187084714;5239.659422137549;4686.156491831435;4780.043991201774;4785.856424307276;4789.880252993633;4801.375821143229;3276.426625340556;3732.8880133596035;3751.0622344413896;3800.6579407852873;3859.671927886161;1039.6101461951769;1162.8139497680331;1204.9578264760692;1207.945642446092;1237.449335045914;786.5949036246337;821.4455242711146;821.8109834737924;838.455004247232;898.4294972692387;5633.521150702236;5887.676177810262;5955.612885162451;5965.7613695452565;5967.552433567413;924.2975338742604;943.1558754914313;952.5189779161618;953.1183655118987;975.2393133355994;3833.099221682902;4012.2176175646055;4028.5568587455355;4070.1656160487028;4090.1615168890307;9878.357906736039;10297.3821570706;10338.638147664795;10365.585376585002;10382.065614094769;9328.813423731623;9842.409455432697;10335.483225838678;10342.37945723259;10342.689719308433;7583.203763604853;7876.212378762078;7898.4865983712025;7902.881456778153;7905.604719763989;4491.775411229491;5940.376260492586;6013.218281623361;6020.277566692025;6076.6961651916345;9629.614815407585;9676.27427942893;9698.51507424612;9714.659986900424;9725.902740972782;9312.953435232781;9740.659076932274;9941.353812002486;9957.452340121363;9972.3019388645;7321.524100933422;7332.193458719643;7484.887726683995;7487.737929172453;7490.138634056935;7698.2686644768655;8021;8085;8091.271785539329;8110.0673422557165;6074.838312288219;7086.751963681247;7137.85724285501;7257.745978890714;7328.633568321669;7096.183938847099;7134.60759658203;7153.892691609734;7269.818254543467;7275.599842008741;6815.216250131299;6833.009213664091;6849.691763870737;6900.999355093383;6914;4171.791410429406;4932.235503496964;4954.157793175097;5004.549590536869;5019.196928491424;4917.680350777188;4942.827001054946;4952.257161425815;5028.9245661315845;5084.771185296744 09:27:46 INFO - PROCESS | 1657 | |4;prototype.html;293.4161019571052;295.5094543058523;308.90732780165945;311.2731771313982;314.0327790405551;4158.37624356346;5281.366236051669;5329.360476742791;5377.569794416447;5441.483059109388;4708.575083831821;4746.881327966803;5075.9492405075935;5177.378714554259;5181.507756763111;4056.3104272273786;4084.550699423061;4088.7546747195115;4093.1199792044704;4106.529982165066;3665.771966391262;3802.8098595070273;3899.922001559967;4055.047063939972;4058.8985275368204;4047.8583249586272;4064.4309796628495;4072.839240816368;4076.5515793262716;4091.243120022805;4052.4326594276827;4069.0234343757434;4090.2228576570355;4104.42538044674;4105.733127346743;3333.566566376457;3378.9324213515592;3519.771214871025;3565.3760591896576;3572.4641303804638;4491.73049617024;4813;5141.53726164647;5172.586193104543;5179.326687530597;3900.31744444721;4607.216773148572;4628.1669299526075;4634.582887540104;4651.162790697673;4600.95399046012;5428.83713488596;5589.413111623262;5648.858778530528;5689.630174038674;3218.9678103219135;3304.801711897294;3650.740494529383;3802.790846503441;3821.6942644588644;4947.861991741919;5877.382874798127;5884.794032208895;5896.321922978869;5932.792352267693;3585.6772890439984;3665.2303016366327;3877.302085624615;3894.1432884765304;3906.43356713275;3275.4268003099364;3902.297586434469;3923.6861051115843;3941.586133456003;3960.821763020671;4392.692511524227;4616.1229366420275;4618.653600979873;4626.537346265347;4655.324977878188;4666.976665116721;4842.661013729005;5390.326209223847;5641.266635337462;5759.539236861041;1219.7316590350108;1248.263524520519;1282.051282051294;1299.7790375636164;1344.227069435059;777.8371334854429;824.8185399212164;825.9669613215524;875.7679215008028;892.6563273139888;5191.844244672666;5212.192110222922;5213.244079608511;5220;5227.451117632594;5235.607329450307;5647.887042259132;5687.943120568824;5688.232088667977;5690;4396.812860527703;4809.278608208797;4959.404871415453;5179.4979455957355;5189.3253876996505;2515.5220508103403;2520.0847356009563;2523.583608704543;2556.335352808246;2557.974420255811;4040.7171497995446;4089.650415362923;4738.256777146016;4896.755162241873;5042.747862606855;4386.692931494828;4862.82980095695;4891.364122664102;4898.583620392235;5345.530967981209;5525.950069486785;5587.860303492445;6024.608400454044;6056.818295451144;6114.296855861544;5843.590948633555;5869;5875.206847075761;5876.560242740556;5944.900193464223;4826.251930950708;5135.92296115566;5271.472852714851;5563.721813909369;5820.76716931318;4981.775820088033;5957.344692083954;5997.980343341555;6010.699465026643;6019.518438524995;3594.1194407370363;3839.884803455901;3873.3802591585213;3877.9418308724826;3884.0095775577047;5434.945650543444;5638.774449021993;5644.294463192101;5792.275965504312;5842.444967728059;5680.6591604503865;5699.005348128288;5719.685417302088;5767.711614419179;5773.769049238152;5675.4040825714355;5684.687342196219;5706.429357064427;5731.570132240182;5776.306843178845;5192.3769147702515;5488.588355873405;5554.1391084381985;5562.749676264497;5636.520895723909;4702.321190415559;5669.31968163823;5674.404187560247;5677.403872593483;5694.515966142759;4808.807647694053;5340.385855626652;5407.432219617041;5489.752961116839;5558.333000039859 09:27:46 INFO - PROCESS | 1657 | |5;yui.html;768.4697558684508;791.4420333664767;799.3724925933143;814.254956714606;832.3757182113415;15066.09603423794;15252.847471525281;15706.764398534031;15960.201989900503;15964.201789910516;14509.782353264709;14547.781783273233;14759.852401475982;14760.261986900665;14802.333894974725;636.9936300636981;693.3343989769827;710.6944014073946;850.430211758122;855.285836326668;697.6790676288913;698.6890833579059;700.1528150937372;840.932725381968;871.5337294547419;892.8705337726026;900.3382513852313;901.0808974845653;901.4320977783987;902.6073657958809;893.4415990006246;895.1987970766193;899.4872922434215;900.0954041188596;900.9684661036832;534.284059360455;649.9447546958514;667.9632620205887;672.2269390201259;672.3646154384109;14580.635484112981;14624.926875365662;14642.707145857023;14643.267836608233;14654.853451465455;13450.86549134506;13785.310734463237;13826.308684565833;13835.654108647264;14125.688247579325;12487.440074784647;12533.937330313382;12730.490780368775;12862.871371286354;13201.933990330082;4550.795214215368;4571.634269258451;4591.494935557086;4744.572988431023;4756.857294281177;105892;107087.46456267669;113375;114434;114963.42518287354;10818.29681070728;10850.62022829213;10910.345379277269;10991.945040274908;11251.549938002388;21289.36131916045;21428.89285553562;21431.46421339479;21455.249066282606;21460;7886.211378862068;7890.723824666109;7896.802579935547;8013.519188848688;8092.797680057928;4381.824727010948;4389.385486031958;4475.261581839025;4530.29780384041;4599.2871104978785;3395.0833275015607;3534.151803567177;3539.380608393521;3542.734294927891;3547.396942519726;637.4963778614842;673.0476375928007;678.521642242218;684.5721424109931;691.8685449764629;568.8407245971134;608.412881569279;673.5958424945092;695.4401706626113;703.2018658822274;8867.645294188289;8886.289096872235;8964.8655270171;9127.03946450981;9442.716718498456;571.3629303326844;604.7339170764849;618.8762247550508;626.1671976271639;678.4436761855231;4566.150708962876;4569.109023740405;4667.416572928384;4672.182368085571;4699.130660827689;103289;107437.8512429747;116799.41600292113;154042;162525;90241.54879225562;93939.53030234805;100734;108249;138247;115924;119470;122770;123329.38335308267;127178;7556.622168891643;8895.332850036144;8932.776680583038;9132.269418446642;9244.95377523108;57455.13817292827;63680.72638547296;67328;71054.5789084205;71414.28585714284;69769;73556.05775769123;75283;92021.61967570626;93155.06844931735;13255.337233138498;13614.455421783414;13635.522756703467;13684.31578421055;14207;14332.928335358256;14791.778123327944;14982.325795339018;14990.47533336328;15013;11197.888021119685;13672.453101875813;14093;14124.223167725873;14350.067245629001;10048.89951100509;12518;13443.596692099252;13803.309834508034;14220.958934849443;12093.334866582423;12138.817917731065;12223.144379893321;12278.508859645513;12723.491060357483;11116.555337786722;11193.328400296008;11407.486663100346;11462.312261264016;11464.885351146382;11033.117350612092;11044.779104418027;11356;11389.54441822351;11425.428728563373 09:27:46 INFO - PROCESS | 1657 | __end_tp_report 09:27:46 INFO - PROCESS | 1657 | __start_cc_report 09:27:46 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1085 09:27:46 INFO - PROCESS | 1657 | __end_cc_report 09:27:46 INFO - PROCESS | 1657 | __startTimestamp1467649666137__endTimestamp 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:27:46 INFO - PROCESS | 1657 | Number of tests: 6 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5777.68 Median:6048.76 stddev:647.65 (10.7%) stddev-sans-first:620.69 09:27:46 INFO - PROCESS | 1657 | Values: 3337.1 3346.0 3371.4 3382.7 3414.9 5596.3 5631.6 5696.6 5822.0 5824.5 5685.3 5703.6 5816.2 5834.9 5836.2 5138.4 5163.6 5212.5 5224.9 5248.8 5244.1 5251.3 5274.5 5294.9 5314.3 5146.8 5160.7 5168.5 5219.4 5227.5 5189.3 5191.2 5223.8 5314.3 5339.9 5180.7 5224.0 5248.1 5265.8 5324.8 5644.1 5810.8 5847.8 5890.9 5891.6 6134.4 6151.7 6175.9 6226.6 6263.5 6294.8 6323.2 6358.2 6375.9 6392.4 5676.7 5764.2 5926.1 5938.5 5939.1 6263.9 6272.6 6290.8 6305.7 6309.2 6209.6 6240.5 6243.8 6250.1 6252.2 6075.5 6107.9 6217.5 6311.8 6324.9 5220.6 6095.4 6147.0 6157.5 6167.8 6265.6 6281.6 6300.9 6303.7 6331.9 4914.8 4963.7 4984.8 5043.8 5071.6 3808.7 4439.0 4474.0 4510.4 4541.1 5793.9 5805.9 5825.5 5826.4 5897.4 6135.5 6216.3 6217.4 6231.0 6245.5 5762.1 5806.5 5845.4 5879.0 5902.3 5499.6 5505.7 5507.0 5522.8 5535.7 6206.4 6356.5 6370.8 6375.8 6431.7 5381.6 6118.8 6199.8 6243.3 6250.0 6238.2 6280.9 6300.9 6337.4 6374.7 6160.9 6238.5 6246.0 6246.7 6252.8 6048.8 6187.6 6232.0 6260.7 6277.6 6093.7 6198.8 6216.0 6224.7 6237.8 6163.1 6191.2 6192.3 6201.6 6223.3 5873.9 5931.1 5947.2 6012.8 6066.1 6157.4 6183.8 6193.3 6207.4 6234.7 5867.7 6070.6 6072.2 6073.0 6086.1 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:70939.50 Median:20211.84 stddev:158122.47 (782.3%) stddev-sans-first:158477.48 09:27:46 INFO - PROCESS | 1657 | Values: 451.7 494.4 504.3 524.5 552.9 29982.7 39281.4 40029.4 40117.4 40133.0 42275.4 42535.8 42585.4 42668.0 42732.1 8617.5 9461.8 9618.3 9620.2 9624.7 8634.6 11539.7 11794.2 11805.9 12101.8 11813.0 11874.8 11877.0 11878.9 11891.9 7353.2 11239.6 11489.4 11616.4 11838.0 11532.6 11594.4 11613.4 11616.4 11623.0 38877.0 38986.8 39172.8 39241.0 39295.6 3414.2 3462.5 3482.3 3488.8 3603.0 30449.4 30660.8 31195.5 31521.9 31842.7 3250.1 3262.7 3299.4 3323.6 3412.4 502909.5 504929.0 505348.0 505531.0 506406.5 32737.7 33164.7 60343.4 60417.8 60717.4 34854.5 35009.1 35043.3 35101.6 35121.5 14377.4 14714.2 14722.0 14731.0 14750.6 11310.1 11452.5 11805.2 11913.8 12459.0 6490.9 7744.6 7765.3 7799.1 7812.3 3164.6 3217.5 3224.2 3226.7 3238.8 903.8 948.3 954.7 972.2 999.8 29668.6 29783.0 29995.9 30512.4 30516.2 890.0 978.4 1031.8 1106.2 1124.6 12501.6 12753.9 15390.0 15570.1 15838.0 618130.0 620148.0 621932.0 622439.0 624353.0 57580.0 58314.4 58378.7 58383.5 58701.0 1239.7 1276.1 1347.5 1375.5 1393.0 29578.1 30367.4 30538.9 31249.8 31376.5 56068.0 56296.0 56926.7 57198.7 57403.0 630736.0 637094.0 638162.0 638777.0 639624.0 35527.5 37316.3 42942.6 43047.6 43457.6 36820.6 46009.0 46762.0 46818.6 47183.8 38416.4 38884.6 39147.4 39461.8 39956.0 38905.4 39095.6 39631.8 39866.0 40724.2 35816.6 38497.6 38588.0 38601.2 38815.0 33551.0 34868.5 35168.8 35285.0 35353.3 25046.7 25544.0 25583.4 26080.6 26172.0 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:34767.28 Median:2255.21 stddev:97307.87 (4314.8%) stddev-sans-first:97546.55 09:27:46 INFO - PROCESS | 1657 | Values: 361.5 381.3 387.8 390.3 410.6 15529.6 16186.7 18145.9 21701.7 22090.0 20395.0 20664.6 21175.0 21623.1 21693.5 5383.7 5974.5 6206.7 6219.2 6264.2 4259.4 4518.0 6103.9 6581.5 6886.7 6578.0 6841.7 6884.8 6886.0 6893.3 6785.5 6816.7 6818.3 6825.5 6853.3 3799.1 4034.4 5890.6 5921.6 5950.3 26762.7 27099.6 27634.6 27798.0 27973.9 2199.1 2251.9 2254.4 2256.0 2972.0 15249.5 15596.2 16292.8 16356.4 16868.2 1726.8 1746.0 1820.1 1883.6 1896.8 119749.2 123762.0 124354.0 124849.8 127336.4 9400.2 10940.9 10967.4 11246.2 11544.6 14088.8 14210.9 14252.4 14260.7 14272.0 8625.0 8831.3 8862.4 8917.7 8939.9 8868.6 8873.6 8906.6 9034.7 9040.0 6861.9 7019.5 7022.5 7025.6 7034.2 1713.7 1719.2 1785.8 1878.6 1931.9 927.4 937.8 964.6 967.0 1042.6 22356.2 22390.7 23174.7 23413.9 23465.9 937.3 1016.1 1021.2 1048.3 1049.1 10021.6 11342.8 12235.3 12375.3 12395.6 568535.0 581868.0 582482.0 594016.0 594259.0 72368.6 103433.5 119078.0 122871.0 131677.0 1002.5 1012.3 1043.2 1043.6 1045.2 20585.4 20648.8 20734.5 20767.5 20855.4 50069.7 52039.5 53885.7 54032.7 54738.7 73799.6 74120.1 76853.2 78741.0 80451.6 7922.4 8185.4 8254.8 8419.2 8454.8 7875.7 9309.0 12906.4 13525.5 13712.5 11153.9 11400.1 11714.0 11728.5 11786.8 11708.6 11718.2 11752.6 11792.9 11894.4 11985.4 12031.3 12130.5 12196.4 12236.6 8386.8 8491.5 8544.4 8565.7 8679.5 10831.6 11760.6 11824.6 11947.2 12061.3 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5522.53 Median:5235.06 stddev:3042.28 (58.1%) stddev-sans-first:3035.60 09:27:46 INFO - PROCESS | 1657 | Values: 1473.6 1534.3 1688.2 1846.9 1853.2 7590.1 7849.7 8141.2 8639.2 8640.7 7708.0 7797.3 8149.1 8375.2 8384.2 2291.3 2307.7 2336.5 2338.8 2343.1 2055.9 2303.8 2317.2 2342.1 2343.7 1954.3 2103.9 2266.6 2342.9 2347.5 2307.4 2331.8 2333.5 2339.4 2341.4 2029.5 2112.5 2327.7 2333.0 2337.3 7775.3 7855.5 8249.0 8437.2 8457.0 5154.0 5278.1 5278.6 5293.5 5293.8 3526.4 3578.1 3604.1 3604.2 3613.7 1055.4 1058.2 1064.2 1070.7 1081.6 11015.6 11112.6 11120.2 11124.1 11141.0 4491.4 4494.4 4718.0 4723.7 4730.4 9621.4 9622.1 9622.7 9639.5 9658.3 5216.7 5232.4 5237.7 5238.7 5239.7 4686.2 4780.0 4785.9 4789.9 4801.4 3276.4 3732.9 3751.1 3800.7 3859.7 1039.6 1162.8 1205.0 1207.9 1237.4 786.6 821.4 821.8 838.5 898.4 5633.5 5887.7 5955.6 5965.8 5967.6 924.3 943.2 952.5 953.1 975.2 3833.1 4012.2 4028.6 4070.2 4090.2 9878.4 10297.4 10338.6 10365.6 10382.1 9328.8 9842.4 10335.5 10342.4 10342.7 7583.2 7876.2 7898.5 7902.9 7905.6 4491.8 5940.4 6013.2 6020.3 6076.7 9629.6 9676.3 9698.5 9714.7 9725.9 9313.0 9740.7 9941.4 9957.5 9972.3 7321.5 7332.2 7484.9 7487.7 7490.1 7698.3 8021.0 8085.0 8091.3 8110.1 6074.8 7086.8 7137.9 7257.7 7328.6 7096.2 7134.6 7153.9 7269.8 7275.6 6815.2 6833.0 6849.7 6901.0 6914.0 4171.8 4932.2 4954.2 5004.5 5019.2 4917.7 4942.8 4952.3 5028.9 5084.8 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4484.01 Median:4923.22 stddev:1402.56 (28.5%) stddev-sans-first:1370.77 09:27:46 INFO - PROCESS | 1657 | Values: 293.4 295.5 308.9 311.3 314.0 4158.4 5281.4 5329.4 5377.6 5441.5 4708.6 4746.9 5075.9 5177.4 5181.5 4056.3 4084.6 4088.8 4093.1 4106.5 3665.8 3802.8 3899.9 4055.0 4058.9 4047.9 4064.4 4072.8 4076.6 4091.2 4052.4 4069.0 4090.2 4104.4 4105.7 3333.6 3378.9 3519.8 3565.4 3572.5 4491.7 4813.0 5141.5 5172.6 5179.3 3900.3 4607.2 4628.2 4634.6 4651.2 4601.0 5428.8 5589.4 5648.9 5689.6 3219.0 3304.8 3650.7 3802.8 3821.7 4947.9 5877.4 5884.8 5896.3 5932.8 3585.7 3665.2 3877.3 3894.1 3906.4 3275.4 3902.3 3923.7 3941.6 3960.8 4392.7 4616.1 4618.7 4626.5 4655.3 4667.0 4842.7 5390.3 5641.3 5759.5 1219.7 1248.3 1282.1 1299.8 1344.2 777.8 824.8 826.0 875.8 892.7 5191.8 5212.2 5213.2 5220.0 5227.5 5235.6 5647.9 5687.9 5688.2 5690.0 4396.8 4809.3 4959.4 5179.5 5189.3 2515.5 2520.1 2523.6 2556.3 2558.0 4040.7 4089.7 4738.3 4896.8 5042.7 4386.7 4862.8 4891.4 4898.6 5345.5 5526.0 5587.9 6024.6 6056.8 6114.3 5843.6 5869.0 5875.2 5876.6 5944.9 4826.3 5135.9 5271.5 5563.7 5820.8 4981.8 5957.3 5998.0 6010.7 6019.5 3594.1 3839.9 3873.4 3877.9 3884.0 5434.9 5638.8 5644.3 5792.3 5842.4 5680.7 5699.0 5719.7 5767.7 5773.8 5675.4 5684.7 5706.4 5731.6 5776.3 5192.4 5488.6 5554.1 5562.7 5636.5 4702.3 5669.3 5674.4 5677.4 5694.5 4808.8 5340.4 5407.4 5489.8 5558.3 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:23837.75 Median:3541.06 stddev:37233.68 (1051.5%) stddev-sans-first:37297.84 09:27:46 INFO - PROCESS | 1657 | Values: 768.5 791.4 799.4 814.3 832.4 15066.1 15252.8 15706.8 15960.2 15964.2 14509.8 14547.8 14759.9 14760.3 14802.3 637.0 693.3 710.7 850.4 855.3 697.7 698.7 700.2 840.9 871.5 892.9 900.3 901.1 901.4 902.6 893.4 895.2 899.5 900.1 901.0 534.3 649.9 668.0 672.2 672.4 14580.6 14624.9 14642.7 14643.3 14654.9 13450.9 13785.3 13826.3 13835.7 14125.7 12487.4 12533.9 12730.5 12862.9 13201.9 4550.8 4571.6 4591.5 4744.6 4756.9 105892.0 107087.5 113375.0 114434.0 114963.4 10818.3 10850.6 10910.3 10991.9 11251.5 21289.4 21428.9 21431.5 21455.2 21460.0 7886.2 7890.7 7896.8 8013.5 8092.8 4381.8 4389.4 4475.3 4530.3 4599.3 3395.1 3534.2 3539.4 3542.7 3547.4 637.5 673.0 678.5 684.6 691.9 568.8 608.4 673.6 695.4 703.2 8867.6 8886.3 8964.9 9127.0 9442.7 571.4 604.7 618.9 626.2 678.4 4566.2 4569.1 4667.4 4672.2 4699.1 103289.0 107437.9 116799.4 154042.0 162525.0 90241.5 93939.5 100734.0 108249.0 138247.0 115924.0 119470.0 122770.0 123329.4 127178.0 7556.6 8895.3 8932.8 9132.3 9245.0 57455.1 63680.7 67328.0 71054.6 71414.3 69769.0 73556.1 75283.0 92021.6 93155.1 13255.3 13614.5 13635.5 13684.3 14207.0 14332.9 14791.8 14982.3 14990.5 15013.0 11197.9 13672.5 14093.0 14124.2 14350.1 10048.9 12518.0 13443.6 13803.3 14221.0 12093.3 12138.8 12223.1 12278.5 12723.5 11116.6 11193.3 11407.5 11462.3 11464.9 11033.1 11044.8 11356.0 11389.5 11425.4 09:27:46 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:27:46 INFO - PROCESS | 1657 | 09:27:46 INFO - TEST-INFO | 1657: exit 0 09:27:46 INFO - TEST-OK | dromaeo_css | took 1116460ms 09:27:46 INFO - TEST-START | kraken 09:27:46 INFO - operating with platform_type : mac_ 09:27:46 INFO - Initialising browser for kraken test... 09:27:46 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpvMMB0Q/profile http://localhost:49265/getInfo.html) 09:27:49 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 09:27:49 INFO - PROCESS | 1664 | colorDepth:24 09:27:49 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 09:27:49 INFO - PROCESS | 1664 | __metrics 09:27:49 INFO - PROCESS | 1664 | 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" 09:27:49 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:27:49 INFO - TEST-INFO | 1664: exit 0 09:27:49 INFO - Browser initialized. 09:27:49 INFO - Running cycle 1/1 for kraken test... 09:27:49 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpvMMB0Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:01 INFO - PROCESS | 1666 | RSS: Main: 244883456 09:28:01 INFO - PROCESS | 1666 | 09:28:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 09:28:05 INFO - PROCESS | 1666 | RSS: Main: 295198720 09:28:05 INFO - PROCESS | 1666 | 09:28:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 09:28:08 INFO - PROCESS | 1666 | RSS: Main: 306012160 09:28:08 INFO - PROCESS | 1666 | 09:28:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 09:28:12 INFO - PROCESS | 1666 | RSS: Main: 390823936 09:28:12 INFO - PROCESS | 1666 | 09:28:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 09:28:15 INFO - PROCESS | 1666 | RSS: Main: 292659200 09:28:15 INFO - PROCESS | 1666 | 09:28:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:28:18 INFO - PROCESS | 1666 | RSS: Main: 297000960 09:28:18 INFO - PROCESS | 1666 | 09:28:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 09:28:21 INFO - PROCESS | 1666 | RSS: Main: 334684160 09:28:21 INFO - PROCESS | 1666 | 09:28:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 09:28:25 INFO - PROCESS | 1666 | RSS: Main: 335572992 09:28:25 INFO - PROCESS | 1666 | 09:28:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 09:28:28 INFO - PROCESS | 1666 | RSS: Main: 334438400 09:28:28 INFO - PROCESS | 1666 | 09:28:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:28:30 INFO - PROCESS | 1666 | RSS: Main: 313319424 09:28:30 INFO - PROCESS | 1666 | 09:28:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:28:33 INFO - PROCESS | 1666 | RSS: Main: 320520192 09:28:33 INFO - PROCESS | 1666 | 09:28:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:28:36 INFO - PROCESS | 1666 | RSS: Main: 318111744 09:28:36 INFO - PROCESS | 1666 | 09:28:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:28:39 INFO - PROCESS | 1666 | RSS: Main: 327086080 09:28:39 INFO - PROCESS | 1666 | 09:28:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:28:43 INFO - PROCESS | 1666 | RSS: Main: 328249344 09:28:43 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 09:28:44 INFO - PROCESS | 1666 | RSS: Main: 371679232 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | __start_tp_report 09:28:44 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 09:28:44 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 09:28:44 INFO - PROCESS | 1666 | |i|pagename|runs| 09:28:44 INFO - PROCESS | 1666 | |0;ai-astar;103;112;111;111;111;110;112;113;116;166 09:28:44 INFO - PROCESS | 1666 | |1;audio-beat-detection;147;134;104;156;107;105;130;97;137;135 09:28:44 INFO - PROCESS | 1666 | |2;audio-dft;183;183;187;160;199;240;259;183;183;181 09:28:44 INFO - PROCESS | 1666 | |3;audio-fft;79;73;57;85;48;74;84;93;97;57 09:28:44 INFO - PROCESS | 1666 | |4;audio-oscillator;77;109;128;75;77;70;96;84;102;137 09:28:44 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;87;85;89;86;89;84;88;85;89;86 09:28:44 INFO - PROCESS | 1666 | |6;imaging-darkroom;94;94;94;93;93;93;94;94;94;93 09:28:44 INFO - PROCESS | 1666 | |7;imaging-desaturate;86;87;86;84;85;85;86;85;86;86 09:28:44 INFO - PROCESS | 1666 | |8;json-parse-financial;57;56;56;56;54;54;53;53;55;55 09:28:44 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;66;68;83;77;72;74;70;69;70;71 09:28:44 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;83;81;82;83;82;81;84;86;83;85 09:28:44 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;136;136;133;133;133;135;136;137;168;144 09:28:44 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;163;163;162;161;161;161;162;162;159;166 09:28:44 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;67;66;66;66;65;67;68;64;66 09:28:44 INFO - PROCESS | 1666 | __end_tp_report 09:28:44 INFO - PROCESS | 1666 | __start_cc_report 09:28:44 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2014 09:28:44 INFO - PROCESS | 1666 | __end_cc_report 09:28:44 INFO - PROCESS | 1666 | __startTimestamp1467649724966__endTimestamp 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | ------- Summary: start ------- 09:28:44 INFO - PROCESS | 1666 | Number of tests: 14 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:116.50 Median:112.00 stddev:17.70 (15.8%) stddev-sans-first:18.08 09:28:44 INFO - PROCESS | 1666 | Values: 103.0 112.0 111.0 111.0 111.0 110.0 112.0 113.0 116.0 166.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:136.00 stddev:20.39 (15.0%) stddev-sans-first:20.05 09:28:44 INFO - PROCESS | 1666 | Values: 147.0 134.0 104.0 156.0 107.0 105.0 130.0 97.0 137.0 135.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:195.80 Median:185.00 stddev:30.17 (16.3%) stddev-sans-first:31.64 09:28:44 INFO - PROCESS | 1666 | Values: 183.0 183.0 187.0 160.0 199.0 240.0 259.0 183.0 183.0 181.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:74.70 Median:81.50 stddev:16.28 (20.0%) stddev-sans-first:17.20 09:28:44 INFO - PROCESS | 1666 | Values: 79.0 73.0 57.0 85.0 48.0 74.0 84.0 93.0 97.0 57.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:76.00 stddev:23.31 (30.7%) stddev-sans-first:23.74 09:28:44 INFO - PROCESS | 1666 | Values: 77.0 109.0 128.0 75.0 77.0 70.0 96.0 84.0 102.0 137.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:1.87 (2.1%) stddev-sans-first:1.99 09:28:44 INFO - PROCESS | 1666 | Values: 87.0 85.0 89.0 86.0 89.0 84.0 88.0 85.0 89.0 86.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:28:44 INFO - PROCESS | 1666 | Values: 94.0 94.0 94.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:0.84 (1.0%) stddev-sans-first:0.88 09:28:44 INFO - PROCESS | 1666 | Values: 86.0 87.0 86.0 84.0 85.0 85.0 86.0 85.0 86.0 86.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.50 stddev:1.37 (2.5%) stddev-sans-first:1.22 09:28:44 INFO - PROCESS | 1666 | Values: 57.0 56.0 56.0 56.0 54.0 54.0 53.0 53.0 55.0 55.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.50 stddev:4.94 (6.9%) stddev-sans-first:4.74 09:28:44 INFO - PROCESS | 1666 | Values: 66.0 68.0 83.0 77.0 72.0 74.0 70.0 69.0 70.0 71.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:1.63 (2.0%) stddev-sans-first:1.73 09:28:44 INFO - PROCESS | 1666 | Values: 83.0 81.0 82.0 83.0 82.0 81.0 84.0 86.0 83.0 85.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:136.00 stddev:10.65 (7.8%) stddev-sans-first:11.24 09:28:44 INFO - PROCESS | 1666 | Values: 136.0 136.0 133.0 133.0 133.0 135.0 136.0 137.0 168.0 144.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.00 Median:162.00 stddev:1.83 (1.1%) stddev-sans-first:1.90 09:28:44 INFO - PROCESS | 1666 | Values: 163.0 163.0 162.0 161.0 161.0 161.0 162.0 162.0 159.0 166.0 09:28:44 INFO - PROCESS | 1666 | 09:28:44 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.14 (1.7%) stddev-sans-first:1.17 09:28:44 INFO - PROCESS | 1666 | Values: 67.0 67.0 66.0 66.0 66.0 65.0 67.0 68.0 64.0 66.0 09:28:44 INFO - PROCESS | 1666 | -------- Summary: end -------- 09:28:44 INFO - PROCESS | 1666 | 09:28:45 INFO - TEST-INFO | 1666: exit 0 09:28:45 INFO - TEST-OK | kraken | took 59061ms 09:28:45 INFO - SUITE-END | took 1175s 09:28:46 INFO - Completed test suite (00:19:35) 09:28:46 INFO - javascript benchmark 09:28:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.0823023668495, 3345.9665403345975, 3371.359441706076, 3382.6786455286738, 3414.931701365973, 5596.328440587135, 5631.60578759487, 5696.572757043223, 5822.0, 5824.534037277018, 5685.317761868581, 5703.600747947635, 5816.156657284691, 5834.854128646786, 5836.182934389179, 5138.3577052868395, 5163.63854530183, 5212.452692467293, 5224.947750522503, 5248.8425347239645, 5244.082285600024, 5251.343582052244, 5274.525292723654, 5294.867628309285, 5314.282571852789, 5146.845594632167, 5160.716160611165, 5168.534831865112, 5219.42586315505, 5227.477252274742, 5189.325387699575, 5191.169412893919, 5223.791048358061, 5314.256004159421, 5339.866503337409, 5180.6891586504935, 5223.981323641891, 5248.134057880445, 5265.76304066318, 5324.840254792362, 5644.125160600113, 5810.82567522975, 5847.8245652630485, 5890.911636325459, 5891.646501209898, 6134.355892631254, 6151.661658608775, 6175.87648247037, 6226.6264024158245, 6263.530235232331, 6294.842628934268, 6323.241211054703, 6358.237011558596, 6375.93624063758, 6392.360763923617, 5676.744546495335, 5764.221830052889, 5926.081457374114, 5938.524918006549, 5939.13882487037, 6263.937360626426, 6272.560920735505, 6290.779822706183, 6305.747770089145, 6309.179806625201, 6209.627422354673, 6240.500759939194, 6243.75024999004, 6250.124982502453, 6252.249730032425, 6075.513958883279, 6107.877842443127, 6217.502599792097, 6311.842203944846, 6324.9051264231075, 5220.634555581073, 6095.359987201368, 6146.955017647011, 6157.538184636171, 6167.809500062336, 6265.592736472206, 6281.623102613858, 6300.936990630035, 6303.747850086037, 6331.905021424683, 4914.803407863646, 4963.677360971526, 4984.77568509418, 5043.798248070036, 5071.594272458195, 3808.676262517717, 4439.0, 4474.015716542355, 4510.391097201901, 4541.091781643684, 5793.8841223175305, 5805.94194058054, 5825.533957283407, 5826.417358264224, 5897.439743224387, 6135.5398345124295, 6216.347283535164, 6217.440430361199, 6231.003039513656, 6245.469135123565, 5762.135679648002, 5806.477417032487, 5845.357010728816, 5879.0, 5902.321233058254, 5499.587530935196, 5505.724713764376, 5506.972465137648, 5522.779088836482, 5535.6955367454375, 6206.410391012847, 6356.459700925473, 6370.8088757337355, 6375.77684781049, 6431.678416079271, 5381.596380271417, 6118.771466972081, 6199.783007594712, 6243.282022567463, 6249.96875015622, 6238.220222472191, 6280.874382512233, 6300.905486417799, 6337.397947195009, 6374.681265936777, 6160.907586386297, 6238.469730072995, 6246.0, 6246.6876656167915, 6252.812415627538, 6048.758049678139, 6187.628742275478, 6231.9688401559515, 6260.718267678057, 6277.623342599459, 6093.664848433379, 6198.81403557894, 6215.96892015537, 6224.657643829632, 6237.844053898689, 6163.137160797582, 6191.226096737908, 6192.256929168529, 6201.627902325875, 6223.3465486123305, 5873.853153671193, 5931.08068249422, 5947.167396564571, 6012.759489620366, 6066.090086487062, 6157.384261573807, 6183.84540386494, 6193.256809182927, 6207.379262073757, 6234.719437625227, 5867.677277749594, 6070.6357618543025, 6072.210612620331, 6072.99795533732, 6086.055570499105], "value": 5735.581932039165, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.71767645221735, 494.37708487305986, 504.29902435614525, 524.4807640435968, 552.9391766905641, 29982.70017299829, 39281.410778838304, 40029.39955900657, 40117.39823902636, 40133.0, 42275.36586951198, 42535.78732106335, 42585.36121958165, 42668.0, 42732.145357092915, 8617.482951022926, 9461.81076378472, 9618.326717129803, 9620.230381569492, 9624.663136790214, 8634.611442485071, 11539.711507212303, 11794.233374830652, 11805.94097029518, 12101.757964840697, 11813.0, 11874.7625047499, 11877.04983601314, 11878.881211187907, 11891.881081189207, 7353.227911069314, 11239.606613768561, 11489.368084755335, 11616.419179041057, 11838.0, 11532.59635912739, 11594.362310072942, 11613.419329033515, 11616.36110013949, 11623.012043976272, 38877.02807429837, 38986.805065974775, 39172.80413597914, 39241.0, 39295.60704392948, 3414.163529935182, 3462.4633181856852, 3482.2687235680537, 3488.8081155536433, 3603.045193023851, 30449.391012179854, 30660.846695766602, 31195.532067019012, 31521.89673361444, 31842.726290948332, 3250.089974807058, 3262.706356427933, 3299.4390953537954, 3323.567936168307, 3412.4198886189406, 502909.48545257404, 504929.0, 505348.0, 505531.0, 506406.4679676615, 32737.67262327346, 33164.66835331664, 60343.39656603378, 60417.79164416775, 60717.39282607206, 34854.47718284177, 35009.1247718804, 35043.29913401718, 35101.64898350984, 35121.47317790235, 14377.424903003763, 14714.190719510316, 14722.0, 14731.0, 14750.55748327552, 11310.09519238459, 11452.484638191301, 11805.23265987708, 11913.821292680617, 12459.003279737599, 6490.935090649127, 7744.612769361509, 7765.339946104531, 7799.064112306446, 7812.257835505618, 3164.626530102423, 3217.549543063973, 3224.1617179533023, 3226.709596136312, 3238.757093217971, 903.7785742493131, 948.2840455456087, 954.6754103604808, 972.2076507646245, 999.7500624843789, 29668.554971675443, 29783.0, 29995.850020750193, 30512.389752204832, 30516.237094072825, 889.9955500222458, 978.4080631218153, 1031.8091153136693, 1106.2422240765102, 1124.6457365929707, 12501.562445314552, 12753.91591714696, 15390.0, 15570.149134380836, 15838.0, 618130.0, 620148.0, 621932.0, 622439.0, 624353.0, 57580.0, 58314.41685583175, 58378.7081064592, 58383.54041149005, 58701.0, 1239.6962744127566, 1276.1194775604804, 1347.4879545772935, 1375.4635692080096, 1392.9930350348386, 29578.112656620335, 30367.392652147275, 30538.931137410083, 31249.84375078201, 31376.529352060195, 56068.0, 56296.0, 56926.715366422904, 57198.7140064297, 57403.0, 630736.0, 637094.0, 638162.0, 638777.0305742393, 639624.0, 35527.46708799422, 37316.25367492689, 42942.57057429511, 43047.56952430436, 43457.565424345354, 36820.63179368172, 46009.00572955985, 46762.0, 46818.59544213679, 47183.764081179375, 38416.423753644274, 38884.61115388923, 39147.41278880762, 39461.8119528731, 39956.0, 38905.41641875431, 39095.609043910335, 39631.80184099061, 39866.0, 40724.18551628892, 35816.64183358238, 38497.6150238494, 38588.0, 38601.227975440896, 38815.029624259616, 33551.0, 34868.47697284492, 35168.824155879054, 35285.0, 35353.292934140656, 25046.74953250444, 25543.978240870158, 25583.360415989006, 26080.608790868533, 26172.0], "value": 19139.77496643469, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.50473850824375, 381.3345711733026, 387.76927728001846, 390.3442217075314, 410.57915636472615, 15529.611759706013, 16186.676266474678, 18145.909270453663, 21701.67447488285, 22090.0, 20395.0, 20664.586708265826, 21175.0, 21623.135074596998, 21693.45766355842, 5383.650062745909, 5974.492168165701, 6206.6586337751405, 6219.1915051043525, 6264.185655864754, 4259.382389553512, 4518.006038671487, 6103.908441373383, 6581.473482121419, 6886.655667216644, 6578.0, 6841.657917104149, 6884.793456196322, 6886.035954966309, 6893.310668933092, 6785.491088168383, 6816.693248803816, 6818.318168183142, 6825.453963682893, 6853.314668533157, 3799.088218827489, 4034.374671925856, 5890.617109887887, 5921.61509501881, 5950.34546199922, 26762.73237267622, 27099.593506097423, 27634.585481217797, 27798.0, 27973.881044758185, 2199.0763879170627, 2251.876146811941, 2254.413852398372, 2256.007356763019, 2971.9746687392817, 15249.466268680653, 15596.22018899062, 16292.755608665879, 16356.427525036566, 16868.24092915822, 1726.8359505846925, 1745.9825401745948, 1820.071763400662, 1883.5761953560477, 1896.7913529511743, 119749.21630094103, 123762.0, 124354.0, 124849.75150248472, 127336.36331818374, 9400.15398614128, 10940.905909408995, 10967.396793176293, 11246.212765106528, 11544.59593914209, 14088.788668169986, 14210.85789142116, 14252.429902803771, 14260.714785704227, 14272.0, 8625.0, 8831.293496520262, 8862.379633425599, 8917.687880924137, 8939.865902011476, 8868.556572171366, 8873.64505419776, 8906.55467226636, 9034.728958131267, 9040.005599384063, 6861.862762744717, 7019.473539484559, 7022.54353467023, 7025.648717564101, 7034.155901291878, 1713.6744018636418, 1719.2270936764141, 1785.7678501794944, 1878.6235412203573, 1931.8988176739406, 927.3693888156118, 937.7514958535993, 964.609333220047, 966.9613215471443, 1042.61944390297, 22356.217532386614, 22390.664140037912, 23174.65238021431, 23413.882930585238, 23465.88267058654, 937.3438592984937, 1016.1261315268863, 1021.1881554164457, 1048.2557384257263, 1049.0872940541778, 10021.599136034623, 11342.773144537228, 12235.32705701195, 12375.344711932516, 12395.62813115608, 568535.0, 581868.0, 582482.0, 594016.0, 594259.0, 72368.63815680993, 103433.48283258536, 119078.0, 122871.0, 131677.0, 1002.4837208837308, 1012.336919317848, 1043.186314674555, 1043.5929987304958, 1045.153425725165, 20585.382438526867, 20648.793512065287, 20734.481637959172, 20767.480812979797, 20855.37433876986, 50069.74965125151, 52039.479605203465, 53885.730571346896, 54032.72983635057, 54738.726306369805, 73799.63100184465, 74120.14699632552, 76853.23146768461, 78741.0, 80451.59774201325, 7922.405819563671, 8185.386096042702, 8254.752357429286, 8419.200175983271, 8454.788630284293, 7875.684972601031, 9309.022552631875, 12906.41921113571, 13525.458981640624, 13712.52006179779, 11153.88846111561, 11400.087992960378, 11714.0, 11728.472218750007, 11786.76426471483, 11708.648740537797, 11718.17972741934, 11752.58865939688, 11792.941035295113, 11894.405279736151, 11985.398701001435, 12031.338276394532, 12130.514779408724, 12196.390180490762, 12236.632901012983, 8386.790330241549, 8491.532965686947, 8544.359173061921, 8565.657373705231, 8679.479231245892, 10831.620893268699, 11760.647180584596, 11824.645260642195, 11947.163698541019, 12061.336626485627], "value": 10008.876391188407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.580029691538, 1534.2635534943227, 1688.222873373301, 1846.9353572624962, 1853.2216469082991, 7590.089189297279, 7849.68601255949, 8141.226583474568, 8639.179277968591, 8640.654373825048, 7708.0, 7797.25926037027, 8149.103598604163, 8375.246227839492, 8384.161583841627, 2291.32405940248, 2307.711536057997, 2336.4626135988733, 2338.8012018978407, 2343.1372059212968, 2055.9074841632164, 2303.815694744425, 2317.2353123469297, 2342.0865862313626, 2343.6836027136374, 1954.2671498188179, 2103.852730308879, 2266.614675505168, 2342.871142087193, 2347.507023525062, 2307.3885420363617, 2331.7668233176714, 2333.5099629077745, 2339.4385347516645, 2341.3678306857055, 2029.482481967089, 2112.524681946565, 2327.7439481657, 2333.0317918063984, 2337.2637619149914, 7775.339096176832, 7855.450118491708, 8248.958755206186, 8437.156284371575, 8457.0, 5154.0, 5278.102722537178, 5278.551323137538, 5293.470652934714, 5293.788248470057, 3526.418141006731, 3578.141246100943, 3604.1350075981577, 3604.1710406606626, 3613.7109031277696, 1055.3878750324793, 1058.2063452410691, 1064.1912146768511, 1070.6520380876168, 1081.6268387406333, 11015.559377624886, 11112.611058612909, 11120.165987550885, 11124.110071194285, 11140.997310242035, 4491.371208030879, 4494.438195225597, 4717.976410117927, 4723.6929599575915, 4730.385049943554, 9621.374610650426, 9622.13400793918, 9622.711318660451, 9639.469829159323, 9658.275629327829, 5216.66091704038, 5232.424433312332, 5237.685738855681, 5238.71187084714, 5239.659422137549, 4686.156491831435, 4780.043991201774, 4785.856424307276, 4789.880252993633, 4801.375821143229, 3276.426625340556, 3732.8880133596035, 3751.0622344413896, 3800.6579407852873, 3859.671927886161, 1039.6101461951769, 1162.8139497680331, 1204.9578264760692, 1207.945642446092, 1237.449335045914, 786.5949036246337, 821.4455242711146, 821.8109834737924, 838.455004247232, 898.4294972692387, 5633.521150702236, 5887.676177810262, 5955.612885162451, 5965.7613695452565, 5967.552433567413, 924.2975338742604, 943.1558754914313, 952.5189779161618, 953.1183655118987, 975.2393133355994, 3833.099221682902, 4012.2176175646055, 4028.5568587455355, 4070.1656160487028, 4090.1615168890307, 9878.357906736039, 10297.3821570706, 10338.638147664795, 10365.585376585002, 10382.065614094769, 9328.813423731623, 9842.409455432697, 10335.483225838678, 10342.37945723259, 10342.689719308433, 7583.203763604853, 7876.212378762078, 7898.4865983712025, 7902.881456778153, 7905.604719763989, 4491.775411229491, 5940.376260492586, 6013.218281623361, 6020.277566692025, 6076.6961651916345, 9629.614815407585, 9676.27427942893, 9698.51507424612, 9714.659986900424, 9725.902740972782, 9312.953435232781, 9740.659076932274, 9941.353812002486, 9957.452340121363, 9972.3019388645, 7321.524100933422, 7332.193458719643, 7484.887726683995, 7487.737929172453, 7490.138634056935, 7698.2686644768655, 8021.0, 8085.0, 8091.271785539329, 8110.0673422557165, 6074.838312288219, 7086.751963681247, 7137.85724285501, 7257.745978890714, 7328.633568321669, 7096.183938847099, 7134.60759658203, 7153.892691609734, 7269.818254543467, 7275.599842008741, 6815.216250131299, 6833.009213664091, 6849.691763870737, 6900.999355093383, 6914.0, 4171.791410429406, 4932.235503496964, 4954.157793175097, 5004.549590536869, 5019.196928491424, 4917.680350777188, 4942.827001054946, 4952.257161425815, 5028.9245661315845, 5084.771185296744], "value": 4458.3062712244455, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.4161019571052, 295.5094543058523, 308.90732780165945, 311.2731771313982, 314.0327790405551, 4158.37624356346, 5281.366236051669, 5329.360476742791, 5377.569794416447, 5441.483059109388, 4708.575083831821, 4746.881327966803, 5075.9492405075935, 5177.378714554259, 5181.507756763111, 4056.3104272273786, 4084.550699423061, 4088.7546747195115, 4093.1199792044704, 4106.529982165066, 3665.771966391262, 3802.8098595070273, 3899.922001559967, 4055.047063939972, 4058.8985275368204, 4047.8583249586272, 4064.4309796628495, 4072.839240816368, 4076.5515793262716, 4091.243120022805, 4052.4326594276827, 4069.0234343757434, 4090.2228576570355, 4104.42538044674, 4105.733127346743, 3333.566566376457, 3378.9324213515592, 3519.771214871025, 3565.3760591896576, 3572.4641303804638, 4491.73049617024, 4813.0, 5141.53726164647, 5172.586193104543, 5179.326687530597, 3900.31744444721, 4607.216773148572, 4628.1669299526075, 4634.582887540104, 4651.162790697673, 4600.95399046012, 5428.83713488596, 5589.413111623262, 5648.858778530528, 5689.630174038674, 3218.9678103219135, 3304.801711897294, 3650.740494529383, 3802.790846503441, 3821.6942644588644, 4947.861991741919, 5877.382874798127, 5884.794032208895, 5896.321922978869, 5932.792352267693, 3585.6772890439984, 3665.2303016366327, 3877.302085624615, 3894.1432884765304, 3906.43356713275, 3275.4268003099364, 3902.297586434469, 3923.6861051115843, 3941.586133456003, 3960.821763020671, 4392.692511524227, 4616.1229366420275, 4618.653600979873, 4626.537346265347, 4655.324977878188, 4666.976665116721, 4842.661013729005, 5390.326209223847, 5641.266635337462, 5759.539236861041, 1219.7316590350108, 1248.263524520519, 1282.051282051294, 1299.7790375636164, 1344.227069435059, 777.8371334854429, 824.8185399212164, 825.9669613215524, 875.7679215008028, 892.6563273139888, 5191.844244672666, 5212.192110222922, 5213.244079608511, 5220.0, 5227.451117632594, 5235.607329450307, 5647.887042259132, 5687.943120568824, 5688.232088667977, 5690.0, 4396.812860527703, 4809.278608208797, 4959.404871415453, 5179.4979455957355, 5189.3253876996505, 2515.5220508103403, 2520.0847356009563, 2523.583608704543, 2556.335352808246, 2557.974420255811, 4040.7171497995446, 4089.650415362923, 4738.256777146016, 4896.755162241873, 5042.747862606855, 4386.692931494828, 4862.82980095695, 4891.364122664102, 4898.583620392235, 5345.530967981209, 5525.950069486785, 5587.860303492445, 6024.608400454044, 6056.818295451144, 6114.296855861544, 5843.590948633555, 5869.0, 5875.206847075761, 5876.560242740556, 5944.900193464223, 4826.251930950708, 5135.92296115566, 5271.472852714851, 5563.721813909369, 5820.76716931318, 4981.775820088033, 5957.344692083954, 5997.980343341555, 6010.699465026643, 6019.518438524995, 3594.1194407370363, 3839.884803455901, 3873.3802591585213, 3877.9418308724826, 3884.0095775577047, 5434.945650543444, 5638.774449021993, 5644.294463192101, 5792.275965504312, 5842.444967728059, 5680.6591604503865, 5699.005348128288, 5719.685417302088, 5767.711614419179, 5773.769049238152, 5675.4040825714355, 5684.687342196219, 5706.429357064427, 5731.570132240182, 5776.306843178845, 5192.3769147702515, 5488.588355873405, 5554.1391084381985, 5562.749676264497, 5636.520895723909, 4702.321190415559, 5669.31968163823, 5674.404187560247, 5677.403872593483, 5694.515966142759, 4808.807647694053, 5340.385855626652, 5407.432219617041, 5489.752961116839, 5558.333000039859], "value": 4032.3618402939164, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.4697558684508, 791.4420333664767, 799.3724925933143, 814.254956714606, 832.3757182113415, 15066.09603423794, 15252.847471525281, 15706.764398534031, 15960.201989900503, 15964.201789910516, 14509.782353264709, 14547.781783273233, 14759.852401475982, 14760.261986900665, 14802.333894974725, 636.9936300636981, 693.3343989769827, 710.6944014073946, 850.430211758122, 855.285836326668, 697.6790676288913, 698.6890833579059, 700.1528150937372, 840.932725381968, 871.5337294547419, 892.8705337726026, 900.3382513852313, 901.0808974845653, 901.4320977783987, 902.6073657958809, 893.4415990006246, 895.1987970766193, 899.4872922434215, 900.0954041188596, 900.9684661036832, 534.284059360455, 649.9447546958514, 667.9632620205887, 672.2269390201259, 672.3646154384109, 14580.635484112981, 14624.926875365662, 14642.707145857023, 14643.267836608233, 14654.853451465455, 13450.86549134506, 13785.310734463237, 13826.308684565833, 13835.654108647264, 14125.688247579325, 12487.440074784647, 12533.937330313382, 12730.490780368775, 12862.871371286354, 13201.933990330082, 4550.795214215368, 4571.634269258451, 4591.494935557086, 4744.572988431023, 4756.857294281177, 105892.0, 107087.46456267669, 113375.0, 114434.0, 114963.42518287354, 10818.29681070728, 10850.62022829213, 10910.345379277269, 10991.945040274908, 11251.549938002388, 21289.36131916045, 21428.89285553562, 21431.46421339479, 21455.249066282606, 21460.0, 7886.211378862068, 7890.723824666109, 7896.802579935547, 8013.519188848688, 8092.797680057928, 4381.824727010948, 4389.385486031958, 4475.261581839025, 4530.29780384041, 4599.2871104978785, 3395.0833275015607, 3534.151803567177, 3539.380608393521, 3542.734294927891, 3547.396942519726, 637.4963778614842, 673.0476375928007, 678.521642242218, 684.5721424109931, 691.8685449764629, 568.8407245971134, 608.412881569279, 673.5958424945092, 695.4401706626113, 703.2018658822274, 8867.645294188289, 8886.289096872235, 8964.8655270171, 9127.03946450981, 9442.716718498456, 571.3629303326844, 604.7339170764849, 618.8762247550508, 626.1671976271639, 678.4436761855231, 4566.150708962876, 4569.109023740405, 4667.416572928384, 4672.182368085571, 4699.130660827689, 103289.0, 107437.8512429747, 116799.41600292113, 154042.0, 162525.0, 90241.54879225562, 93939.53030234805, 100734.0, 108249.0, 138247.0, 115924.0, 119470.0, 122770.0, 123329.38335308267, 127178.0, 7556.622168891643, 8895.332850036144, 8932.776680583038, 9132.269418446642, 9244.95377523108, 57455.13817292827, 63680.72638547296, 67328.0, 71054.5789084205, 71414.28585714284, 69769.0, 73556.05775769123, 75283.0, 92021.61967570626, 93155.06844931735, 13255.337233138498, 13614.455421783414, 13635.522756703467, 13684.31578421055, 14207.0, 14332.928335358256, 14791.778123327944, 14982.325795339018, 14990.47533336328, 15013.0, 11197.888021119685, 13672.453101875813, 14093.0, 14124.223167725873, 14350.067245629001, 10048.89951100509, 12518.0, 13443.596692099252, 13803.309834508034, 14220.958934849443, 12093.334866582423, 12138.817917731065, 12223.144379893321, 12278.508859645513, 12723.491060357483, 11116.555337786722, 11193.328400296008, 11407.486663100346, 11462.312261264016, 11464.885351146382, 11033.117350612092, 11044.779104418027, 11356.0, 11389.54441822351, 11425.428728563373], "value": 7750.693107859352, "unit": "score"}], "name": "dromaeo_css", "value": 7314.242849041151, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 111.0, 111.0, 111.0, 110.0, 112.0, 113.0, 116.0, 166.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 134.0, 104.0, 156.0, 107.0, 105.0, 130.0, 97.0, 137.0, 135.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 187.0, 160.0, 199.0, 240.0, 259.0, 183.0, 183.0, 181.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 57.0, 85.0, 48.0, 74.0, 84.0, 93.0, 97.0, 57.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 109.0, 128.0, 75.0, 77.0, 70.0, 96.0, 84.0, 102.0, 137.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 86.0, 89.0, 84.0, 88.0, 85.0, 89.0, 86.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 84.0, 85.0, 85.0, 86.0, 85.0, 86.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 56.0, 54.0, 54.0, 53.0, 53.0, 55.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 83.0, 77.0, 72.0, 74.0, 70.0, 69.0, 70.0, 71.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 83.0, 82.0, 81.0, 84.0, 86.0, 83.0, 85.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 133.0, 133.0, 133.0, 135.0, 136.0, 137.0, 168.0, 144.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 161.0, 161.0, 161.0, 162.0, 162.0, 159.0, 166.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 66.0, 65.0, 67.0, 68.0, 64.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1450.8999999999999, "alertThreshold": 2.0}]} 09:28:46 INFO - Return code: 0 09:28:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:28:46 INFO - # TBPL SUCCESS # 09:28:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:46 INFO - 2016-07-04 09:28:46.268 screenresolution[1670:10102] starting screenresolution argv=screenresolution get 09:28:46 INFO - 2016-07-04 09:28:46.271 screenresolution[1670:10102] Display 0: 1600x1200x32@60 09:28:46 INFO - 2016-07-04 09:28:46.277 screenresolution[1671:10105] starting screenresolution argv=screenresolution list 09:28:46 INFO - Available Modes on Display 0 09:28:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:46 INFO - Intel Iris: 09:28:46 INFO - Chipset Model: Intel Iris 09:28:46 INFO - Type: GPU 09:28:46 INFO - Bus: Built-In 09:28:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:46 INFO - Vendor: Intel (0x8086) 09:28:46 INFO - Device ID: 0x0a2e 09:28:46 INFO - Revision ID: 0x0009 09:28:46 INFO - Displays: 09:28:46 INFO - Display: 09:28:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:46 INFO - Display Serial Number: 1600x1200 60 09:28:46 INFO - Main Display: Yes 09:28:46 INFO - Mirror: Off 09:28:46 INFO - Online: Yes 09:28:46 INFO - Rotation: Supported 09:28:46 INFO - Return code: 0 09:28:46 INFO - Running post-action listener: _resource_record_post_action 09:28:46 INFO - ##### 09:28:46 INFO - ##### Finished run-tests step (success) 09:28:46 INFO - ##### 09:28:46 INFO - Running post-run listener: _resource_record_post_run 09:28:46 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36980224; Write bytes: 705227264; Read time: 239; Write time: 1938 09:28:46 INFO - TinderboxPrint: CPU usage
53.5% 09:28:46 INFO - TinderboxPrint: I/O read bytes / time
36,980,224 / 239 09:28:46 INFO - TinderboxPrint: I/O write bytes / time
705,227,264 / 1,938 09:28:46 INFO - TinderboxPrint: CPU idle
2,251.1 (46.5%) 09:28:46 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 09:28:46 INFO - TinderboxPrint: CPU user
1,624.2 (33.5%) 09:28:46 INFO - TinderboxPrint: Swap in / out
443,801,600 / 0 09:28:46 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 270336; Write bytes: 408835584; Read time: 5; Write time: 843 09:28:46 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 34506240; Write bytes: 295019520; Read time: 216; Write time: 1089 09:28:46 INFO - Running post-run listener: _upload_blobber_files 09:28:46 INFO - Blob upload gear active. 09:28:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:28:46 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 09:28:46 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'] 09:28:46 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 09:28:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:28:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:28:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:28:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:48 INFO - (blobuploader) - INFO - Done attempting. 09:28:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:28:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:28:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:49 INFO - (blobuploader) - INFO - Done attempting. 09:28:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:28:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:28:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:51 INFO - (blobuploader) - INFO - Done attempting. 09:28:51 INFO - (blobuploader) - INFO - Iteration through files over. 09:28:51 INFO - Return code: 0 09:28:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:28:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:28:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eafc99359d8daca8aedefae3516a2f64bb95f3d4e136c0f439c62c6016c7beb4b2faca0aaa7b6977c30ed14dd45b789ce5173a379e8c278657e6b5a243f89480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e768a04a07adf1dca07e1d4113d53c12e9e22a770f1ce4ba1b7b79e5e01400cb5f51d289620a9c7fb1843393c4bdfc502c1a211a992f97a79ded2f3bfff8af55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/273c607fa5a85014db5648e024d8f320edf039ee41cdf1deed56ae7b95f1dac9b511a796aa6b25053459e7820b2c420fc645e91f1ca9213bd23a9fa7f947ec39"} 09:28:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:28:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:28:51 INFO - Contents: 09:28:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eafc99359d8daca8aedefae3516a2f64bb95f3d4e136c0f439c62c6016c7beb4b2faca0aaa7b6977c30ed14dd45b789ce5173a379e8c278657e6b5a243f89480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e768a04a07adf1dca07e1d4113d53c12e9e22a770f1ce4ba1b7b79e5e01400cb5f51d289620a9c7fb1843393c4bdfc502c1a211a992f97a79ded2f3bfff8af55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/273c607fa5a85014db5648e024d8f320edf039ee41cdf1deed56ae7b95f1dac9b511a796aa6b25053459e7820b2c420fc645e91f1ca9213bd23a9fa7f947ec39"} 09:28:51 INFO - Running post-run listener: copy_logs_to_upload_dir 09:28:51 INFO - Copying logs to upload dir... 09:28:51 INFO - mkdir: /builds/slave/test/build/upload/logs 09:28:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.022905 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-04 09:28:51.459327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 09:28:51.464125) ========= 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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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/eafc99359d8daca8aedefae3516a2f64bb95f3d4e136c0f439c62c6016c7beb4b2faca0aaa7b6977c30ed14dd45b789ce5173a379e8c278657e6b5a243f89480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e768a04a07adf1dca07e1d4113d53c12e9e22a770f1ce4ba1b7b79e5e01400cb5f51d289620a9c7fb1843393c4bdfc502c1a211a992f97a79ded2f3bfff8af55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/273c607fa5a85014db5648e024d8f320edf039ee41cdf1deed56ae7b95f1dac9b511a796aa6b25053459e7820b2c420fc645e91f1ca9213bd23a9fa7f947ec39"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011060 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467280921/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eafc99359d8daca8aedefae3516a2f64bb95f3d4e136c0f439c62c6016c7beb4b2faca0aaa7b6977c30ed14dd45b789ce5173a379e8c278657e6b5a243f89480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e768a04a07adf1dca07e1d4113d53c12e9e22a770f1ce4ba1b7b79e5e01400cb5f51d289620a9c7fb1843393c4bdfc502c1a211a992f97a79ded2f3bfff8af55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/273c607fa5a85014db5648e024d8f320edf039ee41cdf1deed56ae7b95f1dac9b511a796aa6b25053459e7820b2c420fc645e91f1ca9213bd23a9fa7f947ec39"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 09:28:51.496323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:28:51.496621) ========= 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.vr1BjKEUhz/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.HzPKQoOMd2/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.005061 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:28:51.545170) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 09:28:51.545457) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 09:28:51.545745) ========= ========= Total master_lag: 2.55 =========