builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1471749642.32 results: success (0) buildid: 20160720030201 builduid: 477bc79dd26844549ba8261ee18ce627 revision: ed8e23b5e0c7b739e61173bb180cf3410a306679 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:42.318616) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:42.319062) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:42.319338) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:42.652711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:42.653071) ========= 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.ppuP14aCJG/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.YQwmHc25RI/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-20 20:20:42-- 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% 15.5M=0.001s 2016-08-20 20:20:42 (15.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.336831 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:43.049843) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:43.050292) ========= 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.ppuP14aCJG/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.YQwmHc25RI/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.029687 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:43.107560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-20 20:20:43.107962) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ed8e23b5e0c7b739e61173bb180cf3410a306679 --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 ed8e23b5e0c7b739e61173bb180cf3410a306679 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ppuP14aCJG/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.YQwmHc25RI/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-20 20:20:43,185 truncating revision to first 12 chars 2016-08-20 20:20:43,185 Setting DEBUG logging. 2016-08-20 20:20:43,185 attempt 1/10 2016-08-20 20:20:43,185 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ed8e23b5e0c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-20 20:20:44,790 unpacking tar archive at: mozilla-central-ed8e23b5e0c7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.862747 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-20 20:20:45.069959) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:45.070427) ========= script_repo_revision: ed8e23b5e0c7b739e61173bb180cf3410a306679 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:45.070866) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:45.071169) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-20 20:20:45.156108) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-20 20:20:45.156393) ========= /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.ppuP14aCJG/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.YQwmHc25RI/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:20:45 INFO - MultiFileLogger online at 20160820 20:20:45 in /builds/slave/test 20:20:45 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 20:20:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:20:45 INFO - {'append_to_log': False, 20:20:45 INFO - 'base_work_dir': '/builds/slave/test', 20:20:45 INFO - 'blob_upload_branch': 'Firefox', 20:20:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:20:45 INFO - 'branch': 'Firefox', 20:20:45 INFO - 'buildbot_json_path': 'buildprops.json', 20:20:45 INFO - 'config_files': ('talos/mac_config.py',), 20:20:45 INFO - 'default_actions': ('clobber', 20:20:45 INFO - 'read-buildbot-config', 20:20:45 INFO - 'download-and-extract', 20:20:45 INFO - 'populate-webroot', 20:20:45 INFO - 'create-virtualenv', 20:20:45 INFO - 'install', 20:20:45 INFO - 'run-tests'), 20:20:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:20:45 INFO - 'download_minidump_stackwalk': True, 20:20:45 INFO - 'download_symbols': 'ondemand', 20:20:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:20:45 INFO - 'tooltool.py': '/tools/tooltool.py', 20:20:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:20:45 INFO - '/tools/misc-python/virtualenv.py')}, 20:20:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:20:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:20:45 INFO - 'installer_path': 'installer.exe', 20:20:45 INFO - 'log_level': 'info', 20:20:45 INFO - 'log_name': 'talos', 20:20:45 INFO - 'log_to_console': True, 20:20:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:20:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:20:45 INFO - 'opt_config_files': (), 20:20:45 INFO - 'pip_index': False, 20:20:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:20:45 INFO - 'cmd': ('bash', 20:20:45 INFO - '-c', 20:20:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:20:45 INFO - 'enabled': True, 20:20:45 INFO - 'halt_on_failure': False, 20:20:45 INFO - 'name': 'check_screen_resolution'},), 20:20:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:20:45 INFO - 'cmd': ('bash', 20:20:45 INFO - '-c', 20:20:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:20:45 INFO - 'enabled': True, 20:20:45 INFO - 'halt_on_failure': False, 20:20:45 INFO - 'name': 'check_screen_resolution'},), 20:20:45 INFO - 'run_cmd_checks_enabled': True, 20:20:45 INFO - 'sps_profile': False, 20:20:45 INFO - 'sps_profile_interval': 0, 20:20:45 INFO - 'suite': 'dromaeojs', 20:20:45 INFO - 'system_bits': '32', 20:20:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:20:45 INFO - 'title': 't-yosemite-r7-0105', 20:20:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:20:45 INFO - 'use_talos_json': True, 20:20:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:20:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:20:45 INFO - 'work_dir': 'build'} 20:20:45 INFO - [mozharness: 2016-08-21 03:20:45.358570Z] Running clobber step. 20:20:45 INFO - Running pre-action listener: _resource_record_pre_action 20:20:45 INFO - Running main action method: clobber 20:20:45 INFO - rmtree: /builds/slave/test/build 20:20:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:20:47 INFO - Running post-action listener: _resource_record_post_action 20:20:47 INFO - [mozharness: 2016-08-21 03:20:47.456795Z] Finished clobber step (success) 20:20:47 INFO - [mozharness: 2016-08-21 03:20:47.456970Z] Running read-buildbot-config step. 20:20:47 INFO - Running pre-action listener: _resource_record_pre_action 20:20:47 INFO - Running main action method: read_buildbot_config 20:20:47 INFO - Using buildbot properties: 20:20:47 INFO - { 20:20:47 INFO - "project": "", 20:20:47 INFO - "product": "firefox", 20:20:47 INFO - "script_repo_revision": "production", 20:20:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 20:20:47 INFO - "repository": "", 20:20:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 20:20:47 INFO - "buildid": "20160720030201", 20:20:47 INFO - "stage_platform": "macosx64", 20:20:47 INFO - "basedir": "/builds/slave/test", 20:20:47 INFO - "nightly_build": "True", 20:20:47 INFO - "buildnumber": 72, 20:20:47 INFO - "slavename": "t-yosemite-r7-0105", 20:20:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:20:47 INFO - "platform": "yosemite_r7", 20:20:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:20:47 INFO - "branch": "mozilla-central", 20:20:47 INFO - "repo_path": "mozilla-central", 20:20:47 INFO - "revision": "ed8e23b5e0c7b739e61173bb180cf3410a306679", 20:20:47 INFO - "pgo_build": "False", 20:20:47 INFO - "builduid": "477bc79dd26844549ba8261ee18ce627", 20:20:47 INFO - "slavebuilddir": "test" 20:20:47 INFO - } 20:20:47 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg. 20:20:47 INFO - Running post-action listener: _resource_record_post_action 20:20:47 INFO - [mozharness: 2016-08-21 03:20:47.461325Z] Finished read-buildbot-config step (success) 20:20:47 INFO - [mozharness: 2016-08-21 03:20:47.461468Z] Running download-and-extract step. 20:20:47 INFO - Running pre-action listener: _resource_record_pre_action 20:20:47 INFO - Running main action method: download_and_extract 20:20:47 INFO - mkdir: /builds/slave/test/build/tests 20:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:47 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.test_packages.json 20:20:47 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 20:20:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/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 20:20:47 INFO - Downloaded 1199 bytes. 20:20:47 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 20:20:47 INFO - Using the following test package requirements: 20:20:47 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:20:47 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 20:20:47 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 20:20:47 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'jsshell-mac.zip'], 20:20:47 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 20:20:47 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:20:47 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 20:20:47 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 20:20:47 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 20:20:47 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:20:47 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 20:20:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:47 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.common.tests.zip 20:20:47 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:20:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/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 20:20:49 INFO - Downloaded 18842877 bytes. 20:20:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:20:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:20:52 INFO - Return code: 0 20:20:52 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 20:20:52 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')]} 20:20:52 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.common.tests.zip 20:20:52 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/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 20:20:54 INFO - Downloaded 18842877 bytes. 20:20:54 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'] 20:20:54 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 20:20:57 INFO - Return code: 0 20:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:57 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.talos.tests.zip 20:20:57 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/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 20:20:59 INFO - Downloaded 14403317 bytes. 20:20: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'] 20:20: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 20:21:00 INFO - Return code: 0 20:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg 20:21:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 20:21:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 20:21:07 INFO - Downloaded 113063838 bytes. 20:21:07 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg 20:21:07 INFO - mkdir: /builds/slave/test/properties 20:21:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:21:07 INFO - Writing to file /builds/slave/test/properties/build_url 20:21:07 INFO - Contents: 20:21:07 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg 20:21:08 INFO - Running post-action listener: _resource_record_post_action 20:21:08 INFO - Running post-action listener: set_extra_try_arguments 20:21:08 INFO - [mozharness: 2016-08-21 03:21:08.032646Z] Finished download-and-extract step (success) 20:21:08 INFO - [mozharness: 2016-08-21 03:21:08.032789Z] Running populate-webroot step. 20:21:08 INFO - Running pre-action listener: _resource_record_pre_action 20:21:08 INFO - Running main action method: populate_webroot 20:21:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:21:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:21:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:21:08 INFO - '--tppagecycles', 20:21:08 INFO - '7'], 20:21:08 INFO - 'tests': ['tsvgm']}}, 20:21:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:21:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:21:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:21:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:21:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:21:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:21:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['damp', 'tps']}, 20:21:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'tests': ['damp', 'tps']}, 20:21:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['dromaeo_dom']}, 20:21:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:21:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['basic_compositor_video']}, 20:21:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:21:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['a11yr', 20:21:08 INFO - 'ts_paint', 20:21:08 INFO - 'tpaint', 20:21:08 INFO - 'sessionrestore', 20:21:08 INFO - 'sessionrestore_no_auto_restore', 20:21:08 INFO - 'tabpaint']}, 20:21:08 INFO - 'other-e10s': {'tests': ['a11yr', 20:21:08 INFO - 'ts_paint', 20:21:08 INFO - 'tpaint', 20:21:08 INFO - 'sessionrestore', 20:21:08 INFO - 'sessionrestore_no_auto_restore', 20:21:08 INFO - 'tabpaint']}, 20:21:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:21:08 INFO - 'ts_paint', 20:21:08 INFO - 'tpaint', 20:21:08 INFO - 'sessionrestore', 20:21:08 INFO - 'sessionrestore_no_auto_restore', 20:21:08 INFO - 'tabpaint']}, 20:21:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:21:08 INFO - 'ts_paint', 20:21:08 INFO - 'tpaint', 20:21:08 INFO - 'sessionrestore', 20:21:08 INFO - 'sessionrestore_no_auto_restore', 20:21:08 INFO - 'tabpaint']}, 20:21:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['a11yr', 20:21:08 INFO - 'ts_paint', 20:21:08 INFO - 'tpaint', 20:21:08 INFO - 'sessionrestore', 20:21:08 INFO - 'sessionrestore_no_auto_restore', 20:21:08 INFO - 'tabpaint']}, 20:21:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['a11yr', 20:21:08 INFO - 'ts_paint', 20:21:08 INFO - 'tpaint', 20:21:08 INFO - 'sessionrestore', 20:21:08 INFO - 'sessionrestore_no_auto_restore', 20:21:08 INFO - 'tabpaint']}, 20:21:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['tsvgx', 20:21:08 INFO - 'tsvgr_opacity', 20:21:08 INFO - 'tart', 20:21:08 INFO - 'tscrollx', 20:21:08 INFO - 'cart']}, 20:21:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:21:08 INFO - 'tsvgr_opacity', 20:21:08 INFO - 'tart', 20:21:08 INFO - 'tscrollx', 20:21:08 INFO - 'cart']}, 20:21:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'talos_options': ['--disable-e10s'], 20:21:08 INFO - 'tests': ['tp5o']}, 20:21:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'tests': ['tp5o']}, 20:21:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'talos_options': ['--disable-e10s', 20:21:08 INFO - '--xperf_path', 20:21:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:21:08 INFO - 'tests': ['tp5n']}, 20:21:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:21:08 INFO - 'talos_options': ['--xperf_path', 20:21:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:21:08 INFO - 'tests': ['tp5n']}}, 20:21:08 INFO - 'talos.zip': {'path': '', 20:21:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:21:08 INFO - Running post-action listener: _resource_record_post_action 20:21:08 INFO - [mozharness: 2016-08-21 03:21:08.051799Z] Finished populate-webroot step (success) 20:21:08 INFO - [mozharness: 2016-08-21 03:21:08.051937Z] Running create-virtualenv step. 20:21:08 INFO - Running pre-action listener: _resource_record_pre_action 20:21:08 INFO - Running main action method: create_virtualenv 20:21:08 INFO - Creating virtualenv /builds/slave/test/build/venv 20:21:08 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 20:21:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:21:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:21:08 INFO - Using real prefix '/tools/python27' 20:21:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:21:08 INFO - Installing distribute.............................................................................................................................................................................................done. 20:21:11 INFO - Installing pip.................done. 20:21:11 INFO - Return code: 0 20:21:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21: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', 'pip>=1.5'] in /builds/slave/test/build 20:21: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 pip>=1.5 20:21:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:11 INFO - 'HOME': '/Users/cltbld', 20:21:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:11 INFO - 'LOGNAME': 'cltbld', 20:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:11 INFO - 'MOZ_NO_REMOTE': '1', 20:21:11 INFO - 'NO_EM_RESTART': '1', 20:21:11 INFO - 'PAGER': '/bin/cat', 20:21:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:11 INFO - 'PWD': '/builds/slave/test', 20:21:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:11 INFO - 'SHELL': '/bin/bash', 20:21:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:11 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:11 INFO - 'USER': 'cltbld', 20:21:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:11 INFO - 'XPC_FLAGS': '0x0', 20:21:11 INFO - 'XPC_SERVICE_NAME': '0', 20:21:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:12 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 20:21:12 INFO - Cleaning up... 20:21:12 INFO - Return code: 0 20:21:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:12 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 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21:12 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 20:21:12 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 20:21:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:12 INFO - 'HOME': '/Users/cltbld', 20:21:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:12 INFO - 'LOGNAME': 'cltbld', 20:21:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:12 INFO - 'MOZ_NO_REMOTE': '1', 20:21:12 INFO - 'NO_EM_RESTART': '1', 20:21:12 INFO - 'PAGER': '/bin/cat', 20:21:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:12 INFO - 'PWD': '/builds/slave/test', 20:21:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:12 INFO - 'SHELL': '/bin/bash', 20:21:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:12 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:12 INFO - 'USER': 'cltbld', 20:21:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:12 INFO - 'XPC_FLAGS': '0x0', 20:21:12 INFO - 'XPC_SERVICE_NAME': '0', 20:21:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:12 INFO - Downloading/unpacking psutil>=3.1.1 20:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:21:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:21:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:21:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:21:16 INFO - Installing collected packages: psutil 20:21:16 INFO - Running setup.py install for psutil 20:21:16 INFO - building 'psutil._psutil_osx' extension 20:21:16 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 20:21:16 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 20:21:16 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 20:21:16 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 20:21:16 INFO - building 'psutil._psutil_posix' extension 20:21:16 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 20:21:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:21:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:21:17 INFO - ^ 20:21:17 INFO - 1 warning generated. 20:21:17 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 20:21:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:21:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:21:17 INFO - Successfully installed psutil 20:21:17 INFO - Cleaning up... 20:21:17 INFO - Return code: 0 20:21:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:21: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')]} 20:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21: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')]} 20:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:21: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 mozsystemmonitor==0.3 20:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:17 INFO - 'HOME': '/Users/cltbld', 20:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:17 INFO - 'LOGNAME': 'cltbld', 20:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:17 INFO - 'MOZ_NO_REMOTE': '1', 20:21:17 INFO - 'NO_EM_RESTART': '1', 20:21:17 INFO - 'PAGER': '/bin/cat', 20:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:17 INFO - 'PWD': '/builds/slave/test', 20:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:17 INFO - 'SHELL': '/bin/bash', 20:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:17 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:17 INFO - 'USER': 'cltbld', 20:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:17 INFO - 'XPC_FLAGS': '0x0', 20:21:17 INFO - 'XPC_SERVICE_NAME': '0', 20:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:21: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 20:21: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 20:21: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 20:21: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 20:21: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 20:21: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 20:21:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:21:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:21:20 INFO - Installing collected packages: mozsystemmonitor 20:21:20 INFO - Running setup.py install for mozsystemmonitor 20:21:20 INFO - Successfully installed mozsystemmonitor 20:21:20 INFO - Cleaning up... 20:21:20 INFO - Return code: 0 20:21:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:21:20 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')]} 20:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:20 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')]} 20:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:20 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 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21:20 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 20:21:20 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 20:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:20 INFO - 'HOME': '/Users/cltbld', 20:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:20 INFO - 'LOGNAME': 'cltbld', 20:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:20 INFO - 'MOZ_NO_REMOTE': '1', 20:21:20 INFO - 'NO_EM_RESTART': '1', 20:21:20 INFO - 'PAGER': '/bin/cat', 20:21:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:20 INFO - 'PWD': '/builds/slave/test', 20:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:20 INFO - 'SHELL': '/bin/bash', 20:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:20 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:20 INFO - 'USER': 'cltbld', 20:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:20 INFO - 'XPC_FLAGS': '0x0', 20:21:20 INFO - 'XPC_SERVICE_NAME': '0', 20:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:20 INFO - Downloading/unpacking jsonschema==2.5.1 20:21: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 20:21: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 20:21: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 20:21: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 20:21: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 20:21: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 20:21:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:21:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:21:22 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 20:21:22 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 20:21:22 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 20:21:22 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 20:21:22 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 20:21:22 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 20:21:23 INFO - Downloading functools32-3.2.3-2.tar.gz 20:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:21:23 INFO - warning: no files found matching '*.txt' 20:21:23 INFO - no previously-included directories found matching 'build' 20:21:23 INFO - no previously-included directories found matching 'dist' 20:21:23 INFO - no previously-included directories found matching '.git*' 20:21:23 INFO - Installing collected packages: jsonschema, functools32 20:21:23 INFO - Running setup.py install for functools32 20:21:23 INFO - warning: no files found matching '*.txt' 20:21:23 INFO - no previously-included directories found matching 'build' 20:21:23 INFO - no previously-included directories found matching 'dist' 20:21:23 INFO - no previously-included directories found matching '.git*' 20:21:23 INFO - Successfully installed jsonschema functools32 20:21:23 INFO - Cleaning up... 20:21:23 INFO - Return code: 0 20:21:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:23 INFO - 'HOME': '/Users/cltbld', 20:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:23 INFO - 'LOGNAME': 'cltbld', 20:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:23 INFO - 'MOZ_NO_REMOTE': '1', 20:21:23 INFO - 'NO_EM_RESTART': '1', 20:21:23 INFO - 'PAGER': '/bin/cat', 20:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:23 INFO - 'PWD': '/builds/slave/test', 20:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:23 INFO - 'SHELL': '/bin/bash', 20:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:23 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:23 INFO - 'USER': 'cltbld', 20:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:23 INFO - 'XPC_FLAGS': '0x0', 20:21:23 INFO - 'XPC_SERVICE_NAME': '0', 20:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:24 INFO - Downloading/unpacking blobuploader==1.2.4 20:21:24 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 20:21:24 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 20:21:24 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 20:21:24 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 20:21:24 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 20:21:24 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 20:21:26 INFO - Downloading blobuploader-1.2.4.tar.gz 20:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:21:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:21:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:21:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:21:27 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 20:21:27 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 20:21:27 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 20:21:27 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 20:21:27 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 20:21:27 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 20:21:27 INFO - Downloading docopt-0.6.1.tar.gz 20:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:21:27 INFO - Installing collected packages: blobuploader, requests, docopt 20:21:27 INFO - Running setup.py install for blobuploader 20:21:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:21:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:21:27 INFO - Running setup.py install for requests 20:21:28 INFO - Running setup.py install for docopt 20:21:28 INFO - Successfully installed blobuploader requests docopt 20:21:28 INFO - Cleaning up... 20:21:28 INFO - Return code: 0 20:21:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:28 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 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21:28 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 20:21:28 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 20:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:28 INFO - 'HOME': '/Users/cltbld', 20:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:28 INFO - 'LOGNAME': 'cltbld', 20:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:28 INFO - 'MOZ_NO_REMOTE': '1', 20:21:28 INFO - 'NO_EM_RESTART': '1', 20:21:28 INFO - 'PAGER': '/bin/cat', 20:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:28 INFO - 'PWD': '/builds/slave/test', 20:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:28 INFO - 'SHELL': '/bin/bash', 20:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:28 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:28 INFO - 'USER': 'cltbld', 20:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:28 INFO - 'XPC_FLAGS': '0x0', 20:21:28 INFO - 'XPC_SERVICE_NAME': '0', 20:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:21:28 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-fYjwSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2I1aLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-y7X5k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-08mZ3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-tGkiga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZOjLj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-IeUbrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-jtAdiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-VntCWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-uf6w_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:21:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-TrWF13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:21:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-iNlI1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:21:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-h32Cm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:21:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-KN33Qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-y9MChD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:21:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-BlGz8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:21:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-4jSIxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:21:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:21:30 INFO - Running setup.py install for manifestparser 20:21:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:21:30 INFO - Running setup.py install for mozcrash 20:21:31 INFO - Running setup.py install for mozdebug 20:21:31 INFO - Running setup.py install for mozdevice 20:21:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Running setup.py install for mozfile 20:21:31 INFO - Running setup.py install for mozhttpd 20:21:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Running setup.py install for mozinfo 20:21:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Running setup.py install for mozInstall 20:21:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:21:31 INFO - Running setup.py install for mozleak 20:21:32 INFO - Running setup.py install for mozlog 20:21:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:21:32 INFO - Running setup.py install for moznetwork 20:21:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:21:32 INFO - Running setup.py install for mozprocess 20:21:32 INFO - Running setup.py install for mozprofile 20:21:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:21:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:21:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:21:32 INFO - Running setup.py install for mozrunner 20:21:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:21:32 INFO - Running setup.py install for mozscreenshot 20:21:33 INFO - Running setup.py install for moztest 20:21:33 INFO - Running setup.py install for mozversion 20:21:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:21:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:21:33 INFO - Cleaning up... 20:21:33 INFO - Return code: 0 20:21:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:33 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 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21:33 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 20:21:33 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 20:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:33 INFO - 'HOME': '/Users/cltbld', 20:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:33 INFO - 'LOGNAME': 'cltbld', 20:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:33 INFO - 'MOZ_NO_REMOTE': '1', 20:21:33 INFO - 'NO_EM_RESTART': '1', 20:21:33 INFO - 'PAGER': '/bin/cat', 20:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:33 INFO - 'PWD': '/builds/slave/test', 20:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:33 INFO - 'SHELL': '/bin/bash', 20:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:33 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:33 INFO - 'USER': 'cltbld', 20:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:33 INFO - 'XPC_FLAGS': '0x0', 20:21:33 INFO - 'XPC_SERVICE_NAME': '0', 20:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:21:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-AmCToY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:21:33 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)) 20:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:21:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Lik_1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:21:33 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)) 20:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:21:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-YhvhoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:21:33 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)) 20:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:21:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-O9pfkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pqzRut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-XysSva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-7c1MOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-rBZEir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-wtLzB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-LtalwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pi4fgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-KKFDIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Ld4w0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:21:34 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)) 20:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:21:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-RqKv1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hYuOL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:35 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)) 20:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:21:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-yPeMfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:21:35 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)) 20:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:21:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pDvt9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:21:35 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)) 20:21:35 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)) 20:21:35 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)) 20:21:35 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)) 20:21:35 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)) 20:21:35 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)) 20:21:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:21:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:37 INFO - Downloading blessings-1.6.tar.gz 20:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:21:37 INFO - Installing collected packages: blessings 20:21:37 INFO - Running setup.py install for blessings 20:21:38 INFO - Successfully installed blessings 20:21:38 INFO - Cleaning up... 20:21:38 INFO - Return code: 0 20:21:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:21:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:21:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:21:38 INFO - Reading from file tmpfile_stdout 20:21:38 INFO - Current package versions: 20:21:38 INFO - blessings == 1.6 20:21:38 INFO - blobuploader == 1.2.4 20:21:38 INFO - docopt == 0.6.1 20:21:38 INFO - functools32 == 3.2.3-2 20:21:38 INFO - jsonschema == 2.5.1 20:21:38 INFO - manifestparser == 1.1 20:21:38 INFO - mozInstall == 1.12 20:21:38 INFO - mozcrash == 0.17 20:21:38 INFO - mozdebug == 0.1 20:21:38 INFO - mozdevice == 0.48 20:21:38 INFO - mozfile == 1.2 20:21:38 INFO - mozhttpd == 0.7 20:21:38 INFO - mozinfo == 0.9 20:21:38 INFO - mozleak == 0.1 20:21:38 INFO - mozlog == 3.3 20:21:38 INFO - moznetwork == 0.27 20:21:38 INFO - mozprocess == 0.23 20:21:38 INFO - mozprofile == 0.28 20:21:38 INFO - mozrunner == 6.12 20:21:38 INFO - mozscreenshot == 0.1 20:21:38 INFO - mozsystemmonitor == 0.3 20:21:38 INFO - moztest == 0.7 20:21:38 INFO - mozversion == 1.4 20:21:38 INFO - psutil == 3.1.1 20:21:38 INFO - requests == 1.2.3 20:21:38 INFO - wsgiref == 0.1.2 20:21:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:21:38 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')]} 20:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:38 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')]} 20:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:38 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 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21:38 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 20:21:38 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 20:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:38 INFO - 'HOME': '/Users/cltbld', 20:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:38 INFO - 'LOGNAME': 'cltbld', 20:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:38 INFO - 'MOZ_NO_REMOTE': '1', 20:21:38 INFO - 'NO_EM_RESTART': '1', 20:21:38 INFO - 'PAGER': '/bin/cat', 20:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:38 INFO - 'PWD': '/builds/slave/test', 20:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:38 INFO - 'SHELL': '/bin/bash', 20:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:38 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:38 INFO - 'USER': 'cltbld', 20:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:38 INFO - 'XPC_FLAGS': '0x0', 20:21:38 INFO - 'XPC_SERVICE_NAME': '0', 20:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 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)) 20:21:38 INFO - Cleaning up... 20:21:38 INFO - Return code: 0 20:21:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:21:38 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')]} 20:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:38 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')]} 20:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:21:38 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 0x103a55a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aea618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c886b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, '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.ppuP14aCJG/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.YQwmHc25RI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 20:21:38 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 20:21:38 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 20:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:38 INFO - 'HOME': '/Users/cltbld', 20:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:38 INFO - 'LOGNAME': 'cltbld', 20:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:38 INFO - 'MOZ_NO_REMOTE': '1', 20:21:38 INFO - 'NO_EM_RESTART': '1', 20:21:38 INFO - 'PAGER': '/bin/cat', 20:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:38 INFO - 'PWD': '/builds/slave/test', 20:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:38 INFO - 'SHELL': '/bin/bash', 20:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:21:38 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:38 INFO - 'USER': 'cltbld', 20:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:38 INFO - 'XPC_FLAGS': '0x0', 20:21:38 INFO - 'XPC_SERVICE_NAME': '0', 20:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:21:38 INFO - Cleaning up... 20:21:38 INFO - Return code: 0 20:21:38 INFO - Running post-action listener: _resource_record_post_action 20:21:38 INFO - Running post-action listener: _start_resource_monitoring 20:21:39 INFO - Starting resource monitoring. 20:21:39 INFO - [mozharness: 2016-08-21 03:21:39.020367Z] Finished create-virtualenv step (success) 20:21:39 INFO - [mozharness: 2016-08-21 03:21:39.021360Z] Running install step. 20:21:39 INFO - Running pre-action listener: _resource_record_pre_action 20:21:39 INFO - Running main action method: install 20:21:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:21:39 INFO - Reading from file tmpfile_stdout 20:21:39 INFO - Detecting whether we're running mozinstall >=1.0... 20:21:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:21:39 INFO - Reading from file tmpfile_stdout 20:21:39 INFO - Output received: 20:21:39 INFO - Usage: mozinstall [options] installer 20:21:39 INFO - Options: 20:21:39 INFO - -h, --help show this help message and exit 20:21:39 INFO - -d DEST, --destination=DEST 20:21:39 INFO - Directory to install application into. [default: 20:21:39 INFO - "/builds/slave/test"] 20:21:39 INFO - --app=APP Application being installed. [default: firefox] 20:21:39 INFO - mkdir: /builds/slave/test/build/application 20:21:39 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'] 20:21:39 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 20:22:13 INFO - Reading from file tmpfile_stdout 20:22:13 INFO - Output received: 20:22:13 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 20:22:13 INFO - Running post-action listener: _resource_record_post_action 20:22:13 INFO - [mozharness: 2016-08-21 03:22:13.864597Z] Finished install step (success) 20:22:13 INFO - [mozharness: 2016-08-21 03:22:13.864727Z] Running run-tests step. 20:22:13 INFO - Running pre-action listener: _resource_record_pre_action 20:22:13 INFO - Running main action method: run_tests 20:22:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:22:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:22:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:22:13 INFO - 2016-08-20 20:22:13.878 screenresolution[1637:8294] starting screenresolution argv=screenresolution get 20:22:13 INFO - 2016-08-20 20:22:13.881 screenresolution[1637:8294] Display 0: 1600x1200x32@60 20:22:13 INFO - 2016-08-20 20:22:13.887 screenresolution[1638:8296] starting screenresolution argv=screenresolution list 20:22:13 INFO - Available Modes on Display 0 20:22:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:22:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:22:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:22:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:22:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:22:14 INFO - Intel Iris: 20:22:14 INFO - Chipset Model: Intel Iris 20:22:14 INFO - Type: GPU 20:22:14 INFO - Bus: Built-In 20:22:14 INFO - VRAM (Dynamic, Max): 1536 MB 20:22:14 INFO - Vendor: Intel (0x8086) 20:22:14 INFO - Device ID: 0x0a2e 20:22:14 INFO - Revision ID: 0x0009 20:22:14 INFO - Displays: 20:22:14 INFO - Display: 20:22:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:22:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:22:14 INFO - Display Serial Number: 1600x1200 60 20:22:14 INFO - Main Display: Yes 20:22:14 INFO - Mirror: Off 20:22:14 INFO - Online: Yes 20:22:14 INFO - Rotation: Supported 20:22:14 INFO - Return code: 0 20:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:22:14 INFO - Python 2.7.3 20:22:14 INFO - Return code: 0 20:22:14 INFO - grabbing minidump binary from tooltool 20:22: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')]} 20:22:14 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 0x1039b9d30>, '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 0x10371edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039b1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:22:14 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 20:22:14 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 20:22:14 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 20:22:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:22:14 INFO - Return code: 0 20:22:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:22:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:22:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:22:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:22:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:22:14 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', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 20:22:14 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 https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 20:22:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppuP14aCJG/Render', 20:22:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:14 INFO - 'HOME': '/Users/cltbld', 20:22:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:22:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PAGER': '/bin/cat', 20:22:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:22:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQwmHc25RI/Listeners', 20:22:14 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 20:22:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - 'XPC_FLAGS': '0x0', 20:22:14 INFO - 'XPC_SERVICE_NAME': '0', 20:22:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:14 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', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 20:22:14 INFO - mozversion application_buildid: 20160720030201 20:22:14 INFO - mozversion application_changeset: ed8e23b5e0c7b739e61173bb180cf3410a306679 20:22:14 INFO - mozversion application_display_name: Nightly 20:22:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:22:14 INFO - mozversion application_name: Firefox 20:22:14 INFO - mozversion application_remotingname: firefox 20:22:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:22:14 INFO - mozversion application_vendor: Mozilla 20:22:14 INFO - mozversion application_version: 50.0a1 20:22:14 INFO - mozversion platform_buildid: 20160720030201 20:22:14 INFO - mozversion platform_changeset: ed8e23b5e0c7b739e61173bb180cf3410a306679 20:22:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:22:14 INFO - mozversion platform_version: 50.0a1 20:22:14 INFO - using testdate: 1471749734 20:22:14 INFO - actual date: 1471749734 20:22:14 INFO - starting webserver on 'localhost:49261' 20:22:14 INFO - SUITE-START | Running 2 tests 20:22:14 INFO - TEST-START | dromaeo_css 20:22:14 INFO - operating with platform_type : mac_ 20:22:14 INFO - Initialising browser for dromaeo_css test... 20:22:14 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpKq_3gc/profile http://localhost:49261/getInfo.html) 20:22:17 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 20:22:17 INFO - PROCESS | 1648 | colorDepth:24 20:22:17 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 20:22:17 INFO - PROCESS | 1648 | __metrics 20:22:17 INFO - PROCESS | 1648 | 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" 20:22:17 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:22:17 INFO - TEST-INFO | 1648: exit 0 20:22:17 INFO - Browser initialized. 20:22:17 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:17 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpKq_3gc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:22:29 INFO - PROCESS | 1652 | RSS: Main: 245645312 20:22:29 INFO - PROCESS | 1652 | 20:23:20 INFO - PROCESS | 1652 | 1471749800871 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] 20:25:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49261/tests/dromaeo/cssquery-ext.html) 20:25:33 INFO - PROCESS | 1652 | RSS: Main: 388747264 20:25:33 INFO - PROCESS | 1652 | 20:28:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-ext.html (next: http://localhost:49261/tests/dromaeo/cssquery-jquery.html) 20:28:37 INFO - PROCESS | 1652 | RSS: Main: 381992960 20:28:37 INFO - PROCESS | 1652 | 20:31:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49261/tests/dromaeo/cssquery-mootools.html) 20:31:40 INFO - PROCESS | 1652 | RSS: Main: 372187136 20:31:40 INFO - PROCESS | 1652 | 20:34:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49261/tests/dromaeo/cssquery-prototype.html) 20:34:43 INFO - PROCESS | 1652 | RSS: Main: 374874112 20:34:43 INFO - PROCESS | 1652 | 20:37:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49261/tests/dromaeo/cssquery-yui.html) 20:37:47 INFO - PROCESS | 1652 | RSS: Main: 341241856 20:37:47 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49261/tests/dromaeo/cssquery-yui.html (next: http://localhost:49261/tests/dromaeo/cssquery-dojo.html) 20:40:51 INFO - PROCESS | 1652 | RSS: Main: 386236416 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | __start_tp_report 20:40:51 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 20:40:51 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 20:40:51 INFO - PROCESS | 1652 | |i|pagename|runs| 20:40:51 INFO - PROCESS | 1652 | |0;dojo.html;3306.9007929762083;3314.3205642843213;3344.665533446655;3366.3603915256117;3397.3375191837586;5537.750801213945;5541.528970037547;5613.494785469307;5628.549716022719;5668.348139963905;4529.954700452994;5581.8604534886645;5699.059655156904;5704.286964129484;5747.856303592413;4132.359484279941;4211;5104.183330667094;5127.897442051157;5221.973890130544;4642.97678511607;5245.6852588844795;5250.737463126848;5264.710440925747;5310.15037593985;5084.9745751271;5158.993996170748;5171.844844654666;5186.1442861927735;5189.8183563575285;5098.6176036797015;5151.6908985461;5172.974135129338;5178.896422071537;5191.169412893919;5101.15830887903;5109.20807274873;5132.28148059272;5178.637495375326;5237.057329680656;5645.2096706461125;5662.320521537401;5719.571032172562;5731.340895796995;5752.482276595085;5983.730732117066;6054.72753726079;6201.658908760016;6203.503719702413;6220.409061139185;6171.13604095427;6186.257649082094;6224.657643829588;6227.532935029891;6265.8120256392385;5768.567357448208;5774.133879918004;5780.017397042555;5784.71076446176;5785.508231800344;6121.788327166048;6133.212252453595;6271.122042913996;6364.140840986408;6368.745250190033;6036.245469316335;6095.087764868071;6101.840650276433;6106.542009349317;6109.326365330526;6203.2245969253845;6212.347703491158;6216.968915155486;6222.0355844844125;6226.657533835682;5956;6109.206538480613;6120.326764055951;6155.507559395238;6168.074788781629;5948.10778383246;6106.755729770759;6130.3369889931655;6132.724027418777;6148.354422785543;4878.853634390974;4882.902341953212;4895.632827537949;4919.3604831371695;4926.531979461945;4393.275109606879;4402.537733537996;4410.625096866738;4450.955490445054;4460.442444694413;5508.9724551376985;5594.468525490072;5604.103343465026;5611.943880561143;5626.971865140648;6085.391460853879;6123.28262147438;6138.815835524942;6145.709401025834;6148.616561273767;4768.237082066922;5482.985647655197;5598;5600.943990560124;5608.859778505488;5332.33345831771;5344.652597581223;5346.465353464622;5359.866003349947;5406.432324605938;6320.462760665303;6372.522060845455;6386.712597933104;6391.265004524539;6396.0405939109505;6074.878502429839;6107.144999699956;6238.968805155945;6264.373562643699;6273.592216505913;6255.624662520263;6327.778527751507;6329.398707122816;6337.334579869047;6350.904736428864;6125.073522814525;6140.495578583275;6145.371476558805;6146.0473626587955;6150.231221097363;5737.540996720336;6186.907196391968;6186.969065154645;6191.349908259568;6220.782272620437;5777.39337369587;5844.035734103825;5924.733386997509;6004.459598636144;6007.519398448201;5948.702564871825;6016.488598469179;6054.122152287806;6081.452669259788;6083.726232319645;4842.564169224786;5853.2976042875125;5856.326522449803;5859.062549991981;5891.705414729161;5849.532037436909;5906.0255057914965;6008.639481631116;6056.788012419544;6104.481119104926;5798.85502862415;5834.095715164156;5849.853753656193;5925.081612350093;5970.462658360769 20:40:51 INFO - PROCESS | 1652 | |1;ext.html;481.88675661219605;497.59197458084384;531.2297169104797;543.9203181684356;563.5547917145456;30428;32068.35863282733;32077.037688869314;32080.83959580202;42611.78694106533;44600.776996114975;45229.54770452303;45270.547294527045;45310.86722831931;45364.54635453645;9721.513924303774;9883.308168428213;9906.950465247664;9920.702378928643;9932.404055756642;8746.912530874673;8935.821283574325;9615.807683846318;9714.028597140265;9767.023297670212;11844.822327665092;12080.577179798709;12093;12337.568185113523;12344.321062341567;12110;12205.877941220608;12206.08454365926;12212.938935305267;12216.2670239785;8698.695545655935;11584.942075289653;11913.583024594183;11974.22167559106;11987.981021613174;40231;42877;43017.56982430167;43126.137477250595;43189;3577.3918433866306;3608.9639103608893;3635.8545658173643;4003;4133.565975572552;28577.57133642997;29589.408211835856;30938.91713790029;32598.696052157884;33354.83322583396;3209.4543927532377;3386.136535183537;3393.490976353542;3426.7601267911255;3427.4516077427493;465983;469692;470613;470962;473003;63471;63967;64074.6796266025;64146.679266603365;64266.67866660637;66084.33915660878;66130.66934665295;66243.66878165578;66351.66824165848;66390;13941.372638231303;16545.66908661844;16795.328186872634;16831.74752378715;16855.241514131892;12883.54907578249;12930.224186548838;12952.481900723866;12952.481900724055;12969.481220751253;15368.923155384151;15587.29857156408;16169.919150404172;17338.91330543339;17869.821301786815;6384.201974753156;6501.252355979028;6544.7382104716235;6546.13435170974;6580.8354791130605;818.3412353055847;909.8635204719212;964.4502633498979;996.7259003773875;1038.8234000219982;27387.86306068457;30619.38761224763;30772.846135769178;31202;31399.84300078485;996.6710985374809;1064.7125276175543;1112.5327362507767;1118.1334465788916;1227.4415141110774;16312.673746525003;16327.673446531004;16372.754408683879;16517.008979461272;16597.604867495465;591459;594440;598514;600059;607429;53341.733291333294;53593.46406535885;59683.50791230254;60682;60892;1206.5294535131306;1405.4308005257888;1441.1209162410712;1441.358595425026;1441.452248145698;27190.320241994108;27339.726602733717;27600.861995689895;27754.306142346602;27802.582961255193;53896.73051634717;58901.70549147227;59859.80280394455;59877.40122598718;62694.68652656708;638624;640307;662963;664406;666760;25957.740422595532;25959.091431799796;26311;26541.203763887115;26580;47997.520024799305;48042.27936581024;48510.787230318114;48872.53382398534;49296;39847.80076099601;39860.601393985686;40866.63026753339;41280.38079428871;41646.750597482125;39411.60588394079;40109.598904010585;40663.59336406598;40852.77441676754;40978.79510602428;35535.46696799602;35768;36696.449553257255;36852.81573592115;36942.26115477729;31971.840140799148;34823.84082235783;35347.64652353444;35563.82218088893;36411.90233367384;24468.26595202147;25602.61596076023;26401.339966500993;26488.470230594896;26553.867230663724 20:40:51 INFO - PROCESS | 1652 | |2;jquery.html;342.61583203856424;353.06437939460443;360.94766258892463;377.1965713031243;404.87651266363747;15740.449084282052;17073.48779536614;21092.261770838024;21244.99999999996;21378.89310553449;15471.69056618867;21508.892455537738;21799.237026704068;21847;22284;5308.9469105308835;5899.5870289079785;6109.14471973924;6176.876462470748;6185;6060.727267272983;6157.661328626924;6162;6164.660943648098;6182.690865456731;5722.256106706143;6006;6058.273007239138;6108.633481991094;6134.171886795292;5066.771995260204;6075.605085669417;6078.787242446515;6083.8174854754425;6093.055576385667;4993.226049962262;5626.677730733274;5820.35976042635;5842.269716285465;5880.147378630075;22377.216797412013;23110.884445577834;25384.238472845846;26172.8691356542;26348.20955371342;2249.6175650139517;2321.907123715066;2362.055177928825;2878.5826055221874;2954.4829654810537;9557.330986830924;10487.842682359771;10614.310069845513;12829.743405131938;16451.177441127897;1812.9456116316533;1841.9355322563647;1895.810418958107;1908.6469003234447;1949.1716020691292;96498;111329.88670113277;114893.4255328718;116410.41794791057;136536.88483612303;8445.1977062179;9608.231341492663;9888.357256778287;10187.7453063674;10224.335418197794;12461.314627695563;12537.937310313573;12560.932320752838;12562.994960403146;12569.434375453127;7851.097123830833;8107.1892810718455;8159.836803263902;8198.959005205055;8224.506529608243;8435.409521333571;8530.317574594017;8536.914630853613;8696.956515217384;8745.868811967826;3916.3146449371247;3916.433662820458;3921.627445392683;3933.1347103637154;3997.181282516448;1707.2658756734475;1934.332655233942;1939.6217737541044;1952.1215453046186;1953.8436925045962;340.99147521312165;365.48466662006444;403.6972270796902;861.1087524412328;872.2455076358904;11771.646850594496;11827.822582661267;11932.821007684892;12267.263964162179;12309.26144431337;432.1874875234539;436.80343845269755;437.96277316427825;444.24700133274433;451.1405772004337;6068.696565171724;6152.033705397195;6158.107074474176;6231.719572619243;6260.686965651699;608399;620660;621376;622873;629744;92742;141742;143600;147333.26333368264;151747;380.2622911551581;418.0823093310155;426.8314015963677;431.29483294813207;432.0904496035862;9818.312718109664;10135.64525241613;11064.612738554433;11094;11437.02785263262;57264;57409;57548.56128596651;59078.70460647669;59624.70187649034;66627.33372666406;80066.56933724672;81670.59164704138;83394.58302708447;83692;7116.750913717995;7831.316267002622;7881.502514522223;8105.8784118239455;8175.836483270183;11063;13131.671708207372;13183.802242966554;13671;13707.109037912503;11263.211575189658;11725.296482211095;11849.933505984503;11880.287182769061;11889.048876089719;10184.287099902936;11214.439278036229;11292.887071129184;11792.23350482216;11833.585824496102;9638.518074096408;9650.131488166098;11008.119350452105;11707.2975621463;11929.045676346044;6059.54570902989;7309.086364204474;7438.404927605887;7507.174210836694;7564.621768911422;9456.621735130519;11039.779204416027;11240;11616.419179041182;11659.125565582784 20:40:51 INFO - PROCESS | 1652 | |3;mootools.html;1568.8431156884312;1618.8704903607713;1800.6308706715117;1910.5987742574068;1912.9043547822605;8660.696875609356;9077.137671921157;9081.31890108242;9092.863607045883;9099.45403275803;7942.086660034083;8203.507789532632;8481.491110533372;8759.781005474866;8862.64549418024;2327.8836058197107;2392.6411038344213;2396.1134380279323;2397.4845408237225;2409.036385445827;2340.2394221877958;2358.7287461941837;2377.95244095118;2381.92854214373;2386.319898828834;2086.2802333195023;2168.2086038595876;2320.4198950262435;2382.559226543095;2385.4751954569965;2369.2418426103654;2382.130522359335;2402.771736685012;2404.0504000919714;2405.506871091422;2379.5954687703133;2382.9642555361684;2386.642003699459;2387;2387.4866903615807;8799.384043116985;8887.466751994836;8898.332625053146;8898.86651700225;8902.643894244222;5205.687658740488;5279.47205279469;5340.893182136374;5345.518903298722;5369.48989845964;3479.1823921378455;3525.8589656413715;3673.485712000322;3678.1172518595613;3698.14942563212;1041.9739506512321;1081.291753901196;1089.5702583239342;1105.6296140792763;1106.778644271141;11599.246049006788;11698.47356868943;11839.467223974942;11853.940730296294;11883.049356051495;4518.9548104518535;4610.216263235258;4779.378680771547;4799.160146974265;4806.591439727592;10180.847287290697;10185.898141018642;10201.897981020094;10210.285280030475;10229.335093218917;5301.5493683037375;5305.230741542455;5307.124324486494;5313.521783039545;5316.282301889273;4645.279981602857;4768.117898188846;4787.40157480315;4837.95162048375;4844.346013288161;3024.9848750756105;3650.945235821465;3684.373656478405;3713.795741234204;3735.6264373562426;883.5758835758743;1014.5130337438071;1054.847047178155;1056.1656291529605;1066.5520481397832;769.8652735771218;817.6116344736322;876.4828751036791;877.1798368525322;922.9538523073819;5858.677772722456;5883.7058147092475;5980.22257106582;6036.5170786336985;6201.751929922752;935.8128374325026;958.5973890965811;975.878015248094;983.3830167364299;1004.924630652704;3527.312174126072;3914.393269043303;3957.406389041667;3991.3015222336558;4005.339119045383;9121.31590130743;10425.165986721044;10942;10951.123910087173;10968.067714244218;9860.260480463994;10292.588296468208;10752.677419677422;10887.618933337295;10904.07315378186;7364.631768411665;8001.399895007898;8083.514989100673;8229.588520573947;8232.382571307176;5938.6436813791315;6015.939840601713;6172.845678858064;6193.194884664965;6202.534809889366;9500.334976551852;10119.79760404773;10131.544080516542;10148.949255253676;10149.188064954933;9994.20046396272;10122.291439599157;10185.185185185315;10189.84715229287;10234.795304093728;7476.663550140365;7529.171791103084;7613.467057305936;7659.4255430841795;7670.079590448959;8142.348612110898;8386.496810191407;8386.790330241549;8406.621702023545;8407.150426906072;7419.851602968019;7509.699612015458;7523.3229009389415;7586.165521792488;7678;6640.003999400129;7090.290970902868;7388.519746216479;7471.775846724607;7472;6301.369863013845;6817.897731533932;6977.092977912839;7054.541454805421;7071;4852.878678033077;4876.536729010738;4937.753112344297;4949.034938187019;5057.266696329085;4907.067657145131;4927.260910863399;4985.326980857538;5010.448850626355;5035.773390197523 20:40:51 INFO - PROCESS | 1652 | |4;prototype.html;282.5860114931625;295.812159278858;303.541652105321;308.82088388734536;309.6918566026804;4330.610245077943;4881.755912204393;5224.29472021277;5227.581793456524;5235.450277720842;4375.934360984587;4932.334134891788;4969.900601987958;5149.742512874351;5179.249008893708;3818.6754125899333;3951.6641085507695;3999.140184860269;4015.6586690131376;4044.716869819099;3447.9828450607133;3932.6853851691794;4024.215278020787;4028.476298081245;4078.5921407859273;3622.2574372253757;3774.6602805747475;3966.821493032806;3991.800409979504;3998.1603863188766;3956.8021598920227;3993.4608827808165;4006.8981030216632;4009.418634298025;4022.9138132704297;3071.800332978349;3291.572095627553;3474.2183008823067;3477.9130521736906;3481.9477707834403;4165.75005499671;4885.511448855122;5094.7197904115255;5146.897062058738;5151.665141765773;4301.698881078326;4505.229283990322;4518.819247230107;4574.1309151261485;4589.35748995141;4227.682923780698;5447.268620994591;5473.06957817172;5496.532794712454;5567.659404357353;2999.7000299969827;3019.803712758686;3626.8937973917946;3824.063104539378;3829.291581057513;5489.807856725035;5637.971810140964;5646.463585959327;5725;5730.4269573042775;3252.3007553376137;3871.1483473635753;3875.534935807721;3881.86413475527;3883.990162557699;3541.6458354164893;3729.589745128088;3921.8039098044983;3947.1710940702774;3985.80070996449;4334.566543345641;4385.489175593718;4403.5376285489565;4555.589996900295;4560.452745670541;5482.80810171642;5511.3937466878615;5511.696856672841;5549.694766787866;5557.722113894289;1303.419978109732;1320.6236222676491;1326.4694122350943;1345.6164992976956;1345.663584103974;735.8050116719073;773.3465221887496;858.5321000054985;870.7866572689605;923.3813345058827;5142.871428214325;5143.305653736773;5147.1507201312115;5153.433122356538;5272.574615676832;5665.971670141623;5687.232223649837;5708.857278568069;5744.913826292609;5785.1611516329895;4926.901461970812;5111.437741848394;5132.640715149903;5143.228515722672;5192.792288308501;2939.8383088930314;2950.583719814501;2960.86676099576;2965.9703402965692;2970.420767950272;4850;5794.362620111784;5812.7502586943165;5855.765769369177;5865.736041878125;4648.48866624671;5729.742161602738;5755.827325180251;5784.136852258075;5784.623999440023;4867.537583929521;4878.780454879539;5602.355729091207;5849.6782676952325;5974.9402505974385;5380.408155103014;5576.944230557805;5605.290386432014;5709.857253568694;5738.598298119258;5548.583856210881;5563.805266815642;5565.30433695788;5583.776648933997;5597.524210442158;4754.976225118852;5771.249737534094;5848.912266315923;5852.4440178183;5911.615744976562;3377.8648854046546;3707.8146092695797;3776.773393596393;3808.1812410331913;3851.6533511984057;5097.847064588068;5575.498205161555;5589.580781441489;5613.242212301287;5850.882982340415;4664.510226426312;5419.58828321409;5435;5506.339239291311;5558.888822223452;4495.494009506721;5120.257562653469;5159.742012899265;5429.6199266051;5454.10007348783;4528.841490547815;4560.657950653781;4809.663323567317;5093.414257360303;5410.240777203576;5018.548330650113;5335.759890805;5394.757235924314;5444.237806706985;5538.363088244903;4496.775161241991;5432.348118225838;5454.618176727591;5491.462390530658;5497.835064948058 20:40:51 INFO - PROCESS | 1652 | |5;yui.html;653.5490511547032;740.5038624121838;755.882838160085;760.2321655128319;783.1111688233855;15503.379864805409;15705.450309239179;16448.917755411207;16451.177441127955;16452.17739113044;14941;15139.167345796004;15214.391424343014;15232.086074835517;15264.16047117408;680.6426626021329;685.5441131647474;742.907136607924;894.6868595991416;918;718.242254421585;884.4781578868466;903.9005709371963;915.6612053540166;917.4311926605513;936.7611259128912;946.2619157057471;948.4830767231875;949.2121539122512;953.4755884263662;949.3829011142743;950.8098380323963;951.524237881063;953.3374304858127;953.5565961827749;561.6658088437414;706.3783870194247;706.4948561778302;708.4792677382171;713.2225873797586;14443.855561444356;14862.70274594502;14870.925645371704;14925.776113358308;14942.477013304591;14356.569302920929;14546.39989601143;14835.332410041574;14903.701925961528;14920;11770.41147942608;11853;12079.214851034654;12082.758344833142;12394.504219831197;3914.549826769901;3920.7843568603716;3983.820728067244;4021.7989100545146;4086.0193553547233;103452;111018;115100.84899150985;115807;116855;8792.736217913473;11094.334339939629;11180.608678696206;11194.160437967184;11567.942160289145;17111.74332385015;22055;22641.88679056627;22754.31737047891;22756.4310892229;8299.253067223979;8299.29455996235;8408.453450525696;8420.073791882887;8544.017437994586;4563;4574.153781550423;4656.767161641905;4700.835470758507;4779.235322348477;3525.242072954276;3616;3631.8002509862213;3658.5243918290453;3661.0481274868725;671.150964157156;672.579637726421;675.598019178588;677.8000489855406;680.2279412866433;553.1923391847866;569.5927411900511;629.9464545513591;681.2404169351138;690.2166041542885;9200.90799092014;9894.802103958025;10015.14871235953;10076.395416275047;10104.343217690826;619.9752009919642;665;689.5483458334799;691.6230654293422;697.8534507753327;4198.28629133042;4841.443234028062;4865.635077369211;4882.414110306786;4883.536064073907;157579;158003.41996580118;164948.17525912295;165233.17383413247;170474;108365;110941;113279;113649;121639;83904.58047709722;103218.2586306015;106156.75372985148;107297;108289;10096.19230461576;10255.641052563124;10260.281780275309;10263.58945642166;10295.691129266133;59168;59637.10544341925;59874.70062649659;61457.77084458375;62915;66425;85575.71636425334;90692;91472.54263728639;92592.07407926104;13159.407826648016;13675.589732308046;13678.247696376397;13898;14081.788773168206;14614.488492902698;14734.263286835401;14849.851501484847;15086.321115549606;15207.923960380127;14374.568762937128;14558.199299038655;14602.269886505845;14707.338169782171;14905.329260183102;13958.720825583228;14382.3527941245;14630.487932922297;14718.852811472176;14882.404703812159;11800.3509806958;13683.179009259078;13732.931335343259;13932.860671393157;14129.010969232466;11400.543978240778;11657;11771.646850594496;11890.940545297219;12039.699007524532;9700.459332343786;10995.835062474229;11467.31196128235;11760.411979400824;11780.291857680417 20:40:51 INFO - PROCESS | 1652 | __end_tp_report 20:40:51 INFO - PROCESS | 1652 | __start_cc_report 20:40:51 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1492 20:40:51 INFO - PROCESS | 1652 | __end_cc_report 20:40:51 INFO - PROCESS | 1652 | __startTimestamp1471750851346__endTimestamp 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | ------- Summary: start ------- 20:40:51 INFO - PROCESS | 1652 | Number of tests: 6 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5659.46 Median:5849.85 stddev:662.89 (11.3%) stddev-sans-first:638.71 20:40:51 INFO - PROCESS | 1652 | Values: 3306.9 3314.3 3344.7 3366.4 3397.3 5537.8 5541.5 5613.5 5628.5 5668.3 4530.0 5581.9 5699.1 5704.3 5747.9 4132.4 4211.0 5104.2 5127.9 5222.0 4643.0 5245.7 5250.7 5264.7 5310.2 5085.0 5159.0 5171.8 5186.1 5189.8 5098.6 5151.7 5173.0 5178.9 5191.2 5101.2 5109.2 5132.3 5178.6 5237.1 5645.2 5662.3 5719.6 5731.3 5752.5 5983.7 6054.7 6201.7 6203.5 6220.4 6171.1 6186.3 6224.7 6227.5 6265.8 5768.6 5774.1 5780.0 5784.7 5785.5 6121.8 6133.2 6271.1 6364.1 6368.7 6036.2 6095.1 6101.8 6106.5 6109.3 6203.2 6212.3 6217.0 6222.0 6226.7 5956.0 6109.2 6120.3 6155.5 6168.1 5948.1 6106.8 6130.3 6132.7 6148.4 4878.9 4882.9 4895.6 4919.4 4926.5 4393.3 4402.5 4410.6 4451.0 4460.4 5509.0 5594.5 5604.1 5611.9 5627.0 6085.4 6123.3 6138.8 6145.7 6148.6 4768.2 5483.0 5598.0 5600.9 5608.9 5332.3 5344.7 5346.5 5359.9 5406.4 6320.5 6372.5 6386.7 6391.3 6396.0 6074.9 6107.1 6239.0 6264.4 6273.6 6255.6 6327.8 6329.4 6337.3 6350.9 6125.1 6140.5 6145.4 6146.0 6150.2 5737.5 6186.9 6187.0 6191.3 6220.8 5777.4 5844.0 5924.7 6004.5 6007.5 5948.7 6016.5 6054.1 6081.5 6083.7 4842.6 5853.3 5856.3 5859.1 5891.7 5849.5 5906.0 6008.6 6056.8 6104.5 5798.9 5834.1 5849.9 5925.1 5970.5 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:71325.71 Median:38176.93 stddev:154877.14 (405.7%) stddev-sans-first:155220.27 20:40:51 INFO - PROCESS | 1652 | Values: 481.9 497.6 531.2 543.9 563.6 30428.0 32068.4 32077.0 32080.8 42611.8 44600.8 45229.5 45270.5 45310.9 45364.5 9721.5 9883.3 9907.0 9920.7 9932.4 8746.9 8935.8 9615.8 9714.0 9767.0 11844.8 12080.6 12093.0 12337.6 12344.3 12110.0 12205.9 12206.1 12212.9 12216.3 8698.7 11584.9 11913.6 11974.2 11988.0 40231.0 42877.0 43017.6 43126.1 43189.0 3577.4 3609.0 3635.9 4003.0 4133.6 28577.6 29589.4 30938.9 32598.7 33354.8 3209.5 3386.1 3393.5 3426.8 3427.5 465983.0 469692.0 470613.0 470962.0 473003.0 63471.0 63967.0 64074.7 64146.7 64266.7 66084.3 66130.7 66243.7 66351.7 66390.0 13941.4 16545.7 16795.3 16831.7 16855.2 12883.5 12930.2 12952.5 12952.5 12969.5 15368.9 15587.3 16169.9 17338.9 17869.8 6384.2 6501.3 6544.7 6546.1 6580.8 818.3 909.9 964.5 996.7 1038.8 27387.9 30619.4 30772.8 31202.0 31399.8 996.7 1064.7 1112.5 1118.1 1227.4 16312.7 16327.7 16372.8 16517.0 16597.6 591459.0 594440.0 598514.0 600059.0 607429.0 53341.7 53593.5 59683.5 60682.0 60892.0 1206.5 1405.4 1441.1 1441.4 1441.5 27190.3 27339.7 27600.9 27754.3 27802.6 53896.7 58901.7 59859.8 59877.4 62694.7 638624.0 640307.0 662963.0 664406.0 666760.0 25957.7 25959.1 26311.0 26541.2 26580.0 47997.5 48042.3 48510.8 48872.5 49296.0 39847.8 39860.6 40866.6 41280.4 41646.8 39411.6 40109.6 40663.6 40852.8 40978.8 35535.5 35768.0 36696.4 36852.8 36942.3 31971.8 34823.8 35347.6 35563.8 36411.9 24468.3 25602.6 26401.3 26488.5 26553.9 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:35200.19 Median:3965.16 stddev:103804.84 (2617.9%) stddev-sans-first:104063.04 20:40:51 INFO - PROCESS | 1652 | Values: 342.6 353.1 360.9 377.2 404.9 15740.4 17073.5 21092.3 21245.0 21378.9 15471.7 21508.9 21799.2 21847.0 22284.0 5308.9 5899.6 6109.1 6176.9 6185.0 6060.7 6157.7 6162.0 6164.7 6182.7 5722.3 6006.0 6058.3 6108.6 6134.2 5066.8 6075.6 6078.8 6083.8 6093.1 4993.2 5626.7 5820.4 5842.3 5880.1 22377.2 23110.9 25384.2 26172.9 26348.2 2249.6 2321.9 2362.1 2878.6 2954.5 9557.3 10487.8 10614.3 12829.7 16451.2 1812.9 1841.9 1895.8 1908.6 1949.2 96498.0 111329.9 114893.4 116410.4 136536.9 8445.2 9608.2 9888.4 10187.7 10224.3 12461.3 12537.9 12560.9 12563.0 12569.4 7851.1 8107.2 8159.8 8199.0 8224.5 8435.4 8530.3 8536.9 8697.0 8745.9 3916.3 3916.4 3921.6 3933.1 3997.2 1707.3 1934.3 1939.6 1952.1 1953.8 341.0 365.5 403.7 861.1 872.2 11771.6 11827.8 11932.8 12267.3 12309.3 432.2 436.8 438.0 444.2 451.1 6068.7 6152.0 6158.1 6231.7 6260.7 608399.0 620660.0 621376.0 622873.0 629744.0 92742.0 141742.0 143600.0 147333.3 151747.0 380.3 418.1 426.8 431.3 432.1 9818.3 10135.6 11064.6 11094.0 11437.0 57264.0 57409.0 57548.6 59078.7 59624.7 66627.3 80066.6 81670.6 83394.6 83692.0 7116.8 7831.3 7881.5 8105.9 8175.8 11063.0 13131.7 13183.8 13671.0 13707.1 11263.2 11725.3 11849.9 11880.3 11889.0 10184.3 11214.4 11292.9 11792.2 11833.6 9638.5 9650.1 11008.1 11707.3 11929.0 6059.5 7309.1 7438.4 7507.2 7564.6 9456.6 11039.8 11240.0 11616.4 11659.1 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5712.24 Median:4848.61 stddev:3217.91 (66.4%) stddev-sans-first:3211.88 20:40:51 INFO - PROCESS | 1652 | Values: 1568.8 1618.9 1800.6 1910.6 1912.9 8660.7 9077.1 9081.3 9092.9 9099.5 7942.1 8203.5 8481.5 8759.8 8862.6 2327.9 2392.6 2396.1 2397.5 2409.0 2340.2 2358.7 2378.0 2381.9 2386.3 2086.3 2168.2 2320.4 2382.6 2385.5 2369.2 2382.1 2402.8 2404.1 2405.5 2379.6 2383.0 2386.6 2387.0 2387.5 8799.4 8887.5 8898.3 8898.9 8902.6 5205.7 5279.5 5340.9 5345.5 5369.5 3479.2 3525.9 3673.5 3678.1 3698.1 1042.0 1081.3 1089.6 1105.6 1106.8 11599.2 11698.5 11839.5 11853.9 11883.0 4519.0 4610.2 4779.4 4799.2 4806.6 10180.8 10185.9 10201.9 10210.3 10229.3 5301.5 5305.2 5307.1 5313.5 5316.3 4645.3 4768.1 4787.4 4838.0 4844.3 3025.0 3650.9 3684.4 3713.8 3735.6 883.6 1014.5 1054.8 1056.2 1066.6 769.9 817.6 876.5 877.2 923.0 5858.7 5883.7 5980.2 6036.5 6201.8 935.8 958.6 975.9 983.4 1004.9 3527.3 3914.4 3957.4 3991.3 4005.3 9121.3 10425.2 10942.0 10951.1 10968.1 9860.3 10292.6 10752.7 10887.6 10904.1 7364.6 8001.4 8083.5 8229.6 8232.4 5938.6 6015.9 6172.8 6193.2 6202.5 9500.3 10119.8 10131.5 10148.9 10149.2 9994.2 10122.3 10185.2 10189.8 10234.8 7476.7 7529.2 7613.5 7659.4 7670.1 8142.3 8386.5 8386.8 8406.6 8407.2 7419.9 7509.7 7523.3 7586.2 7678.0 6640.0 7090.3 7388.5 7471.8 7472.0 6301.4 6817.9 6977.1 7054.5 7071.0 4852.9 4876.5 4937.8 4949.0 5057.3 4907.1 4927.3 4985.3 5010.4 5035.8 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4452.91 Median:5055.98 stddev:1372.44 (27.1%) stddev-sans-first:1340.12 20:40:51 INFO - PROCESS | 1652 | Values: 282.6 295.8 303.5 308.8 309.7 4330.6 4881.8 5224.3 5227.6 5235.5 4375.9 4932.3 4969.9 5149.7 5179.2 3818.7 3951.7 3999.1 4015.7 4044.7 3448.0 3932.7 4024.2 4028.5 4078.6 3622.3 3774.7 3966.8 3991.8 3998.2 3956.8 3993.5 4006.9 4009.4 4022.9 3071.8 3291.6 3474.2 3477.9 3481.9 4165.8 4885.5 5094.7 5146.9 5151.7 4301.7 4505.2 4518.8 4574.1 4589.4 4227.7 5447.3 5473.1 5496.5 5567.7 2999.7 3019.8 3626.9 3824.1 3829.3 5489.8 5638.0 5646.5 5725.0 5730.4 3252.3 3871.1 3875.5 3881.9 3884.0 3541.6 3729.6 3921.8 3947.2 3985.8 4334.6 4385.5 4403.5 4555.6 4560.5 5482.8 5511.4 5511.7 5549.7 5557.7 1303.4 1320.6 1326.5 1345.6 1345.7 735.8 773.3 858.5 870.8 923.4 5142.9 5143.3 5147.2 5153.4 5272.6 5666.0 5687.2 5708.9 5744.9 5785.2 4926.9 5111.4 5132.6 5143.2 5192.8 2939.8 2950.6 2960.9 2966.0 2970.4 4850.0 5794.4 5812.8 5855.8 5865.7 4648.5 5729.7 5755.8 5784.1 5784.6 4867.5 4878.8 5602.4 5849.7 5974.9 5380.4 5576.9 5605.3 5709.9 5738.6 5548.6 5563.8 5565.3 5583.8 5597.5 4755.0 5771.2 5848.9 5852.4 5911.6 3377.9 3707.8 3776.8 3808.2 3851.7 5097.8 5575.5 5589.6 5613.2 5850.9 4664.5 5419.6 5435.0 5506.3 5558.9 4495.5 5120.3 5159.7 5429.6 5454.1 4528.8 4560.7 4809.7 5093.4 5410.2 5018.5 5335.8 5394.8 5444.2 5538.4 4496.8 5432.3 5454.6 5491.5 5497.8 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:24682.58 Median:19583.37 stddev:39234.29 (200.3%) stddev-sans-first:39302.87 20:40:51 INFO - PROCESS | 1652 | Values: 653.5 740.5 755.9 760.2 783.1 15503.4 15705.5 16448.9 16451.2 16452.2 14941.0 15139.2 15214.4 15232.1 15264.2 680.6 685.5 742.9 894.7 918.0 718.2 884.5 903.9 915.7 917.4 936.8 946.3 948.5 949.2 953.5 949.4 950.8 951.5 953.3 953.6 561.7 706.4 706.5 708.5 713.2 14443.9 14862.7 14870.9 14925.8 14942.5 14356.6 14546.4 14835.3 14903.7 14920.0 11770.4 11853.0 12079.2 12082.8 12394.5 3914.5 3920.8 3983.8 4021.8 4086.0 103452.0 111018.0 115100.8 115807.0 116855.0 8792.7 11094.3 11180.6 11194.2 11567.9 17111.7 22055.0 22641.9 22754.3 22756.4 8299.3 8299.3 8408.5 8420.1 8544.0 4563.0 4574.2 4656.8 4700.8 4779.2 3525.2 3616.0 3631.8 3658.5 3661.0 671.2 672.6 675.6 677.8 680.2 553.2 569.6 629.9 681.2 690.2 9200.9 9894.8 10015.1 10076.4 10104.3 620.0 665.0 689.5 691.6 697.9 4198.3 4841.4 4865.6 4882.4 4883.5 157579.0 158003.4 164948.2 165233.2 170474.0 108365.0 110941.0 113279.0 113649.0 121639.0 83904.6 103218.3 106156.8 107297.0 108289.0 10096.2 10255.6 10260.3 10263.6 10295.7 59168.0 59637.1 59874.7 61457.8 62915.0 66425.0 85575.7 90692.0 91472.5 92592.1 13159.4 13675.6 13678.2 13898.0 14081.8 14614.5 14734.3 14849.9 15086.3 15207.9 14374.6 14558.2 14602.3 14707.3 14905.3 13958.7 14382.4 14630.5 14718.9 14882.4 11800.4 13683.2 13732.9 13932.9 14129.0 11400.5 11657.0 11771.6 11890.9 12039.7 9700.5 10995.8 11467.3 11760.4 11780.3 20:40:51 INFO - PROCESS | 1652 | -------- Summary: end -------- 20:40:51 INFO - PROCESS | 1652 | 20:40:51 INFO - TEST-INFO | 1652: exit 0 20:40:51 INFO - TEST-OK | dromaeo_css | took 1117458ms 20:40:51 INFO - TEST-START | kraken 20:40:51 INFO - operating with platform_type : mac_ 20:40:51 INFO - Initialising browser for kraken test... 20:40:51 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp7ewINf/profile http://localhost:49261/getInfo.html) 20:40:54 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 20:40:54 INFO - PROCESS | 1659 | colorDepth:24 20:40:54 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 20:40:54 INFO - PROCESS | 1659 | __metrics 20:40:54 INFO - PROCESS | 1659 | 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" 20:40:54 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:40:54 INFO - TEST-INFO | 1659: exit 0 20:40:54 INFO - Browser initialized. 20:40:54 INFO - Running cycle 1/1 for kraken test... 20:40:54 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp7ewINf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:06 INFO - PROCESS | 1661 | RSS: Main: 245501952 20:41:06 INFO - PROCESS | 1661 | 20:41:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-beat-detection) 20:41:10 INFO - PROCESS | 1661 | RSS: Main: 295174144 20:41:10 INFO - PROCESS | 1661 | 20:41:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-dft) 20:41:13 INFO - PROCESS | 1661 | RSS: Main: 306008064 20:41:13 INFO - PROCESS | 1661 | 20:41:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-fft) 20:41:17 INFO - PROCESS | 1661 | RSS: Main: 394035200 20:41:17 INFO - PROCESS | 1661 | 20:41:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49261/tests/kraken/driver.html?testName=audio-oscillator) 20:41:20 INFO - PROCESS | 1661 | RSS: Main: 293363712 20:41:20 INFO - PROCESS | 1661 | 20:41:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49261/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:41:23 INFO - PROCESS | 1661 | RSS: Main: 297328640 20:41:23 INFO - PROCESS | 1661 | 20:41:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49261/tests/kraken/driver.html?testName=imaging-darkroom) 20:41:26 INFO - PROCESS | 1661 | RSS: Main: 334503936 20:41:26 INFO - PROCESS | 1661 | 20:41:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49261/tests/kraken/driver.html?testName=imaging-desaturate) 20:41:30 INFO - PROCESS | 1661 | RSS: Main: 334974976 20:41:30 INFO - PROCESS | 1661 | 20:41:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49261/tests/kraken/driver.html?testName=json-parse-financial) 20:41:33 INFO - PROCESS | 1661 | RSS: Main: 336371712 20:41:33 INFO - PROCESS | 1661 | 20:41:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49261/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:41:35 INFO - PROCESS | 1661 | RSS: Main: 312922112 20:41:35 INFO - PROCESS | 1661 | 20:41:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:41:38 INFO - PROCESS | 1661 | RSS: Main: 319229952 20:41:38 INFO - PROCESS | 1661 | 20:41:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:41:41 INFO - PROCESS | 1661 | RSS: Main: 317128704 20:41:41 INFO - PROCESS | 1661 | 20:41:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:41:44 INFO - PROCESS | 1661 | RSS: Main: 326356992 20:41:44 INFO - PROCESS | 1661 | 20:41:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:41:48 INFO - PROCESS | 1661 | RSS: Main: 329183232 20:41:48 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49261/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49261/tests/kraken/driver.html?testName=ai-astar) 20:41:49 INFO - PROCESS | 1661 | RSS: Main: 373092352 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | __start_tp_report 20:41:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 20:41:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 20:41:49 INFO - PROCESS | 1661 | |i|pagename|runs| 20:41:49 INFO - PROCESS | 1661 | |0;ai-astar;100;112;112;114;112;113;112;112;112;113 20:41:49 INFO - PROCESS | 1661 | |1;audio-beat-detection;141;113;107;161;105;107;141;135;182;106 20:41:49 INFO - PROCESS | 1661 | |2;audio-dft;182;184;183;184;183;184;183;183;262;189 20:41:49 INFO - PROCESS | 1661 | |3;audio-fft;78;71;57;86;58;58;63;62;83;57 20:41:49 INFO - PROCESS | 1661 | |4;audio-oscillator;84;89;93;72;72;68;99;70;67;100 20:41:49 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;90;87;90;87;90;86;90;87;89;86 20:41:49 INFO - PROCESS | 1661 | |6;imaging-darkroom;94;94;94;94;93;94;94;95;94;94 20:41:49 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;83;86;85;90;87;84;84;83;86 20:41:49 INFO - PROCESS | 1661 | |8;json-parse-financial;55;50;51;51;53;55;53;53;54;52 20:41:49 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;71;77;72;79;75;72;73;72;73;74 20:41:49 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;83;84;82;86;82;86;80;85;82;78 20:41:49 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;138;144;144;142;143;130;135;133;176;136 20:41:49 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;164;175;163;164;163;163;168;164;196;161 20:41:49 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;66;67;69;66;66;67;66;65;64;67 20:41:49 INFO - PROCESS | 1661 | __end_tp_report 20:41:49 INFO - PROCESS | 1661 | __start_cc_report 20:41:49 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2083 20:41:49 INFO - PROCESS | 1661 | __end_cc_report 20:41:49 INFO - PROCESS | 1661 | __startTimestamp1471750909958__endTimestamp 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | ------- Summary: start ------- 20:41:49 INFO - PROCESS | 1661 | Number of tests: 14 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.99 (3.6%) stddev-sans-first:0.73 20:41:49 INFO - PROCESS | 1661 | Values: 100.0 112.0 112.0 114.0 112.0 113.0 112.0 112.0 112.0 113.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:138.00 stddev:26.83 (19.4%) stddev-sans-first:28.15 20:41:49 INFO - PROCESS | 1661 | Values: 141.0 113.0 107.0 161.0 105.0 107.0 141.0 135.0 182.0 106.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:191.70 Median:184.00 stddev:24.77 (13.5%) stddev-sans-first:26.03 20:41:49 INFO - PROCESS | 1661 | Values: 182.0 184.0 183.0 184.0 183.0 184.0 183.0 183.0 262.0 189.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:67.30 Median:67.00 stddev:11.33 (16.9%) stddev-sans-first:11.34 20:41:49 INFO - PROCESS | 1661 | Values: 78.0 71.0 57.0 86.0 58.0 58.0 63.0 62.0 83.0 57.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:78.00 stddev:13.12 (16.8%) stddev-sans-first:13.88 20:41:49 INFO - PROCESS | 1661 | Values: 84.0 89.0 93.0 72.0 72.0 68.0 99.0 70.0 67.0 100.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.50 stddev:1.75 (2.0%) stddev-sans-first:1.73 20:41:49 INFO - PROCESS | 1661 | Values: 90.0 87.0 90.0 87.0 90.0 86.0 90.0 87.0 89.0 86.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 20:41:49 INFO - PROCESS | 1661 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 95.0 94.0 94.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:2.11 (2.5%) stddev-sans-first:2.24 20:41:49 INFO - PROCESS | 1661 | Values: 85.0 83.0 86.0 85.0 90.0 87.0 84.0 84.0 83.0 86.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.70 (3.2%) stddev-sans-first:1.59 20:41:49 INFO - PROCESS | 1661 | Values: 55.0 50.0 51.0 51.0 53.0 55.0 53.0 53.0 54.0 52.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:73.50 stddev:2.53 (3.4%) stddev-sans-first:2.47 20:41:49 INFO - PROCESS | 1661 | Values: 71.0 77.0 72.0 79.0 75.0 72.0 73.0 72.0 73.0 74.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.50 stddev:2.57 (3.1%) stddev-sans-first:2.73 20:41:49 INFO - PROCESS | 1661 | Values: 83.0 84.0 82.0 86.0 82.0 86.0 80.0 85.0 82.0 78.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:142.50 stddev:12.87 (9.0%) stddev-sans-first:13.57 20:41:49 INFO - PROCESS | 1661 | Values: 138.0 144.0 144.0 142.0 143.0 130.0 135.0 133.0 176.0 136.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:164.00 stddev:10.57 (6.4%) stddev-sans-first:11.10 20:41:49 INFO - PROCESS | 1661 | Values: 164.0 175.0 163.0 164.0 163.0 163.0 168.0 164.0 196.0 161.0 20:41:49 INFO - PROCESS | 1661 | 20:41:49 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:1.34 (2.0%) stddev-sans-first:1.41 20:41:49 INFO - PROCESS | 1661 | Values: 66.0 67.0 69.0 66.0 66.0 67.0 66.0 65.0 64.0 67.0 20:41:49 INFO - PROCESS | 1661 | -------- Summary: end -------- 20:41:49 INFO - PROCESS | 1661 | 20:41:50 INFO - TEST-INFO | 1661: exit 0 20:41:50 INFO - TEST-OK | kraken | took 58852ms 20:41:50 INFO - SUITE-END | took 1176s 20:41:51 INFO - Completed test suite (00:19:36) 20:41:51 INFO - javascript benchmark 20:41:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.9007929762083, 3314.3205642843213, 3344.665533446655, 3366.3603915256117, 3397.3375191837586, 5537.750801213945, 5541.528970037547, 5613.494785469307, 5628.549716022719, 5668.348139963905, 4529.954700452994, 5581.8604534886645, 5699.059655156904, 5704.286964129484, 5747.856303592413, 4132.359484279941, 4211.0, 5104.183330667094, 5127.897442051157, 5221.973890130544, 4642.97678511607, 5245.6852588844795, 5250.737463126848, 5264.710440925747, 5310.15037593985, 5084.9745751271, 5158.993996170748, 5171.844844654666, 5186.1442861927735, 5189.8183563575285, 5098.6176036797015, 5151.6908985461, 5172.974135129338, 5178.896422071537, 5191.169412893919, 5101.15830887903, 5109.20807274873, 5132.28148059272, 5178.637495375326, 5237.057329680656, 5645.2096706461125, 5662.320521537401, 5719.571032172562, 5731.340895796995, 5752.482276595085, 5983.730732117066, 6054.72753726079, 6201.658908760016, 6203.503719702413, 6220.409061139185, 6171.13604095427, 6186.257649082094, 6224.657643829588, 6227.532935029891, 6265.8120256392385, 5768.567357448208, 5774.133879918004, 5780.017397042555, 5784.71076446176, 5785.508231800344, 6121.788327166048, 6133.212252453595, 6271.122042913996, 6364.140840986408, 6368.745250190033, 6036.245469316335, 6095.087764868071, 6101.840650276433, 6106.542009349317, 6109.326365330526, 6203.2245969253845, 6212.347703491158, 6216.968915155486, 6222.0355844844125, 6226.657533835682, 5956.0, 6109.206538480613, 6120.326764055951, 6155.507559395238, 6168.074788781629, 5948.10778383246, 6106.755729770759, 6130.3369889931655, 6132.724027418777, 6148.354422785543, 4878.853634390974, 4882.902341953212, 4895.632827537949, 4919.3604831371695, 4926.531979461945, 4393.275109606879, 4402.537733537996, 4410.625096866738, 4450.955490445054, 4460.442444694413, 5508.9724551376985, 5594.468525490072, 5604.103343465026, 5611.943880561143, 5626.971865140648, 6085.391460853879, 6123.28262147438, 6138.815835524942, 6145.709401025834, 6148.616561273767, 4768.237082066922, 5482.985647655197, 5598.0, 5600.943990560124, 5608.859778505488, 5332.33345831771, 5344.652597581223, 5346.465353464622, 5359.866003349947, 5406.432324605938, 6320.462760665303, 6372.522060845455, 6386.712597933104, 6391.265004524539, 6396.0405939109505, 6074.878502429839, 6107.144999699956, 6238.968805155945, 6264.373562643699, 6273.592216505913, 6255.624662520263, 6327.778527751507, 6329.398707122816, 6337.334579869047, 6350.904736428864, 6125.073522814525, 6140.495578583275, 6145.371476558805, 6146.0473626587955, 6150.231221097363, 5737.540996720336, 6186.907196391968, 6186.969065154645, 6191.349908259568, 6220.782272620437, 5777.39337369587, 5844.035734103825, 5924.733386997509, 6004.459598636144, 6007.519398448201, 5948.702564871825, 6016.488598469179, 6054.122152287806, 6081.452669259788, 6083.726232319645, 4842.564169224786, 5853.2976042875125, 5856.326522449803, 5859.062549991981, 5891.705414729161, 5849.532037436909, 5906.0255057914965, 6008.639481631116, 6056.788012419544, 6104.481119104926, 5798.85502862415, 5834.095715164156, 5849.853753656193, 5925.081612350093, 5970.462658360769], "value": 5617.052618430858, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.88675661219605, 497.59197458084384, 531.2297169104797, 543.9203181684356, 563.5547917145456, 30428.0, 32068.35863282733, 32077.037688869314, 32080.83959580202, 42611.78694106533, 44600.776996114975, 45229.54770452303, 45270.547294527045, 45310.86722831931, 45364.54635453645, 9721.513924303774, 9883.308168428213, 9906.950465247664, 9920.702378928643, 9932.404055756642, 8746.912530874673, 8935.821283574325, 9615.807683846318, 9714.028597140265, 9767.023297670212, 11844.822327665092, 12080.577179798709, 12093.0, 12337.568185113523, 12344.321062341567, 12110.0, 12205.877941220608, 12206.08454365926, 12212.938935305267, 12216.2670239785, 8698.695545655935, 11584.942075289653, 11913.583024594183, 11974.22167559106, 11987.981021613174, 40231.0, 42877.0, 43017.56982430167, 43126.137477250595, 43189.0, 3577.3918433866306, 3608.9639103608893, 3635.8545658173643, 4003.0, 4133.565975572552, 28577.57133642997, 29589.408211835856, 30938.91713790029, 32598.696052157884, 33354.83322583396, 3209.4543927532377, 3386.136535183537, 3393.490976353542, 3426.7601267911255, 3427.4516077427493, 465983.0, 469692.0, 470613.0, 470962.0, 473003.0, 63471.0, 63967.0, 64074.6796266025, 64146.679266603365, 64266.67866660637, 66084.33915660878, 66130.66934665295, 66243.66878165578, 66351.66824165848, 66390.0, 13941.372638231303, 16545.66908661844, 16795.328186872634, 16831.74752378715, 16855.241514131892, 12883.54907578249, 12930.224186548838, 12952.481900723866, 12952.481900724055, 12969.481220751253, 15368.923155384151, 15587.29857156408, 16169.919150404172, 17338.91330543339, 17869.821301786815, 6384.201974753156, 6501.252355979028, 6544.7382104716235, 6546.13435170974, 6580.8354791130605, 818.3412353055847, 909.8635204719212, 964.4502633498979, 996.7259003773875, 1038.8234000219982, 27387.86306068457, 30619.38761224763, 30772.846135769178, 31202.0, 31399.84300078485, 996.6710985374809, 1064.7125276175543, 1112.5327362507767, 1118.1334465788916, 1227.4415141110774, 16312.673746525003, 16327.673446531004, 16372.754408683879, 16517.008979461272, 16597.604867495465, 591459.0, 594440.0, 598514.0, 600059.0, 607429.0, 53341.733291333294, 53593.46406535885, 59683.50791230254, 60682.0, 60892.0, 1206.5294535131306, 1405.4308005257888, 1441.1209162410712, 1441.358595425026, 1441.452248145698, 27190.320241994108, 27339.726602733717, 27600.861995689895, 27754.306142346602, 27802.582961255193, 53896.73051634717, 58901.70549147227, 59859.80280394455, 59877.40122598718, 62694.68652656708, 638624.0, 640307.0, 662963.0, 664406.0, 666760.0, 25957.740422595532, 25959.091431799796, 26311.0, 26541.203763887115, 26580.0, 47997.520024799305, 48042.27936581024, 48510.787230318114, 48872.53382398534, 49296.0, 39847.80076099601, 39860.601393985686, 40866.63026753339, 41280.38079428871, 41646.750597482125, 39411.60588394079, 40109.598904010585, 40663.59336406598, 40852.77441676754, 40978.79510602428, 35535.46696799602, 35768.0, 36696.449553257255, 36852.81573592115, 36942.26115477729, 31971.840140799148, 34823.84082235783, 35347.64652353444, 35563.82218088893, 36411.90233367384, 24468.26595202147, 25602.61596076023, 26401.339966500993, 26488.470230594896, 26553.867230663724], "value": 20506.62178264807, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.61583203856424, 353.06437939460443, 360.94766258892463, 377.1965713031243, 404.87651266363747, 15740.449084282052, 17073.48779536614, 21092.261770838024, 21244.99999999996, 21378.89310553449, 15471.69056618867, 21508.892455537738, 21799.237026704068, 21847.0, 22284.0, 5308.9469105308835, 5899.5870289079785, 6109.14471973924, 6176.876462470748, 6185.0, 6060.727267272983, 6157.661328626924, 6162.0, 6164.660943648098, 6182.690865456731, 5722.256106706143, 6006.0, 6058.273007239138, 6108.633481991094, 6134.171886795292, 5066.771995260204, 6075.605085669417, 6078.787242446515, 6083.8174854754425, 6093.055576385667, 4993.226049962262, 5626.677730733274, 5820.35976042635, 5842.269716285465, 5880.147378630075, 22377.216797412013, 23110.884445577834, 25384.238472845846, 26172.8691356542, 26348.20955371342, 2249.6175650139517, 2321.907123715066, 2362.055177928825, 2878.5826055221874, 2954.4829654810537, 9557.330986830924, 10487.842682359771, 10614.310069845513, 12829.743405131938, 16451.177441127897, 1812.9456116316533, 1841.9355322563647, 1895.810418958107, 1908.6469003234447, 1949.1716020691292, 96498.0, 111329.88670113277, 114893.4255328718, 116410.41794791057, 136536.88483612303, 8445.1977062179, 9608.231341492663, 9888.357256778287, 10187.7453063674, 10224.335418197794, 12461.314627695563, 12537.937310313573, 12560.932320752838, 12562.994960403146, 12569.434375453127, 7851.097123830833, 8107.1892810718455, 8159.836803263902, 8198.959005205055, 8224.506529608243, 8435.409521333571, 8530.317574594017, 8536.914630853613, 8696.956515217384, 8745.868811967826, 3916.3146449371247, 3916.433662820458, 3921.627445392683, 3933.1347103637154, 3997.181282516448, 1707.2658756734475, 1934.332655233942, 1939.6217737541044, 1952.1215453046186, 1953.8436925045962, 340.99147521312165, 365.48466662006444, 403.6972270796902, 861.1087524412328, 872.2455076358904, 11771.646850594496, 11827.822582661267, 11932.821007684892, 12267.263964162179, 12309.26144431337, 432.1874875234539, 436.80343845269755, 437.96277316427825, 444.24700133274433, 451.1405772004337, 6068.696565171724, 6152.033705397195, 6158.107074474176, 6231.719572619243, 6260.686965651699, 608399.0, 620660.0, 621376.0, 622873.0, 629744.0, 92742.0, 141742.0, 143600.0, 147333.26333368264, 151747.0, 380.2622911551581, 418.0823093310155, 426.8314015963677, 431.29483294813207, 432.0904496035862, 9818.312718109664, 10135.64525241613, 11064.612738554433, 11094.0, 11437.02785263262, 57264.0, 57409.0, 57548.56128596651, 59078.70460647669, 59624.70187649034, 66627.33372666406, 80066.56933724672, 81670.59164704138, 83394.58302708447, 83692.0, 7116.750913717995, 7831.316267002622, 7881.502514522223, 8105.8784118239455, 8175.836483270183, 11063.0, 13131.671708207372, 13183.802242966554, 13671.0, 13707.109037912503, 11263.211575189658, 11725.296482211095, 11849.933505984503, 11880.287182769061, 11889.048876089719, 10184.287099902936, 11214.439278036229, 11292.887071129184, 11792.23350482216, 11833.585824496102, 9638.518074096408, 9650.131488166098, 11008.119350452105, 11707.2975621463, 11929.045676346044, 6059.54570902989, 7309.086364204474, 7438.404927605887, 7507.174210836694, 7564.621768911422, 9456.621735130519, 11039.779204416027, 11240.0, 11616.419179041182, 11659.125565582784], "value": 8580.958180147954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1568.8431156884312, 1618.8704903607713, 1800.6308706715117, 1910.5987742574068, 1912.9043547822605, 8660.696875609356, 9077.137671921157, 9081.31890108242, 9092.863607045883, 9099.45403275803, 7942.086660034083, 8203.507789532632, 8481.491110533372, 8759.781005474866, 8862.64549418024, 2327.8836058197107, 2392.6411038344213, 2396.1134380279323, 2397.4845408237225, 2409.036385445827, 2340.2394221877958, 2358.7287461941837, 2377.95244095118, 2381.92854214373, 2386.319898828834, 2086.2802333195023, 2168.2086038595876, 2320.4198950262435, 2382.559226543095, 2385.4751954569965, 2369.2418426103654, 2382.130522359335, 2402.771736685012, 2404.0504000919714, 2405.506871091422, 2379.5954687703133, 2382.9642555361684, 2386.642003699459, 2387.0, 2387.4866903615807, 8799.384043116985, 8887.466751994836, 8898.332625053146, 8898.86651700225, 8902.643894244222, 5205.687658740488, 5279.47205279469, 5340.893182136374, 5345.518903298722, 5369.48989845964, 3479.1823921378455, 3525.8589656413715, 3673.485712000322, 3678.1172518595613, 3698.14942563212, 1041.9739506512321, 1081.291753901196, 1089.5702583239342, 1105.6296140792763, 1106.778644271141, 11599.246049006788, 11698.47356868943, 11839.467223974942, 11853.940730296294, 11883.049356051495, 4518.9548104518535, 4610.216263235258, 4779.378680771547, 4799.160146974265, 4806.591439727592, 10180.847287290697, 10185.898141018642, 10201.897981020094, 10210.285280030475, 10229.335093218917, 5301.5493683037375, 5305.230741542455, 5307.124324486494, 5313.521783039545, 5316.282301889273, 4645.279981602857, 4768.117898188846, 4787.40157480315, 4837.95162048375, 4844.346013288161, 3024.9848750756105, 3650.945235821465, 3684.373656478405, 3713.795741234204, 3735.6264373562426, 883.5758835758743, 1014.5130337438071, 1054.847047178155, 1056.1656291529605, 1066.5520481397832, 769.8652735771218, 817.6116344736322, 876.4828751036791, 877.1798368525322, 922.9538523073819, 5858.677772722456, 5883.7058147092475, 5980.22257106582, 6036.5170786336985, 6201.751929922752, 935.8128374325026, 958.5973890965811, 975.878015248094, 983.3830167364299, 1004.924630652704, 3527.312174126072, 3914.393269043303, 3957.406389041667, 3991.3015222336558, 4005.339119045383, 9121.31590130743, 10425.165986721044, 10942.0, 10951.123910087173, 10968.067714244218, 9860.260480463994, 10292.588296468208, 10752.677419677422, 10887.618933337295, 10904.07315378186, 7364.631768411665, 8001.399895007898, 8083.514989100673, 8229.588520573947, 8232.382571307176, 5938.6436813791315, 6015.939840601713, 6172.845678858064, 6193.194884664965, 6202.534809889366, 9500.334976551852, 10119.79760404773, 10131.544080516542, 10148.949255253676, 10149.188064954933, 9994.20046396272, 10122.291439599157, 10185.185185185315, 10189.84715229287, 10234.795304093728, 7476.663550140365, 7529.171791103084, 7613.467057305936, 7659.4255430841795, 7670.079590448959, 8142.348612110898, 8386.496810191407, 8386.790330241549, 8406.621702023545, 8407.150426906072, 7419.851602968019, 7509.699612015458, 7523.3229009389415, 7586.165521792488, 7678.0, 6640.003999400129, 7090.290970902868, 7388.519746216479, 7471.775846724607, 7472.0, 6301.369863013845, 6817.897731533932, 6977.092977912839, 7054.541454805421, 7071.0, 4852.878678033077, 4876.536729010738, 4937.753112344297, 4949.034938187019, 5057.266696329085, 4907.067657145131, 4927.260910863399, 4985.326980857538, 5010.448850626355, 5035.773390197523], "value": 4570.389889580113, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.5860114931625, 295.812159278858, 303.541652105321, 308.82088388734536, 309.6918566026804, 4330.610245077943, 4881.755912204393, 5224.29472021277, 5227.581793456524, 5235.450277720842, 4375.934360984587, 4932.334134891788, 4969.900601987958, 5149.742512874351, 5179.249008893708, 3818.6754125899333, 3951.6641085507695, 3999.140184860269, 4015.6586690131376, 4044.716869819099, 3447.9828450607133, 3932.6853851691794, 4024.215278020787, 4028.476298081245, 4078.5921407859273, 3622.2574372253757, 3774.6602805747475, 3966.821493032806, 3991.800409979504, 3998.1603863188766, 3956.8021598920227, 3993.4608827808165, 4006.8981030216632, 4009.418634298025, 4022.9138132704297, 3071.800332978349, 3291.572095627553, 3474.2183008823067, 3477.9130521736906, 3481.9477707834403, 4165.75005499671, 4885.511448855122, 5094.7197904115255, 5146.897062058738, 5151.665141765773, 4301.698881078326, 4505.229283990322, 4518.819247230107, 4574.1309151261485, 4589.35748995141, 4227.682923780698, 5447.268620994591, 5473.06957817172, 5496.532794712454, 5567.659404357353, 2999.7000299969827, 3019.803712758686, 3626.8937973917946, 3824.063104539378, 3829.291581057513, 5489.807856725035, 5637.971810140964, 5646.463585959327, 5725.0, 5730.4269573042775, 3252.3007553376137, 3871.1483473635753, 3875.534935807721, 3881.86413475527, 3883.990162557699, 3541.6458354164893, 3729.589745128088, 3921.8039098044983, 3947.1710940702774, 3985.80070996449, 4334.566543345641, 4385.489175593718, 4403.5376285489565, 4555.589996900295, 4560.452745670541, 5482.80810171642, 5511.3937466878615, 5511.696856672841, 5549.694766787866, 5557.722113894289, 1303.419978109732, 1320.6236222676491, 1326.4694122350943, 1345.6164992976956, 1345.663584103974, 735.8050116719073, 773.3465221887496, 858.5321000054985, 870.7866572689605, 923.3813345058827, 5142.871428214325, 5143.305653736773, 5147.1507201312115, 5153.433122356538, 5272.574615676832, 5665.971670141623, 5687.232223649837, 5708.857278568069, 5744.913826292609, 5785.1611516329895, 4926.901461970812, 5111.437741848394, 5132.640715149903, 5143.228515722672, 5192.792288308501, 2939.8383088930314, 2950.583719814501, 2960.86676099576, 2965.9703402965692, 2970.420767950272, 4850.0, 5794.362620111784, 5812.7502586943165, 5855.765769369177, 5865.736041878125, 4648.48866624671, 5729.742161602738, 5755.827325180251, 5784.136852258075, 5784.623999440023, 4867.537583929521, 4878.780454879539, 5602.355729091207, 5849.6782676952325, 5974.9402505974385, 5380.408155103014, 5576.944230557805, 5605.290386432014, 5709.857253568694, 5738.598298119258, 5548.583856210881, 5563.805266815642, 5565.30433695788, 5583.776648933997, 5597.524210442158, 4754.976225118852, 5771.249737534094, 5848.912266315923, 5852.4440178183, 5911.615744976562, 3377.8648854046546, 3707.8146092695797, 3776.773393596393, 3808.1812410331913, 3851.6533511984057, 5097.847064588068, 5575.498205161555, 5589.580781441489, 5613.242212301287, 5850.882982340415, 4664.510226426312, 5419.58828321409, 5435.0, 5506.339239291311, 5558.888822223452, 4495.494009506721, 5120.257562653469, 5159.742012899265, 5429.6199266051, 5454.10007348783, 4528.841490547815, 4560.657950653781, 4809.663323567317, 5093.414257360303, 5410.240777203576, 5018.548330650113, 5335.759890805, 5394.757235924314, 5444.237806706985, 5538.363088244903, 4496.775161241991, 5432.348118225838, 5454.618176727591, 5491.462390530658, 5497.835064948058], "value": 4013.8973631962435, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.5490511547032, 740.5038624121838, 755.882838160085, 760.2321655128319, 783.1111688233855, 15503.379864805409, 15705.450309239179, 16448.917755411207, 16451.177441127955, 16452.17739113044, 14941.0, 15139.167345796004, 15214.391424343014, 15232.086074835517, 15264.16047117408, 680.6426626021329, 685.5441131647474, 742.907136607924, 894.6868595991416, 918.0, 718.242254421585, 884.4781578868466, 903.9005709371963, 915.6612053540166, 917.4311926605513, 936.7611259128912, 946.2619157057471, 948.4830767231875, 949.2121539122512, 953.4755884263662, 949.3829011142743, 950.8098380323963, 951.524237881063, 953.3374304858127, 953.5565961827749, 561.6658088437414, 706.3783870194247, 706.4948561778302, 708.4792677382171, 713.2225873797586, 14443.855561444356, 14862.70274594502, 14870.925645371704, 14925.776113358308, 14942.477013304591, 14356.569302920929, 14546.39989601143, 14835.332410041574, 14903.701925961528, 14920.0, 11770.41147942608, 11853.0, 12079.214851034654, 12082.758344833142, 12394.504219831197, 3914.549826769901, 3920.7843568603716, 3983.820728067244, 4021.7989100545146, 4086.0193553547233, 103452.0, 111018.0, 115100.84899150985, 115807.0, 116855.0, 8792.736217913473, 11094.334339939629, 11180.608678696206, 11194.160437967184, 11567.942160289145, 17111.74332385015, 22055.0, 22641.88679056627, 22754.31737047891, 22756.4310892229, 8299.253067223979, 8299.29455996235, 8408.453450525696, 8420.073791882887, 8544.017437994586, 4563.0, 4574.153781550423, 4656.767161641905, 4700.835470758507, 4779.235322348477, 3525.242072954276, 3616.0, 3631.8002509862213, 3658.5243918290453, 3661.0481274868725, 671.150964157156, 672.579637726421, 675.598019178588, 677.8000489855406, 680.2279412866433, 553.1923391847866, 569.5927411900511, 629.9464545513591, 681.2404169351138, 690.2166041542885, 9200.90799092014, 9894.802103958025, 10015.14871235953, 10076.395416275047, 10104.343217690826, 619.9752009919642, 665.0, 689.5483458334799, 691.6230654293422, 697.8534507753327, 4198.28629133042, 4841.443234028062, 4865.635077369211, 4882.414110306786, 4883.536064073907, 157579.0, 158003.41996580118, 164948.17525912295, 165233.17383413247, 170474.0, 108365.0, 110941.0, 113279.0, 113649.0, 121639.0, 83904.58047709722, 103218.2586306015, 106156.75372985148, 107297.0, 108289.0, 10096.19230461576, 10255.641052563124, 10260.281780275309, 10263.58945642166, 10295.691129266133, 59168.0, 59637.10544341925, 59874.70062649659, 61457.77084458375, 62915.0, 66425.0, 85575.71636425334, 90692.0, 91472.54263728639, 92592.07407926104, 13159.407826648016, 13675.589732308046, 13678.247696376397, 13898.0, 14081.788773168206, 14614.488492902698, 14734.263286835401, 14849.851501484847, 15086.321115549606, 15207.923960380127, 14374.568762937128, 14558.199299038655, 14602.269886505845, 14707.338169782171, 14905.329260183102, 13958.720825583228, 14382.3527941245, 14630.487932922297, 14718.852811472176, 14882.404703812159, 11800.3509806958, 13683.179009259078, 13732.931335343259, 13932.860671393157, 14129.010969232466, 11400.543978240778, 11657.0, 11771.646850594496, 11890.940545297219, 12039.699007524532, 9700.459332343786, 10995.835062474229, 11467.31196128235, 11760.411979400824, 11780.291857680417], "value": 7978.552295959616, "unit": "score"}], "name": "dromaeo_css", "value": 7245.552706302081, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 112.0, 114.0, 112.0, 113.0, 112.0, 112.0, 112.0, 113.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 107.0, 161.0, 105.0, 107.0, 141.0, 135.0, 182.0, 106.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 184.0, 183.0, 184.0, 183.0, 183.0, 262.0, 189.0], "value": 191.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 57.0, 86.0, 58.0, 58.0, 63.0, 62.0, 83.0, 57.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 93.0, 72.0, 72.0, 68.0, 99.0, 70.0, 67.0, 100.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 90.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 85.0, 90.0, 87.0, 84.0, 84.0, 83.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 50.0, 51.0, 51.0, 53.0, 55.0, 53.0, 53.0, 54.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 72.0, 79.0, 75.0, 72.0, 73.0, 72.0, 73.0, 74.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 82.0, 86.0, 82.0, 86.0, 80.0, 85.0, 82.0, 78.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 144.0, 142.0, 143.0, 130.0, 135.0, 133.0, 176.0, 136.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 175.0, 163.0, 164.0, 163.0, 163.0, 168.0, 164.0, 196.0, 161.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 66.0, 66.0, 67.0, 66.0, 65.0, 64.0, 67.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1434.6999999999998, "alertThreshold": 2.0}]} 20:41:51 INFO - Return code: 0 20:41:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:41:51 INFO - # TBPL SUCCESS # 20:41:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:41:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:41:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:41:51 INFO - 2016-08-20 20:41:51.251 screenresolution[1665:10478] starting screenresolution argv=screenresolution get 20:41:51 INFO - 2016-08-20 20:41:51.254 screenresolution[1665:10478] Display 0: 1600x1200x32@60 20:41:51 INFO - 2016-08-20 20:41:51.261 screenresolution[1666:10481] starting screenresolution argv=screenresolution list 20:41:51 INFO - Available Modes on Display 0 20:41:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:41:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:41:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:41:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:41:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:41:51 INFO - Intel Iris: 20:41:51 INFO - Chipset Model: Intel Iris 20:41:51 INFO - Type: GPU 20:41:51 INFO - Bus: Built-In 20:41:51 INFO - VRAM (Dynamic, Max): 1536 MB 20:41:51 INFO - Vendor: Intel (0x8086) 20:41:51 INFO - Device ID: 0x0a2e 20:41:51 INFO - Revision ID: 0x0009 20:41:51 INFO - Displays: 20:41:51 INFO - Display: 20:41:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:41:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:41:51 INFO - Display Serial Number: 1600x1200 60 20:41:51 INFO - Main Display: Yes 20:41:51 INFO - Mirror: Off 20:41:51 INFO - Online: Yes 20:41:51 INFO - Rotation: Supported 20:41:51 INFO - Return code: 0 20:41:51 INFO - Running post-action listener: _resource_record_post_action 20:41:51 INFO - [mozharness: 2016-08-21 03:41:51.504622Z] Finished run-tests step (success) 20:41:51 INFO - Running post-run listener: _resource_record_post_run 20:41:51 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32975872; Write bytes: 706565120; Read time: 315; Write time: 1969 20:41:51 INFO - TinderboxPrint: CPU usage
53.5% 20:41:51 INFO - TinderboxPrint: I/O read bytes / time
32,975,872 / 315 20:41:51 INFO - TinderboxPrint: I/O write bytes / time
706,565,120 / 1,969 20:41:51 INFO - TinderboxPrint: CPU idle
2,257.3 (46.5%) 20:41:51 INFO - TinderboxPrint: CPU system
971.3 (20.0%) 20:41:51 INFO - TinderboxPrint: CPU user
1,621.0 (33.4%) 20:41:51 INFO - TinderboxPrint: Swap in / out
437,231,616 / 0 20:41:51 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391197184; Write bytes: 383666176; Read time: 29060; Write time: 694 20:41:51 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 32029696; Write bytes: 295541760; Read time: 300; Write time: 1224 20:41:51 INFO - Running post-run listener: _upload_blobber_files 20:41:51 INFO - Blob upload gear active. 20:41:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:41:51 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 20:41:51 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'] 20:41:51 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 20:41:52 INFO - (blobuploader) - INFO - Open directory for files ... 20:41:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:41:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:41:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:41:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:41:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:41:53 INFO - (blobuploader) - INFO - Done attempting. 20:41:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:41:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:41:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:41:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:41:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:41:54 INFO - (blobuploader) - INFO - Done attempting. 20:41:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:41:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:41:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:41:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:41:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:41:57 INFO - (blobuploader) - INFO - Done attempting. 20:41:57 INFO - (blobuploader) - INFO - Iteration through files over. 20:41:57 INFO - Return code: 0 20:41:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:41:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:41:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bb7eb3350c92196f66951fea5d9f9a54614af75411f032f13d9bc804754c3ad28fa32b9af175db7d4bd50d6fa3ccd1c7cc1972f825f650a6051b424b229d311", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e505c2f45274cadb8bf6e5c041c14bbc7a39e148742cc9729d3387ffcd7b92e12289def95c3f54003ec275005593490715d3cf211b5952163960154ec97d0a31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/201261928fbdf1d23cb9bd361f67300e274462bbac6fb0698581f4e56aba613bcd5aec84f59f3214c039afa0d33800b7ef0c52dd05072ecd209012729d2d5dd1"} 20:41:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:41:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:41:57 INFO - Contents: 20:41:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bb7eb3350c92196f66951fea5d9f9a54614af75411f032f13d9bc804754c3ad28fa32b9af175db7d4bd50d6fa3ccd1c7cc1972f825f650a6051b424b229d311", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e505c2f45274cadb8bf6e5c041c14bbc7a39e148742cc9729d3387ffcd7b92e12289def95c3f54003ec275005593490715d3cf211b5952163960154ec97d0a31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/201261928fbdf1d23cb9bd361f67300e274462bbac6fb0698581f4e56aba613bcd5aec84f59f3214c039afa0d33800b7ef0c52dd05072ecd209012729d2d5dd1"} 20:41:57 INFO - Running post-run listener: copy_logs_to_upload_dir 20:41:57 INFO - Copying logs to upload dir... 20:41:57 INFO - mkdir: /builds/slave/test/build/upload/logs 20:41:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.955339 ========= master_lag: 0.68 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-20 20:41:57.793060) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-20 20:41:57.796588) ========= 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.ppuP14aCJG/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.YQwmHc25RI/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/0bb7eb3350c92196f66951fea5d9f9a54614af75411f032f13d9bc804754c3ad28fa32b9af175db7d4bd50d6fa3ccd1c7cc1972f825f650a6051b424b229d311", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e505c2f45274cadb8bf6e5c041c14bbc7a39e148742cc9729d3387ffcd7b92e12289def95c3f54003ec275005593490715d3cf211b5952163960154ec97d0a31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/201261928fbdf1d23cb9bd361f67300e274462bbac6fb0698581f4e56aba613bcd5aec84f59f3214c039afa0d33800b7ef0c52dd05072ecd209012729d2d5dd1"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011064 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1469008921/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bb7eb3350c92196f66951fea5d9f9a54614af75411f032f13d9bc804754c3ad28fa32b9af175db7d4bd50d6fa3ccd1c7cc1972f825f650a6051b424b229d311", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e505c2f45274cadb8bf6e5c041c14bbc7a39e148742cc9729d3387ffcd7b92e12289def95c3f54003ec275005593490715d3cf211b5952163960154ec97d0a31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/201261928fbdf1d23cb9bd361f67300e274462bbac6fb0698581f4e56aba613bcd5aec84f59f3214c039afa0d33800b7ef0c52dd05072ecd209012729d2d5dd1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-20 20:41:57.830729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 20:41:57.831087) ========= 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.ppuP14aCJG/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.YQwmHc25RI/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005098 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 20:41:57.880245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-20 20:41:57.880579) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-20 20:41:57.880995) ========= ========= Total master_lag: 0.94 =========